--Task-- name: login-tree-300 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-200'] preceding_task: login-tree-200 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 300} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-24 23:01:48] login-tree-300 pre : N/A ________________________________________________________________________________ [2024-05-24 23:01:48] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-24 23:01:48] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-300/stdout.txt Pod log : /results/orders/login-tree-300 2024-05-24 23:01:48,772 INFO 2024-05-24 23:01:48,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-24 23:01:48,772 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:49,084 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:49,084 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:49,084 DEBUG --- stdout --- 2024-05-24 23:01:49,084 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:49,084 DEBUG --- stderr --- 2024-05-24 23:01:49,084 DEBUG ________________________________________________________________________________ [2024-05-24 23:01:49] login-tree-300 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-24 23:01:49,085 INFO 2024-05-24 23:01:49,085 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:49,085 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:49,394 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:49,394 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:49,394 DEBUG --- stdout --- 2024-05-24 23:01:49,394 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:49,394 DEBUG --- stderr --- 2024-05-24 23:01:49,394 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-300 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-24 23:01:49] login-tree-300 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-24 23:01:49,395 INFO 2024-05-24 23:01:49,395 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:49,395 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:49,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:49,704 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:49,704 DEBUG --- stdout --- 2024-05-24 23:01:49,704 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:49,704 DEBUG --- stderr --- 2024-05-24 23:01:49,705 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/order.json { "name": "login-tree-300", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-3580\" --results-folder /results/gatling/login-tree-300 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-300", "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=300 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-24 23:01:49] login-tree-300 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-05-24 23:01:49,707 INFO 2024-05-24 23:01:49,707 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-24 23:01:49,707 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 23:01:49,955 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 23:01:49,955 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:49,955 DEBUG --- stdout --- 2024-05-24 23:01:49,955 DEBUG WAITING 2024-05-24 23:01:49,955 DEBUG --- stderr --- 2024-05-24 23:01:49,956 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 23:01:49] login-tree-300 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-24 23:01:49,956 INFO 2024-05-24 23:01:49,956 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:49,956 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:50,276 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:50,276 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:50,276 DEBUG --- stdout --- 2024-05-24 23:01:50,276 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:50,276 DEBUG --- stderr --- 2024-05-24 23:01:50,276 DEBUG 2024-05-24 23:01:50,277 INFO 2024-05-24 23:01:50,277 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-24 23:01:50,277 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 23:01:51,634 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:51,635 DEBUG --- stdout --- 2024-05-24 23:01:51,635 DEBUG 2024-05-24 23:01:51,635 DEBUG --- stderr --- 2024-05-24 23:01:51,635 DEBUG Order has been successfully forwarded to overseer [2024-05-24 23:01:51] - INFO: Check if Gatling simulation started successfully 2024-05-24 23:01:51,635 INFO 2024-05-24 23:01:51,635 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 23:01:51,635 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 23:01:51,943 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 23:01:51,943 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:51,943 DEBUG --- stdout --- 2024-05-24 23:01:51,943 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 23:01:51,943 DEBUG --- stderr --- 2024-05-24 23:01:51,943 DEBUG 2024-05-24 23:01:51,944 INFO 2024-05-24 23:01:51,944 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-300/stdout.txt | grep "started..." 2024-05-24 23:01:51,944 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 23:01:52,695 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-24 23:01:58,458 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-24 23:01:58,458 INFO [loop_until]: OK (rc = 0) 2024-05-24 23:01:58,459 DEBUG --- stdout --- 2024-05-24 23:01:58,459 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 23:01:58,459 DEBUG --- stderr --- 2024-05-24 23:01:58,459 DEBUG ________________________________________________________________________________ [2024-05-24 23:01:58] login-tree-300 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 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 09s, 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 00:04:08] login-tree-300 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 00:04:08,749 INFO 2024-05-25 00:04:08,750 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 00:04:08,750 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:09,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:09,093 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:09,093 DEBUG --- stdout --- 2024-05-25 00:04:09,093 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:09,093 DEBUG --- stderr --- 2024-05-25 00:04:09,093 DEBUG 2024-05-25 00:04:09,093 INFO 2024-05-25 00:04:09,094 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 00:04:09,094 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 00:04:09,885 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:09,885 DEBUG --- stdout --- 2024-05-25 00:04:09,885 DEBUG 2024-05-25 00:04:09,885 DEBUG --- stderr --- 2024-05-25 00:04:09,885 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 00:04:09] login-tree-300 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 00:04:09] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/order.json 2024-05-25 00:04:09,887 INFO 2024-05-25 00:04:09,887 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 00:04:09,887 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:10,203 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:10,203 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:10,203 DEBUG --- stdout --- 2024-05-25 00:04:10,203 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:10,203 DEBUG --- stderr --- 2024-05-25 00:04:10,203 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 00:04:10,204 INFO 2024-05-25 00:04:10,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-25 00:04:10,204 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:10,513 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:10,513 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:10,513 DEBUG --- stdout --- 2024-05-25 00:04:10,513 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:10,513 DEBUG --- stderr --- 2024-05-25 00:04:10,513 DEBUG 2024-05-25 00:04:10,514 INFO 2024-05-25 00:04:10,514 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-300/rc.txt" ]; then echo "/results/orders/login-tree-300/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-300/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/rc.txt file found" 2024-05-25 00:04:10,514 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 00:04:11,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 00:04:11,318 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:11,318 DEBUG --- stdout --- 2024-05-25 00:04:11,318 DEBUG /results/orders/login-tree-300/rc.txt file found 2024-05-25 00:04:11,319 DEBUG --- stderr --- 2024-05-25 00:04:11,319 DEBUG 2024-05-25 00:04:11,319 INFO 2024-05-25 00:04:11,319 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 00:04:11,319 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:11,625 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:11,625 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:11,625 DEBUG --- stdout --- 2024-05-25 00:04:11,625 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:11,625 DEBUG --- stderr --- 2024-05-25 00:04:11,625 DEBUG 2024-05-25 00:04:11,625 INFO 2024-05-25 00:04:11,626 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-300/rc.txt 2024-05-25 00:04:12,435 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 00:04:12,435 DEBUG --- stdout --- 2024-05-25 00:04:12,436 DEBUG 4 /results/orders/login-tree-300/rc.txt 2024-05-25 00:04:12,436 DEBUG --- stderr --- 2024-05-25 00:04:12,436 DEBUG 2024-05-25 00:04:12,436 INFO - Download file (size 4k) 2024-05-25 00:04:12,436 INFO 2024-05-25 00:04:12,436 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 00:04:12,436 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:12,745 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:12,745 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:12,745 DEBUG --- stdout --- 2024-05-25 00:04:12,745 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:12,745 DEBUG --- stderr --- 2024-05-25 00:04:12,746 DEBUG source : /results/orders/login-tree-300/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/rc.txt 2024-05-25 00:04:12,746 INFO 2024-05-25 00:04:12,746 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 00:04:12,746 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:13,057 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:13,058 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:13,058 DEBUG --- stdout --- 2024-05-25 00:04:13,058 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:13,058 DEBUG --- stderr --- 2024-05-25 00:04:13,058 DEBUG 2024-05-25 00:04:13,058 INFO 2024-05-25 00:04:13,058 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-300/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/rc.txt 2024-05-25 00:04:13,058 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 00:04:13,794 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:13,794 DEBUG --- stdout --- 2024-05-25 00:04:13,795 DEBUG tar: Removing leading `/' from member names 2024-05-25 00:04:13,795 DEBUG --- stderr --- 2024-05-25 00:04:13,795 DEBUG 2024-05-25 00:04:13,795 INFO 2024-05-25 00:04:13,795 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 00:04:13,795 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:14,106 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:14,106 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:14,106 DEBUG --- stdout --- 2024-05-25 00:04:14,106 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:14,107 DEBUG --- stderr --- 2024-05-25 00:04:14,107 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 00:04:14,107 INFO 2024-05-25 00:04:14,107 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 00:04:14,107 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:14,405 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:14,405 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:14,405 DEBUG --- stdout --- 2024-05-25 00:04:14,406 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:14,406 DEBUG --- stderr --- 2024-05-25 00:04:14,406 DEBUG 2024-05-25 00:04:14,406 INFO 2024-05-25 00:04:14,406 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-300/stderr.txt" ]; then echo "/results/orders/login-tree-300/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-300/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/stderr.txt file found" 2024-05-25 00:04:14,406 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 00:04:15,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 00:04:15,212 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:15,212 DEBUG --- stdout --- 2024-05-25 00:04:15,212 DEBUG /results/orders/login-tree-300/stderr.txt file found 2024-05-25 00:04:15,212 DEBUG --- stderr --- 2024-05-25 00:04:15,212 DEBUG 2024-05-25 00:04:15,213 INFO 2024-05-25 00:04:15,213 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 00:04:15,213 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:15,524 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:15,524 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:15,524 DEBUG --- stdout --- 2024-05-25 00:04:15,524 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:15,524 DEBUG --- stderr --- 2024-05-25 00:04:15,524 DEBUG 2024-05-25 00:04:15,524 INFO 2024-05-25 00:04:15,525 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-300/stderr.txt 2024-05-25 00:04:16,331 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 00:04:16,331 DEBUG --- stdout --- 2024-05-25 00:04:16,331 DEBUG 0 /results/orders/login-tree-300/stderr.txt 2024-05-25 00:04:16,331 DEBUG --- stderr --- 2024-05-25 00:04:16,331 DEBUG 2024-05-25 00:04:16,331 INFO - Download file (size 0k) 2024-05-25 00:04:16,331 INFO 2024-05-25 00:04:16,331 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 00:04:16,331 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:16,646 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:16,646 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:16,646 DEBUG --- stdout --- 2024-05-25 00:04:16,646 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:16,646 DEBUG --- stderr --- 2024-05-25 00:04:16,646 DEBUG source : /results/orders/login-tree-300/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stderr.txt 2024-05-25 00:04:16,646 INFO 2024-05-25 00:04:16,646 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 00:04:16,647 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:16,960 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:16,960 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:16,960 DEBUG --- stdout --- 2024-05-25 00:04:16,960 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:16,960 DEBUG --- stderr --- 2024-05-25 00:04:16,960 DEBUG 2024-05-25 00:04:16,961 INFO 2024-05-25 00:04:16,961 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-300/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stderr.txt 2024-05-25 00:04:16,961 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 00:04:17,746 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:17,746 DEBUG --- stdout --- 2024-05-25 00:04:17,747 DEBUG tar: Removing leading `/' from member names 2024-05-25 00:04:17,747 DEBUG --- stderr --- 2024-05-25 00:04:17,747 DEBUG 2024-05-25 00:04:17,747 INFO 2024-05-25 00:04:17,747 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 00:04:17,747 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:18,056 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:18,057 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:18,057 DEBUG --- stdout --- 2024-05-25 00:04:18,057 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:18,057 DEBUG --- stderr --- 2024-05-25 00:04:18,057 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 00:04:18,057 INFO 2024-05-25 00:04:18,057 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 00:04:18,057 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:18,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:18,379 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:18,379 DEBUG --- stdout --- 2024-05-25 00:04:18,379 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:18,379 DEBUG --- stderr --- 2024-05-25 00:04:18,379 DEBUG 2024-05-25 00:04:18,380 INFO 2024-05-25 00:04:18,380 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-300/stdout.txt" ]; then echo "/results/orders/login-tree-300/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-300/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/stdout.txt file found" 2024-05-25 00:04:18,380 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 00:04:19,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 00:04:19,187 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:19,187 DEBUG --- stdout --- 2024-05-25 00:04:19,187 DEBUG /results/orders/login-tree-300/stdout.txt file found 2024-05-25 00:04:19,187 DEBUG --- stderr --- 2024-05-25 00:04:19,187 DEBUG 2024-05-25 00:04:19,188 INFO 2024-05-25 00:04:19,188 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 00:04:19,188 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:19,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:19,504 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:19,504 DEBUG --- stdout --- 2024-05-25 00:04:19,505 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:19,505 DEBUG --- stderr --- 2024-05-25 00:04:19,505 DEBUG 2024-05-25 00:04:19,505 INFO 2024-05-25 00:04:19,505 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-300/stdout.txt 2024-05-25 00:04:20,312 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 00:04:20,312 DEBUG --- stdout --- 2024-05-25 00:04:20,312 DEBUG 636 /results/orders/login-tree-300/stdout.txt 2024-05-25 00:04:20,312 DEBUG --- stderr --- 2024-05-25 00:04:20,312 DEBUG 2024-05-25 00:04:20,312 INFO - Download file (size 636k) 2024-05-25 00:04:20,313 INFO 2024-05-25 00:04:20,313 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 00:04:20,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:20,621 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:20,621 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:20,621 DEBUG --- stdout --- 2024-05-25 00:04:20,622 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:20,622 DEBUG --- stderr --- 2024-05-25 00:04:20,622 DEBUG source : /results/orders/login-tree-300/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stdout.txt 2024-05-25 00:04:20,622 INFO 2024-05-25 00:04:20,622 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 00:04:20,622 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:20,931 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:20,931 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:20,931 DEBUG --- stdout --- 2024-05-25 00:04:20,931 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:20,931 DEBUG --- stderr --- 2024-05-25 00:04:20,931 DEBUG 2024-05-25 00:04:20,932 INFO 2024-05-25 00:04:20,932 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-300/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stdout.txt 2024-05-25 00:04:20,932 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 00:04:21,985 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:21,985 DEBUG --- stdout --- 2024-05-25 00:04:21,985 DEBUG tar: Removing leading `/' from member names 2024-05-25 00:04:21,985 DEBUG --- stderr --- 2024-05-25 00:04:21,985 DEBUG ________________________________________________________________________________ [2024-05-25 00:04:21] login-tree-300 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 00:04:21,986 INFO 2024-05-25 00:04:21,986 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 00:04:21,986 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:22,289 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:22,290 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:22,290 DEBUG --- stdout --- 2024-05-25 00:04:22,290 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:22,290 DEBUG --- stderr --- 2024-05-25 00:04:22,290 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 00:04:22,290 INFO 2024-05-25 00:04:22,290 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 00:04:22,290 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:22,634 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:22,634 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:22,634 DEBUG --- stdout --- 2024-05-25 00:04:22,634 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:22,634 DEBUG --- stderr --- 2024-05-25 00:04:22,634 DEBUG 2024-05-25 00:04:22,635 INFO 2024-05-25 00:04:22,635 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-300/cmd.txt" ]; then echo "/results/orders/login-tree-300/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-300/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/cmd.txt file found" 2024-05-25 00:04:22,635 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 00:04:23,485 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 00:04:23,486 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:23,486 DEBUG --- stdout --- 2024-05-25 00:04:23,486 DEBUG /results/orders/login-tree-300/cmd.txt file found 2024-05-25 00:04:23,486 DEBUG --- stderr --- 2024-05-25 00:04:23,486 DEBUG 2024-05-25 00:04:23,486 INFO 2024-05-25 00:04:23,486 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 00:04:23,486 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:23,797 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:23,797 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:23,797 DEBUG --- stdout --- 2024-05-25 00:04:23,797 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:23,797 DEBUG --- stderr --- 2024-05-25 00:04:23,798 DEBUG 2024-05-25 00:04:23,798 INFO 2024-05-25 00:04:23,798 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-300/cmd.txt 2024-05-25 00:04:24,610 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 00:04:24,610 DEBUG --- stdout --- 2024-05-25 00:04:24,610 DEBUG 4 /results/orders/login-tree-300/cmd.txt 2024-05-25 00:04:24,610 DEBUG --- stderr --- 2024-05-25 00:04:24,610 DEBUG 2024-05-25 00:04:24,610 INFO - Download file (size 4k) 2024-05-25 00:04:24,611 INFO 2024-05-25 00:04:24,611 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 00:04:24,611 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:24,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:24,928 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:24,928 DEBUG --- stdout --- 2024-05-25 00:04:24,928 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:24,928 DEBUG --- stderr --- 2024-05-25 00:04:24,928 DEBUG source : /results/orders/login-tree-300/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/cmd.txt 2024-05-25 00:04:24,928 INFO 2024-05-25 00:04:24,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-25 00:04:24,928 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 00:04:25,236 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 00:04:25,236 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:25,236 DEBUG --- stdout --- 2024-05-25 00:04:25,236 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 00:04:25,236 DEBUG --- stderr --- 2024-05-25 00:04:25,236 DEBUG 2024-05-25 00:04:25,237 INFO 2024-05-25 00:04:25,237 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-300/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/cmd.txt 2024-05-25 00:04:25,237 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 00:04:25,970 INFO [loop_until]: OK (rc = 0) 2024-05-25 00:04:25,970 DEBUG --- stdout --- 2024-05-25 00:04:25,970 DEBUG tar: Removing leading `/' from member names 2024-05-25 00:04:25,970 DEBUG --- stderr --- 2024-05-25 00:04:25,970 DEBUG 2024-05-25 00:04:25,971 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/cmd.txt is small (1 lines), printing all lines: 2024-05-25 00:04:25,971 DEBUG ----- output ----- 2024-05-25 00:04:25,971 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-300 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 00:04:25,971 INFO ________________________________________________________________________________ [2024-05-25 00:04:25] login-tree-300 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 00:04:25,972 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/rc.txt is small (1 lines), printing all lines: 2024-05-25 00:04:25,972 DEBUG ----- output ----- 2024-05-25 00:04:25,972 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 00:04:25,972 INFO ________________________________________________________________________________ [2024-05-25 00:04:25] login-tree-300 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 00:04:25,973 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stdout.txt is 10361 lines): 2024-05-25 00:04:25,973 DEBUG ----- output ----- 2024-05-25 00:04:25,974 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 00:04:25,974 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO ================================================================================ 2024-05-25 00:04:25,974 INFO 2024-05-25 00:02:00 5s elapsed 2024-05-25 00:04:25,974 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,974 INFO > Global (OK=250 KO=0 ) 2024-05-25 00:04:25,974 INFO > restLoginInitiate (OK=126 KO=0 ) 2024-05-25 00:04:25,974 INFO > restLoginUsernamePasswordCallback (OK=124 KO=0 ) 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,974 INFO [- ] 0% 2024-05-25 00:04:25,974 INFO waiting: 1083983 / active: 2 / done: 124 2024-05-25 00:04:25,974 INFO ================================================================================ 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO ================================================================================ 2024-05-25 00:04:25,974 INFO 2024-05-25 00:02:05 10s elapsed 2024-05-25 00:04:25,974 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,974 INFO > Global (OK=1000 KO=0 ) 2024-05-25 00:04:25,974 INFO > restLoginInitiate (OK=501 KO=0 ) 2024-05-25 00:04:25,974 INFO > restLoginUsernamePasswordCallback (OK=499 KO=0 ) 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,974 INFO [- ] 0% 2024-05-25 00:04:25,974 INFO waiting: 1083607 / active: 3 / done: 499 2024-05-25 00:04:25,974 INFO ================================================================================ 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO ================================================================================ 2024-05-25 00:04:25,974 INFO 2024-05-25 00:02:10 15s elapsed 2024-05-25 00:04:25,974 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,974 INFO > Global (OK=2250 KO=0 ) 2024-05-25 00:04:25,974 INFO > restLoginInitiate (OK=1127 KO=0 ) 2024-05-25 00:04:25,974 INFO > restLoginUsernamePasswordCallback (OK=1123 KO=0 ) 2024-05-25 00:04:25,974 INFO 2024-05-25 00:04:25,974 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,975 INFO [- ] 0% 2024-05-25 00:04:25,975 INFO waiting: 1082981 / active: 5 / done: 1123 2024-05-25 00:04:25,975 INFO ================================================================================ 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO ================================================================================ 2024-05-25 00:04:25,975 INFO 2024-05-25 00:02:15 20s elapsed 2024-05-25 00:04:25,975 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,975 INFO > Global (OK=3999 KO=0 ) 2024-05-25 00:04:25,975 INFO > restLoginInitiate (OK=2002 KO=0 ) 2024-05-25 00:04:25,975 INFO > restLoginUsernamePasswordCallback (OK=1997 KO=0 ) 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,975 INFO [- ] 0% 2024-05-25 00:04:25,975 INFO waiting: 1082105 / active: 7 / done: 1997 2024-05-25 00:04:25,975 INFO ================================================================================ 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO ================================================================================ 2024-05-25 00:04:25,975 INFO 2024-05-25 00:02:20 25s elapsed 2024-05-25 00:04:25,975 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,975 INFO > Global (OK=6250 KO=0 ) 2024-05-25 00:04:25,975 INFO > restLoginInitiate (OK=3128 KO=0 ) 2024-05-25 00:04:25,975 INFO > restLoginUsernamePasswordCallback (OK=3122 KO=0 ) 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,975 INFO [- ] 0% 2024-05-25 00:04:25,975 INFO waiting: 1080979 / active: 8 / done: 3122 2024-05-25 00:04:25,975 INFO ================================================================================ 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO ================================================================================ 2024-05-25 00:04:25,975 INFO 2024-05-25 00:02:25 30s elapsed 2024-05-25 00:04:25,975 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,975 INFO > Global (OK=8992 KO=0 ) 2024-05-25 00:04:25,975 INFO > restLoginInitiate (OK=4497 KO=0 ) 2024-05-25 00:04:25,975 INFO > restLoginUsernamePasswordCallback (OK=4495 KO=0 ) 2024-05-25 00:04:25,975 INFO 2024-05-25 00:04:25,975 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,975 INFO [- ] 0% 2024-05-25 00:04:25,976 INFO waiting: 1079604 / active: 10 / done: 4495 2024-05-25 00:04:25,976 INFO ================================================================================ 2024-05-25 00:04:25,976 INFO 2024-05-25 00:04:25,976 INFO 2024-05-25 00:04:25,976 INFO ================================================================================ 2024-05-25 00:04:25,976 INFO 2024-05-25 00:02:30 35s elapsed 2024-05-25 00:04:25,976 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,976 INFO > Global (OK=12027 KO=0 ) 2024-05-25 00:04:25,976 INFO > restLoginInitiate (OK=6017 KO=0 ) 2024-05-25 00:04:25,976 INFO > restLoginUsernamePasswordCallback (OK=6010 KO=0 ) 2024-05-25 00:04:25,976 INFO 2024-05-25 00:04:25,976 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,976 INFO [- ] 0% 2024-05-25 00:04:25,976 INFO waiting: 1078092 / active: 7 / done: 6010 2024-05-25 00:04:25,976 INFO ================================================================================ 2024-05-25 00:04:25,976 INFO 2024-05-25 00:04:25,976 INFO 2024-05-25 00:04:25,976 INFO ================================================================================ 2024-05-25 00:04:25,976 INFO 2024-05-25 00:02:35 40s elapsed 2024-05-25 00:04:25,976 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,976 INFO > Global (OK=14955 KO=0 ) 2024-05-25 00:04:25,976 INFO > restLoginInitiate (OK=7481 KO=0 ) 2024-05-25 00:04:25,976 INFO > restLoginUsernamePasswordCallback (OK=7474 KO=0 ) 2024-05-25 00:04:25,976 INFO [...] 2024-05-25 00:04:25,979 INFO 2024-05-25 00:04:25,979 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,979 INFO [#########################################################################-] 99% 2024-05-25 00:04:25,979 INFO waiting: 5915 / active: 7 / done: 1078187 2024-05-25 00:04:25,979 INFO ================================================================================ 2024-05-25 00:04:25,979 INFO 2024-05-25 00:04:25,979 INFO 2024-05-25 00:04:25,979 INFO ================================================================================ 2024-05-25 00:04:25,979 INFO 2024-05-25 01:02:10 3615s elapsed 2024-05-25 00:04:25,979 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,979 INFO > Global (OK=2159397 KO=2 ) 2024-05-25 00:04:25,979 INFO > restLoginInitiate (OK=1079701 KO=1 ) 2024-05-25 00:04:25,979 INFO > restLoginUsernamePasswordCallback (OK=1079696 KO=1 ) 2024-05-25 00:04:25,979 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 00:04:25,979 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 00:04:25,979 INFO 2024-05-25 00:04:25,979 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,979 INFO [#########################################################################-] 99% 2024-05-25 00:04:25,979 INFO waiting: 4406 / active: 5 / done: 1079698 2024-05-25 00:04:25,979 INFO ================================================================================ 2024-05-25 00:04:25,979 INFO 2024-05-25 00:04:25,979 INFO 2024-05-25 00:04:25,979 INFO ================================================================================ 2024-05-25 00:04:25,979 INFO 2024-05-25 01:02:15 3620s elapsed 2024-05-25 00:04:25,979 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,980 INFO > Global (OK=2162336 KO=2 ) 2024-05-25 00:04:25,980 INFO > restLoginInitiate (OK=1081173 KO=1 ) 2024-05-25 00:04:25,980 INFO > restLoginUsernamePasswordCallback (OK=1081163 KO=1 ) 2024-05-25 00:04:25,980 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 00:04:25,980 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,980 INFO [#########################################################################-] 99% 2024-05-25 00:04:25,980 INFO waiting: 2934 / active: 10 / done: 1081165 2024-05-25 00:04:25,980 INFO ================================================================================ 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO ================================================================================ 2024-05-25 00:04:25,980 INFO 2024-05-25 01:02:20 3625s elapsed 2024-05-25 00:04:25,980 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,980 INFO > Global (OK=2165249 KO=2 ) 2024-05-25 00:04:25,980 INFO > restLoginInitiate (OK=1082629 KO=1 ) 2024-05-25 00:04:25,980 INFO > restLoginUsernamePasswordCallback (OK=1082620 KO=1 ) 2024-05-25 00:04:25,980 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 00:04:25,980 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,980 INFO [#########################################################################-] 99% 2024-05-25 00:04:25,980 INFO waiting: 1476 / active: 11 / done: 1082622 2024-05-25 00:04:25,980 INFO ================================================================================ 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO ================================================================================ 2024-05-25 00:04:25,980 INFO 2024-05-25 01:02:25 3630s elapsed 2024-05-25 00:04:25,980 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,980 INFO > Global (OK=2168211 KO=2 ) 2024-05-25 00:04:25,980 INFO > restLoginInitiate (OK=1084108 KO=1 ) 2024-05-25 00:04:25,980 INFO > restLoginUsernamePasswordCallback (OK=1084103 KO=1 ) 2024-05-25 00:04:25,980 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 00:04:25,980 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,980 INFO [#########################################################################-] 99% 2024-05-25 00:04:25,980 INFO waiting: 0 / active: 4 / done: 1084105 2024-05-25 00:04:25,980 INFO ================================================================================ 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,980 INFO 2024-05-25 00:04:25,981 INFO ================================================================================ 2024-05-25 00:04:25,981 INFO 2024-05-25 01:02:25 3630s elapsed 2024-05-25 00:04:25,981 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 00:04:25,981 INFO > Global (OK=2168215 KO=2 ) 2024-05-25 00:04:25,981 INFO > restLoginInitiate (OK=1084108 KO=1 ) 2024-05-25 00:04:25,981 INFO > restLoginUsernamePasswordCallback (OK=1084107 KO=1 ) 2024-05-25 00:04:25,981 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 00:04:25,981 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 00:04:25,981 INFO 2024-05-25 00:04:25,981 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 00:04:25,981 INFO [##########################################################################]100% 2024-05-25 00:04:25,981 INFO waiting: 0 / active: 0 / done: 1084109 2024-05-25 00:04:25,981 INFO ================================================================================ 2024-05-25 00:04:25,981 INFO 2024-05-25 00:04:25,981 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 00:04:25,981 INFO 2024-05-25 00:04:25,981 INFO ---------------------- 2024-05-25 00:04:25,981 INFO [25/May/2024 01:02:25] Create tar file login-tree-300.tar.gz 2024-05-25 00:04:25,981 INFO ---------------------- 2024-05-25 00:04:25,981 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 00:04:25,981 INFO RESULT_FOLDER=login-tree-300 2024-05-25 00:04:25,981 INFO SIMULATION_PATH=login-tree-300/loadtest-20240524230155303 2024-05-25 00:04:25,981 INFO PWD=/results/gatling/login-tree-300/loadtest-20240524230155303 2024-05-25 00:04:25,981 INFO [cmd] tar -czvf /results/gatling//login-tree-300.tar.gz --exclude=simulation.log * 2024-05-25 00:04:25,981 INFO /results/gatling 2024-05-25 00:04:25,981 INFO 2024-05-25 00:04:25,981 INFO ---------------------- 2024-05-25 00:04:25,981 INFO [25/May/2024 01:02:25] Update /results/gatling//latest 2024-05-25 00:04:25,981 INFO ---------------------- 2024-05-25 00:04:25,981 INFO 2024-05-25 00:04:25,981 INFO ---------------------- 2024-05-25 00:04:25,981 INFO [25/May/2024 01:02:25] Update /results/gatling//latest.tar.gz 2024-05-25 00:04:25,981 INFO ---------------------- 2024-05-25 00:04:25,981 INFO ________________________________________________________________________________ [2024-05-25 00:04:25] login-tree-300 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 00:04:25,982 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stderr.txt is small (0 lines), printing all lines: 2024-05-25 00:04:25,982 DEBUG ----- output ----- 2024-05-25 00:04:25,982 DEBUG 2024-05-25 00:04:25,982 INFO ________________________________________________________________________________ [2024-05-25 00:04:25] login-tree-300 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-05-25 00:04:25] login-tree-300 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 00:04:25] login-tree-300 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped