--Task-- name: login-tree-700 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-600'] preceding_task: login-tree-600 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 700} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 03:12:21] login-tree-700 pre : N/A ________________________________________________________________________________ [2024-05-25 03:12:21] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 03:12:21] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-700/stdout.txt Pod log : /results/orders/login-tree-700 2024-05-25 03:12:21,153 INFO 2024-05-25 03:12:21,153 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-25 03:12:21,153 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:21,459 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:21,459 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:21,459 DEBUG --- stdout --- 2024-05-25 03:12:21,459 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:21,459 DEBUG --- stderr --- 2024-05-25 03:12:21,459 DEBUG ________________________________________________________________________________ [2024-05-25 03:12:21] login-tree-700 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 03:12:21,460 INFO 2024-05-25 03:12:21,460 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-25 03:12:21,460 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:21,771 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:21,771 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:21,771 DEBUG --- stdout --- 2024-05-25 03:12:21,771 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:21,771 DEBUG --- stderr --- 2024-05-25 03:12:21,771 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-700 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 03:12:21] login-tree-700 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 03:12:21,772 INFO 2024-05-25 03:12:21,772 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-25 03:12:21,772 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:22,088 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:22,088 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:22,088 DEBUG --- stdout --- 2024-05-25 03:12:22,088 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:22,088 DEBUG --- stderr --- 2024-05-25 03:12:22,088 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/order.json { "name": "login-tree-700", "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-700 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-700", "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=700 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 03:12:22] login-tree-700 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-25 03:12:22,091 INFO 2024-05-25 03:12:22,091 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 03:12:22,091 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 03:12:22,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 03:12:22,346 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:22,346 DEBUG --- stdout --- 2024-05-25 03:12:22,346 DEBUG WAITING 2024-05-25 03:12:22,346 DEBUG --- stderr --- 2024-05-25 03:12:22,346 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-25 03:12:22] login-tree-700 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 03:12:22,347 INFO 2024-05-25 03:12:22,347 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-25 03:12:22,347 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:22,658 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:22,658 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:22,658 DEBUG --- stdout --- 2024-05-25 03:12:22,658 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:22,659 DEBUG --- stderr --- 2024-05-25 03:12:22,659 DEBUG 2024-05-25 03:12:22,659 INFO 2024-05-25 03:12:22,659 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/08_login-tree-700/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 03:12:22,659 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 03:12:24,076 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:24,076 DEBUG --- stdout --- 2024-05-25 03:12:24,076 DEBUG 2024-05-25 03:12:24,076 DEBUG --- stderr --- 2024-05-25 03:12:24,076 DEBUG Order has been successfully forwarded to overseer [2024-05-25 03:12:24] - INFO: Check if Gatling simulation started successfully 2024-05-25 03:12:24,077 INFO 2024-05-25 03:12:24,077 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-25 03:12:24,077 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:24,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:24,380 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:24,380 DEBUG --- stdout --- 2024-05-25 03:12:24,380 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:24,380 DEBUG --- stderr --- 2024-05-25 03:12:24,380 DEBUG 2024-05-25 03:12:24,381 INFO 2024-05-25 03:12:24,381 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-700/stdout.txt | grep "started..." 2024-05-25 03:12:24,381 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 03:12:25,127 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 03:12:30,863 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 03:12:30,864 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:30,864 DEBUG --- stdout --- 2024-05-25 03:12:30,864 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 03:12:30,864 DEBUG --- stderr --- 2024-05-25 03:12:30,864 DEBUG ________________________________________________________________________________ [2024-05-25 03:12:30] login-tree-700 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 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 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-05-25 04:14:41] login-tree-700 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 04:14:41,186 INFO 2024-05-25 04:14:41,187 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-25 04:14:41,187 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:41,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:41,516 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:41,516 DEBUG --- stdout --- 2024-05-25 04:14:41,517 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:41,517 DEBUG --- stderr --- 2024-05-25 04:14:41,517 DEBUG 2024-05-25 04:14:41,517 INFO 2024-05-25 04:14:41,517 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-25 04:14:41,517 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:14:42,315 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:42,316 DEBUG --- stdout --- 2024-05-25 04:14:42,316 DEBUG 2024-05-25 04:14:42,316 DEBUG --- stderr --- 2024-05-25 04:14:42,316 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 04:14:42] login-tree-700 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 04:14:42] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/order.json 2024-05-25 04:14:42,317 INFO 2024-05-25 04:14:42,317 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-25 04:14:42,317 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:42,616 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:42,616 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:42,616 DEBUG --- stdout --- 2024-05-25 04:14:42,616 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:42,616 DEBUG --- stderr --- 2024-05-25 04:14:42,616 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 04:14:42,617 INFO 2024-05-25 04:14:42,617 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-25 04:14:42,617 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:42,919 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:42,920 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:42,920 DEBUG --- stdout --- 2024-05-25 04:14:42,920 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:42,921 DEBUG --- stderr --- 2024-05-25 04:14:42,921 DEBUG 2024-05-25 04:14:42,921 INFO 2024-05-25 04:14:42,921 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-700/rc.txt" ]; then echo "/results/orders/login-tree-700/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-700/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/rc.txt file found" 2024-05-25 04:14:42,921 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:14:43,721 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 04:14:43,721 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:43,721 DEBUG --- stdout --- 2024-05-25 04:14:43,721 DEBUG /results/orders/login-tree-700/rc.txt file found 2024-05-25 04:14:43,721 DEBUG --- stderr --- 2024-05-25 04:14:43,721 DEBUG 2024-05-25 04:14:43,721 INFO 2024-05-25 04:14:43,721 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-25 04:14:43,721 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:44,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:44,024 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:44,024 DEBUG --- stdout --- 2024-05-25 04:14:44,024 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:44,024 DEBUG --- stderr --- 2024-05-25 04:14:44,024 DEBUG 2024-05-25 04:14:44,024 INFO 2024-05-25 04:14:44,024 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-700/rc.txt 2024-05-25 04:14:44,885 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 04:14:44,886 DEBUG --- stdout --- 2024-05-25 04:14:44,886 DEBUG 4 /results/orders/login-tree-700/rc.txt 2024-05-25 04:14:44,886 DEBUG --- stderr --- 2024-05-25 04:14:44,886 DEBUG 2024-05-25 04:14:44,886 INFO - Download file (size 4k) 2024-05-25 04:14:44,886 INFO 2024-05-25 04:14:44,886 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-25 04:14:44,886 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:45,194 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:45,194 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:45,194 DEBUG --- stdout --- 2024-05-25 04:14:45,194 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:45,195 DEBUG --- stderr --- 2024-05-25 04:14:45,195 DEBUG source : /results/orders/login-tree-700/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/rc.txt 2024-05-25 04:14:45,195 INFO 2024-05-25 04:14:45,195 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-25 04:14:45,195 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:45,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:45,504 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:45,504 DEBUG --- stdout --- 2024-05-25 04:14:45,504 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:45,504 DEBUG --- stderr --- 2024-05-25 04:14:45,504 DEBUG 2024-05-25 04:14:45,505 INFO 2024-05-25 04:14:45,505 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-700/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/rc.txt 2024-05-25 04:14:45,505 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 04:14:46,234 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:46,235 DEBUG --- stdout --- 2024-05-25 04:14:46,235 DEBUG tar: Removing leading `/' from member names 2024-05-25 04:14:46,235 DEBUG --- stderr --- 2024-05-25 04:14:46,235 DEBUG 2024-05-25 04:14:46,235 INFO 2024-05-25 04:14:46,236 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-25 04:14:46,236 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:46,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:46,537 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:46,537 DEBUG --- stdout --- 2024-05-25 04:14:46,537 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:46,537 DEBUG --- stderr --- 2024-05-25 04:14:46,537 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 04:14:46,538 INFO 2024-05-25 04:14:46,538 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-25 04:14:46,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:46,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:46,844 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:46,844 DEBUG --- stdout --- 2024-05-25 04:14:46,844 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:46,845 DEBUG --- stderr --- 2024-05-25 04:14:46,845 DEBUG 2024-05-25 04:14:46,845 INFO 2024-05-25 04:14:46,845 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-700/stderr.txt" ]; then echo "/results/orders/login-tree-700/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-700/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/stderr.txt file found" 2024-05-25 04:14:46,845 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:14:47,654 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 04:14:47,654 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:47,654 DEBUG --- stdout --- 2024-05-25 04:14:47,654 DEBUG /results/orders/login-tree-700/stderr.txt file found 2024-05-25 04:14:47,654 DEBUG --- stderr --- 2024-05-25 04:14:47,654 DEBUG 2024-05-25 04:14:47,655 INFO 2024-05-25 04:14:47,655 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-25 04:14:47,655 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:47,987 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:47,988 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:47,988 DEBUG --- stdout --- 2024-05-25 04:14:47,989 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:47,989 DEBUG --- stderr --- 2024-05-25 04:14:47,989 DEBUG 2024-05-25 04:14:47,989 INFO 2024-05-25 04:14:47,989 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-700/stderr.txt 2024-05-25 04:14:48,792 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 04:14:48,793 DEBUG --- stdout --- 2024-05-25 04:14:48,793 DEBUG 0 /results/orders/login-tree-700/stderr.txt 2024-05-25 04:14:48,793 DEBUG --- stderr --- 2024-05-25 04:14:48,793 DEBUG 2024-05-25 04:14:48,793 INFO - Download file (size 0k) 2024-05-25 04:14:48,794 INFO 2024-05-25 04:14:48,794 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-25 04:14:48,794 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:49,095 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:49,095 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:49,095 DEBUG --- stdout --- 2024-05-25 04:14:49,095 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:49,095 DEBUG --- stderr --- 2024-05-25 04:14:49,095 DEBUG source : /results/orders/login-tree-700/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stderr.txt 2024-05-25 04:14:49,096 INFO 2024-05-25 04:14:49,096 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-25 04:14:49,096 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:49,393 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:49,393 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:49,393 DEBUG --- stdout --- 2024-05-25 04:14:49,393 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:49,394 DEBUG --- stderr --- 2024-05-25 04:14:49,394 DEBUG 2024-05-25 04:14:49,394 INFO 2024-05-25 04:14:49,394 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-700/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stderr.txt 2024-05-25 04:14:49,394 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 04:14:50,116 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:50,116 DEBUG --- stdout --- 2024-05-25 04:14:50,116 DEBUG tar: Removing leading `/' from member names 2024-05-25 04:14:50,116 DEBUG --- stderr --- 2024-05-25 04:14:50,116 DEBUG 2024-05-25 04:14:50,117 INFO 2024-05-25 04:14:50,117 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-25 04:14:50,117 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:50,423 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:50,424 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:50,424 DEBUG --- stdout --- 2024-05-25 04:14:50,424 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:50,424 DEBUG --- stderr --- 2024-05-25 04:14:50,424 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 04:14:50,424 INFO 2024-05-25 04:14:50,424 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-25 04:14:50,424 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:50,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:50,730 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:50,730 DEBUG --- stdout --- 2024-05-25 04:14:50,730 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:50,730 DEBUG --- stderr --- 2024-05-25 04:14:50,730 DEBUG 2024-05-25 04:14:50,731 INFO 2024-05-25 04:14:50,731 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-700/stdout.txt" ]; then echo "/results/orders/login-tree-700/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-700/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/stdout.txt file found" 2024-05-25 04:14:50,731 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:14:51,590 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 04:14:51,590 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:51,591 DEBUG --- stdout --- 2024-05-25 04:14:51,591 DEBUG /results/orders/login-tree-700/stdout.txt file found 2024-05-25 04:14:51,591 DEBUG --- stderr --- 2024-05-25 04:14:51,591 DEBUG 2024-05-25 04:14:51,591 INFO 2024-05-25 04:14:51,591 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-25 04:14:51,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:51,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:51,900 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:51,900 DEBUG --- stdout --- 2024-05-25 04:14:51,900 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:51,900 DEBUG --- stderr --- 2024-05-25 04:14:51,900 DEBUG 2024-05-25 04:14:51,901 INFO 2024-05-25 04:14:51,901 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-700/stdout.txt 2024-05-25 04:14:52,706 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 04:14:52,706 DEBUG --- stdout --- 2024-05-25 04:14:52,706 DEBUG 680 /results/orders/login-tree-700/stdout.txt 2024-05-25 04:14:52,706 DEBUG --- stderr --- 2024-05-25 04:14:52,706 DEBUG 2024-05-25 04:14:52,706 INFO - Download file (size 680k) 2024-05-25 04:14:52,707 INFO 2024-05-25 04:14:52,707 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-25 04:14:52,707 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:53,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:53,031 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:53,031 DEBUG --- stdout --- 2024-05-25 04:14:53,031 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:53,031 DEBUG --- stderr --- 2024-05-25 04:14:53,031 DEBUG source : /results/orders/login-tree-700/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stdout.txt 2024-05-25 04:14:53,031 INFO 2024-05-25 04:14:53,031 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-25 04:14:53,031 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:53,343 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:53,343 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:53,343 DEBUG --- stdout --- 2024-05-25 04:14:53,343 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:53,343 DEBUG --- stderr --- 2024-05-25 04:14:53,343 DEBUG 2024-05-25 04:14:53,344 INFO 2024-05-25 04:14:53,344 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-700/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stdout.txt 2024-05-25 04:14:53,344 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 04:14:54,395 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:54,395 DEBUG --- stdout --- 2024-05-25 04:14:54,396 DEBUG tar: Removing leading `/' from member names 2024-05-25 04:14:54,396 DEBUG --- stderr --- 2024-05-25 04:14:54,396 DEBUG ________________________________________________________________________________ [2024-05-25 04:14:54] login-tree-700 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 04:14:54,396 INFO 2024-05-25 04:14:54,396 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-25 04:14:54,396 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:54,715 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:54,715 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:54,715 DEBUG --- stdout --- 2024-05-25 04:14:54,715 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:54,715 DEBUG --- stderr --- 2024-05-25 04:14:54,715 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 04:14:54,715 INFO 2024-05-25 04:14:54,715 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-25 04:14:54,716 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:55,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:55,029 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:55,029 DEBUG --- stdout --- 2024-05-25 04:14:55,029 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:55,029 DEBUG --- stderr --- 2024-05-25 04:14:55,029 DEBUG 2024-05-25 04:14:55,029 INFO 2024-05-25 04:14:55,029 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-700/cmd.txt" ]; then echo "/results/orders/login-tree-700/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-700/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/cmd.txt file found" 2024-05-25 04:14:55,029 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:14:55,842 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 04:14:55,842 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:55,842 DEBUG --- stdout --- 2024-05-25 04:14:55,842 DEBUG /results/orders/login-tree-700/cmd.txt file found 2024-05-25 04:14:55,842 DEBUG --- stderr --- 2024-05-25 04:14:55,842 DEBUG 2024-05-25 04:14:55,842 INFO 2024-05-25 04:14:55,843 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-25 04:14:55,843 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:56,152 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:56,152 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:56,152 DEBUG --- stdout --- 2024-05-25 04:14:56,152 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:56,152 DEBUG --- stderr --- 2024-05-25 04:14:56,152 DEBUG 2024-05-25 04:14:56,153 INFO 2024-05-25 04:14:56,153 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-700/cmd.txt 2024-05-25 04:14:56,962 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 04:14:56,963 DEBUG --- stdout --- 2024-05-25 04:14:56,963 DEBUG 4 /results/orders/login-tree-700/cmd.txt 2024-05-25 04:14:56,963 DEBUG --- stderr --- 2024-05-25 04:14:56,963 DEBUG 2024-05-25 04:14:56,963 INFO - Download file (size 4k) 2024-05-25 04:14:56,963 INFO 2024-05-25 04:14:56,963 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-25 04:14:56,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:57,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:57,278 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:57,278 DEBUG --- stdout --- 2024-05-25 04:14:57,278 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:57,278 DEBUG --- stderr --- 2024-05-25 04:14:57,278 DEBUG source : /results/orders/login-tree-700/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/cmd.txt 2024-05-25 04:14:57,278 INFO 2024-05-25 04:14:57,278 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-25 04:14:57,278 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:14:57,585 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:14:57,585 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:57,585 DEBUG --- stdout --- 2024-05-25 04:14:57,585 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:14:57,585 DEBUG --- stderr --- 2024-05-25 04:14:57,585 DEBUG 2024-05-25 04:14:57,586 INFO 2024-05-25 04:14:57,586 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-700/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/cmd.txt 2024-05-25 04:14:57,586 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 04:14:58,388 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:14:58,388 DEBUG --- stdout --- 2024-05-25 04:14:58,388 DEBUG tar: Removing leading `/' from member names 2024-05-25 04:14:58,388 DEBUG --- stderr --- 2024-05-25 04:14:58,388 DEBUG 2024-05-25 04:14:58,389 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/cmd.txt is small (1 lines), printing all lines: 2024-05-25 04:14:58,389 DEBUG ----- output ----- 2024-05-25 04:14:58,389 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-700 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 04:14:58,389 INFO ________________________________________________________________________________ [2024-05-25 04:14:58] login-tree-700 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 04:14:58,389 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/rc.txt is small (1 lines), printing all lines: 2024-05-25 04:14:58,389 DEBUG ----- output ----- 2024-05-25 04:14:58,389 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 04:14:58,389 INFO ________________________________________________________________________________ [2024-05-25 04:14:58] login-tree-700 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 04:14:58,391 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/08_login-tree-700/stdout.txt is 10912 lines): 2024-05-25 04:14:58,391 DEBUG ----- output ----- 2024-05-25 04:14:58,392 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 04:14:58,392 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 04:14:58,392 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO ================================================================================ 2024-05-25 04:14:58,392 INFO 2024-05-25 04:12:33 5s elapsed 2024-05-25 04:14:58,392 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,392 INFO > Global (OK=581 KO=0 ) 2024-05-25 04:14:58,392 INFO > restLoginInitiate (OK=292 KO=0 ) 2024-05-25 04:14:58,392 INFO > restLoginUsernamePasswordCallback (OK=289 KO=0 ) 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,392 INFO [- ] 0% 2024-05-25 04:14:58,392 INFO waiting: 2530567 / active: 4 / done: 289 2024-05-25 04:14:58,392 INFO ================================================================================ 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO ================================================================================ 2024-05-25 04:14:58,392 INFO 2024-05-25 04:12:38 10s elapsed 2024-05-25 04:14:58,392 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,392 INFO > Global (OK=2328 KO=0 ) 2024-05-25 04:14:58,392 INFO > restLoginInitiate (OK=1167 KO=0 ) 2024-05-25 04:14:58,392 INFO > restLoginUsernamePasswordCallback (OK=1161 KO=0 ) 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,392 INFO [- ] 0% 2024-05-25 04:14:58,392 INFO waiting: 2529690 / active: 9 / done: 1161 2024-05-25 04:14:58,392 INFO ================================================================================ 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,392 INFO ================================================================================ 2024-05-25 04:14:58,392 INFO 2024-05-25 04:12:43 15s elapsed 2024-05-25 04:14:58,392 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,392 INFO > Global (OK=5244 KO=0 ) 2024-05-25 04:14:58,392 INFO > restLoginInitiate (OK=2627 KO=0 ) 2024-05-25 04:14:58,392 INFO > restLoginUsernamePasswordCallback (OK=2617 KO=0 ) 2024-05-25 04:14:58,392 INFO 2024-05-25 04:14:58,393 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,393 INFO [- ] 0% 2024-05-25 04:14:58,393 INFO waiting: 2528230 / active: 13 / done: 2617 2024-05-25 04:14:58,393 INFO ================================================================================ 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO ================================================================================ 2024-05-25 04:14:58,393 INFO 2024-05-25 04:12:48 20s elapsed 2024-05-25 04:14:58,393 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,393 INFO > Global (OK=9323 KO=0 ) 2024-05-25 04:14:58,393 INFO > restLoginInitiate (OK=4667 KO=0 ) 2024-05-25 04:14:58,393 INFO > restLoginUsernamePasswordCallback (OK=4656 KO=0 ) 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,393 INFO [- ] 0% 2024-05-25 04:14:58,393 INFO waiting: 2526188 / active: 16 / done: 4656 2024-05-25 04:14:58,393 INFO ================================================================================ 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO ================================================================================ 2024-05-25 04:14:58,393 INFO 2024-05-25 04:12:53 25s elapsed 2024-05-25 04:14:58,393 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,393 INFO > Global (OK=14559 KO=0 ) 2024-05-25 04:14:58,393 INFO > restLoginInitiate (OK=7293 KO=0 ) 2024-05-25 04:14:58,393 INFO > restLoginUsernamePasswordCallback (OK=7266 KO=0 ) 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,393 INFO [- ] 0% 2024-05-25 04:14:58,393 INFO waiting: 2523561 / active: 33 / done: 7266 2024-05-25 04:14:58,393 INFO ================================================================================ 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO ================================================================================ 2024-05-25 04:14:58,393 INFO 2024-05-25 04:12:58 30s elapsed 2024-05-25 04:14:58,393 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,393 INFO > Global (OK=20971 KO=0 ) 2024-05-25 04:14:58,393 INFO > restLoginInitiate (OK=10492 KO=0 ) 2024-05-25 04:14:58,393 INFO > restLoginUsernamePasswordCallback (OK=10479 KO=0 ) 2024-05-25 04:14:58,393 INFO 2024-05-25 04:14:58,393 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,393 INFO [- ] 0% 2024-05-25 04:14:58,394 INFO waiting: 2520352 / active: 29 / done: 10479 2024-05-25 04:14:58,394 INFO ================================================================================ 2024-05-25 04:14:58,394 INFO 2024-05-25 04:14:58,394 INFO 2024-05-25 04:14:58,394 INFO ================================================================================ 2024-05-25 04:14:58,394 INFO 2024-05-25 04:13:03 35s elapsed 2024-05-25 04:14:58,394 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,394 INFO > Global (OK=27888 KO=0 ) 2024-05-25 04:14:58,394 INFO > restLoginInitiate (OK=13960 KO=0 ) 2024-05-25 04:14:58,394 INFO > restLoginUsernamePasswordCallback (OK=13928 KO=0 ) 2024-05-25 04:14:58,394 INFO 2024-05-25 04:14:58,394 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,394 INFO [- ] 0% 2024-05-25 04:14:58,394 INFO waiting: 2516895 / active: 37 / done: 13928 2024-05-25 04:14:58,394 INFO ================================================================================ 2024-05-25 04:14:58,394 INFO 2024-05-25 04:14:58,394 INFO 2024-05-25 04:14:58,394 INFO ================================================================================ 2024-05-25 04:14:58,394 INFO 2024-05-25 04:13:08 40s elapsed 2024-05-25 04:14:58,394 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,394 INFO > Global (OK=34988 KO=0 ) 2024-05-25 04:14:58,394 INFO > restLoginInitiate (OK=17506 KO=0 ) 2024-05-25 04:14:58,394 INFO [...] 2024-05-25 04:14:58,397 INFO 2024-05-25 04:14:58,397 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,397 INFO [#########################################################################-] 99% 2024-05-25 04:14:58,397 INFO waiting: 14039 / active: 45 / done: 2516776 2024-05-25 04:14:58,397 INFO ================================================================================ 2024-05-25 04:14:58,397 INFO 2024-05-25 04:14:58,397 INFO 2024-05-25 04:14:58,397 INFO ================================================================================ 2024-05-25 04:14:58,397 INFO 2024-05-25 05:12:43 3615s elapsed 2024-05-25 04:14:58,397 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,397 INFO > Global (OK=5040545 KO=3 ) 2024-05-25 04:14:58,397 INFO > restLoginInitiate (OK=2520396 KO=2 ) 2024-05-25 04:14:58,397 INFO > restLoginUsernamePasswordCallback (OK=2520149 KO=1 ) 2024-05-25 04:14:58,397 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 04:14:58,397 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 04:14:58,397 INFO 2024-05-25 04:14:58,397 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,397 INFO [#########################################################################-] 99% 2024-05-25 04:14:58,398 INFO waiting: 10438 / active: 270 / done: 2520152 2024-05-25 04:14:58,398 INFO ================================================================================ 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO ================================================================================ 2024-05-25 04:14:58,398 INFO 2024-05-25 05:12:48 3620s elapsed 2024-05-25 04:14:58,398 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,398 INFO > Global (OK=5047854 KO=3 ) 2024-05-25 04:14:58,398 INFO > restLoginInitiate (OK=2523938 KO=2 ) 2024-05-25 04:14:58,398 INFO > restLoginUsernamePasswordCallback (OK=2523916 KO=1 ) 2024-05-25 04:14:58,398 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 04:14:58,398 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,398 INFO [#########################################################################-] 99% 2024-05-25 04:14:58,398 INFO waiting: 6911 / active: 30 / done: 2523919 2024-05-25 04:14:58,398 INFO ================================================================================ 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO ================================================================================ 2024-05-25 04:14:58,398 INFO 2024-05-25 05:12:53 3625s elapsed 2024-05-25 04:14:58,398 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,398 INFO > Global (OK=5054866 KO=3 ) 2024-05-25 04:14:58,398 INFO > restLoginInitiate (OK=2527446 KO=2 ) 2024-05-25 04:14:58,398 INFO > restLoginUsernamePasswordCallback (OK=2527420 KO=1 ) 2024-05-25 04:14:58,398 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 04:14:58,398 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,398 INFO [#########################################################################-] 99% 2024-05-25 04:14:58,398 INFO waiting: 3404 / active: 33 / done: 2527423 2024-05-25 04:14:58,398 INFO ================================================================================ 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO 2024-05-25 04:14:58,398 INFO ================================================================================ 2024-05-25 04:14:58,398 INFO 2024-05-25 05:12:58 3630s elapsed 2024-05-25 04:14:58,398 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,398 INFO > Global (OK=5061667 KO=3 ) 2024-05-25 04:14:58,399 INFO > restLoginInitiate (OK=2530853 KO=2 ) 2024-05-25 04:14:58,399 INFO > restLoginUsernamePasswordCallback (OK=2530814 KO=1 ) 2024-05-25 04:14:58,399 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 04:14:58,399 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,399 INFO [#########################################################################-] 99% 2024-05-25 04:14:58,399 INFO waiting: 0 / active: 43 / done: 2530817 2024-05-25 04:14:58,399 INFO ================================================================================ 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO ================================================================================ 2024-05-25 04:14:58,399 INFO 2024-05-25 05:12:58 3630s elapsed 2024-05-25 04:14:58,399 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 04:14:58,399 INFO > Global (OK=5061715 KO=3 ) 2024-05-25 04:14:58,399 INFO > restLoginInitiate (OK=2530858 KO=2 ) 2024-05-25 04:14:58,399 INFO > restLoginUsernamePasswordCallback (OK=2530857 KO=1 ) 2024-05-25 04:14:58,399 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 04:14:58,399 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 04:14:58,399 INFO [##########################################################################]100% 2024-05-25 04:14:58,399 INFO waiting: 0 / active: 0 / done: 2530860 2024-05-25 04:14:58,399 INFO ================================================================================ 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO ---------------------- 2024-05-25 04:14:58,399 INFO [25/May/2024 05:12:58] Create tar file login-tree-700.tar.gz 2024-05-25 04:14:58,399 INFO ---------------------- 2024-05-25 04:14:58,399 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 04:14:58,399 INFO RESULT_FOLDER=login-tree-700 2024-05-25 04:14:58,399 INFO SIMULATION_PATH=login-tree-700/loadtest-20240525031227808 2024-05-25 04:14:58,399 INFO PWD=/results/gatling/login-tree-700/loadtest-20240525031227808 2024-05-25 04:14:58,399 INFO [cmd] tar -czvf /results/gatling//login-tree-700.tar.gz --exclude=simulation.log * 2024-05-25 04:14:58,399 INFO /results/gatling 2024-05-25 04:14:58,399 INFO 2024-05-25 04:14:58,399 INFO ---------------------- 2024-05-25 04:14:58,399 INFO [25/May/2024 05:12:58] Update /results/gatling//latest 2024-05-25 04:14:58,399 INFO ---------------------- 2024-05-25 04:14:58,400 INFO 2024-05-25 04:14:58,400 INFO ---------------------- 2024-05-25 04:14:58,400 INFO [25/May/2024 05:12:58] Update /results/gatling//latest.tar.gz 2024-05-25 04:14:58,400 INFO ---------------------- 2024-05-25 04:14:58,400 INFO ________________________________________________________________________________ [2024-05-25 04:14:58] login-tree-700 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 04:14:58,400 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stderr.txt is small (0 lines), printing all lines: 2024-05-25 04:14:58,400 DEBUG ----- output ----- 2024-05-25 04:14:58,400 DEBUG 2024-05-25 04:14:58,400 INFO ________________________________________________________________________________ [2024-05-25 04:14:58] login-tree-700 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-25 04:14:58] login-tree-700 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 04:14:58] login-tree-700 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped