--Task-- name: login-tree-10 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: [] preceding_task: None options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 10, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-22 18:11:17] login-tree-10 pre : N/A ________________________________________________________________________________ 2024-06-22 18:11:17 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 18:11:17 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-10/stdout.txt Pod log : /results/orders/login-tree-10 2024-06-22 18:11:17,110 INFO 2024-06-22 18:11:17,110 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 18:11:17,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 18:11:17,436 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 18:11:17,436 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:17,436 DEBUG --- stdout --- 2024-06-22 18:11:17,436 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 18:11:17,436 DEBUG --- stderr --- 2024-06-22 18:11:17,436 DEBUG 2024-06-22 18:11:17 - INFO: Get overseer pod resources requests memory 2024-06-22 18:11:17,437 INFO 2024-06-22 18:11:17,437 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-5b6f9464fd-slbm8 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-06-22 18:11:17,437 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 18:11:17,771 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:17,772 DEBUG --- stdout --- 2024-06-22 18:11:17,772 DEBUG 4Gi 2024-06-22 18:11:17,772 DEBUG --- stderr --- 2024-06-22 18:11:17,772 DEBUG ________________________________________________________________________________ [2024-06-22 18:11:17] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 18:11:17,772 INFO 2024-06-22 18:11:17,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-06-22 18:11:17,772 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 18:11:18,078 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 18:11:18,078 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:18,078 DEBUG --- stdout --- 2024-06-22 18:11:18,078 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 18:11:18,078 DEBUG --- stderr --- 2024-06-22 18:11:18,078 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 18:11:18] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 18:11:18,079 INFO 2024-06-22 18:11:18,079 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 18:11:18,079 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 18:11:18,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 18:11:18,390 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:18,390 DEBUG --- stdout --- 2024-06-22 18:11:18,390 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 18:11:18,390 DEBUG --- stderr --- 2024-06-22 18:11:18,390 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json { "name": "login-tree-10", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-p1\" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-10", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 18:11:18] login-tree-10 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-22 18:11:18,392 INFO 2024-06-22 18:11:18,393 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 18:11:18,393 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 18:11:18,702 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 18:11:18,702 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:18,702 DEBUG --- stdout --- 2024-06-22 18:11:18,702 DEBUG WAITING 2024-06-22 18:11:18,703 DEBUG --- stderr --- 2024-06-22 18:11:18,703 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-06-22 18:11:18] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 18:11:18,703 INFO 2024-06-22 18:11:18,703 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 18:11:18,703 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 18:11:19,020 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 18:11:19,020 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:19,020 DEBUG --- stdout --- 2024-06-22 18:11:19,020 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 18:11:19,020 DEBUG --- stderr --- 2024-06-22 18:11:19,020 DEBUG 2024-06-22 18:11:19,021 INFO 2024-06-22 18:11:19,021 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/01_login-tree-10/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 18:11:19,021 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 18:11:20,426 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:20,427 DEBUG --- stdout --- 2024-06-22 18:11:20,427 DEBUG 2024-06-22 18:11:20,427 DEBUG --- stderr --- 2024-06-22 18:11:20,427 DEBUG Order has been successfully forwarded to overseer 2024-06-22 18:11:20 - INFO: Check if Gatling simulation started successfully 2024-06-22 18:11:20,427 INFO 2024-06-22 18:11:20,427 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 18:11:20,427 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 18:11:20,749 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 18:11:20,750 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:20,750 DEBUG --- stdout --- 2024-06-22 18:11:20,750 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 18:11:20,750 DEBUG --- stderr --- 2024-06-22 18:11:20,750 DEBUG 2024-06-22 18:11:20,750 INFO 2024-06-22 18:11:20,750 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-10/stdout.txt | grep "started..." 2024-06-22 18:11:20,750 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 18:11:21,498 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 18:11:27,314 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-22 18:11:33,051 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-22 18:11:33,051 INFO [loop_until]: OK (rc = 0) 2024-06-22 18:11:33,051 DEBUG --- stdout --- 2024-06-22 18:11:33,051 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 19:11:22.583 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 19:11:27.312 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 19:11:27.315 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 19:11:29.071 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 18:11:33,052 DEBUG --- stderr --- 2024-06-22 18:11:33,052 DEBUG ________________________________________________________________________________ [2024-06-22 18:11:33] login-tree-10 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 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 08s, retry Order is running since 56m 08s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-22 19:13:43] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 19:13:43,044 INFO 2024-06-22 19:13:43,045 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:43,045 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:43,393 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:43,393 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:43,394 DEBUG --- stdout --- 2024-06-22 19:13:43,394 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:43,394 DEBUG --- stderr --- 2024-06-22 19:13:43,394 DEBUG 2024-06-22 19:13:43,394 INFO 2024-06-22 19:13:43,394 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-22 19:13:43,394 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 19:13:44,185 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:44,185 DEBUG --- stdout --- 2024-06-22 19:13:44,187 DEBUG 2024-06-22 19:13:44,187 DEBUG --- stderr --- 2024-06-22 19:13:44,187 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 19:13:44] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 19:13:44 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json 2024-06-22 19:13:44,188 INFO 2024-06-22 19:13:44,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-06-22 19:13:44,188 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:44,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:44,493 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:44,493 DEBUG --- stdout --- 2024-06-22 19:13:44,493 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:44,493 DEBUG --- stderr --- 2024-06-22 19:13:44,493 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 19:13:44,494 INFO 2024-06-22 19:13:44,494 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:44,494 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:44,813 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:44,813 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:44,814 DEBUG --- stdout --- 2024-06-22 19:13:44,814 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:44,814 DEBUG --- stderr --- 2024-06-22 19:13:44,814 DEBUG 2024-06-22 19:13:44,814 INFO 2024-06-22 19:13:44,814 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/rc.txt" ]; then echo "/results/orders/login-tree-10/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/rc.txt file found" 2024-06-22 19:13:44,814 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 19:13:45,623 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 19:13:45,624 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:45,624 DEBUG --- stdout --- 2024-06-22 19:13:45,624 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-06-22 19:13:45,624 DEBUG --- stderr --- 2024-06-22 19:13:45,624 DEBUG 2024-06-22 19:13:45,624 INFO 2024-06-22 19:13:45,624 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:45,624 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:45,946 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:45,946 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:45,946 DEBUG --- stdout --- 2024-06-22 19:13:45,946 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:45,946 DEBUG --- stderr --- 2024-06-22 19:13:45,946 DEBUG 2024-06-22 19:13:45,946 INFO 2024-06-22 19:13:45,947 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/rc.txt 2024-06-22 19:13:46,753 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 19:13:46,754 DEBUG --- stdout --- 2024-06-22 19:13:46,754 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-06-22 19:13:46,754 DEBUG --- stderr --- 2024-06-22 19:13:46,754 DEBUG 2024-06-22 19:13:46,754 INFO - Download file (size 4k) 2024-06-22 19:13:46,754 INFO 2024-06-22 19:13:46,754 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:46,755 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:47,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:47,081 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:47,081 DEBUG --- stdout --- 2024-06-22 19:13:47,081 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:47,081 DEBUG --- stderr --- 2024-06-22 19:13:47,081 DEBUG source : /results/orders/login-tree-10/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-22 19:13:47,081 INFO 2024-06-22 19:13:47,082 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:47,082 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:47,422 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:47,422 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:47,422 DEBUG --- stdout --- 2024-06-22 19:13:47,422 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:47,422 DEBUG --- stderr --- 2024-06-22 19:13:47,422 DEBUG 2024-06-22 19:13:47,423 INFO 2024-06-22 19:13:47,423 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-22 19:13:47,423 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 19:13:48,174 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:48,175 DEBUG --- stdout --- 2024-06-22 19:13:48,175 DEBUG tar: Removing leading `/' from member names 2024-06-22 19:13:48,175 DEBUG --- stderr --- 2024-06-22 19:13:48,175 DEBUG 2024-06-22 19:13:48,176 INFO 2024-06-22 19:13:48,176 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:48,176 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:48,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:48,492 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:48,492 DEBUG --- stdout --- 2024-06-22 19:13:48,492 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:48,492 DEBUG --- stderr --- 2024-06-22 19:13:48,492 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 19:13:48,493 INFO 2024-06-22 19:13:48,493 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:48,493 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:48,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:48,814 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:48,814 DEBUG --- stdout --- 2024-06-22 19:13:48,814 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:48,814 DEBUG --- stderr --- 2024-06-22 19:13:48,814 DEBUG 2024-06-22 19:13:48,815 INFO 2024-06-22 19:13:48,815 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stderr.txt" ]; then echo "/results/orders/login-tree-10/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stderr.txt file found" 2024-06-22 19:13:48,815 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 19:13:49,629 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 19:13:49,629 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:49,629 DEBUG --- stdout --- 2024-06-22 19:13:49,629 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-06-22 19:13:49,629 DEBUG --- stderr --- 2024-06-22 19:13:49,629 DEBUG 2024-06-22 19:13:49,629 INFO 2024-06-22 19:13:49,629 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:49,630 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:49,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:49,945 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:49,945 DEBUG --- stdout --- 2024-06-22 19:13:49,945 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:49,945 DEBUG --- stderr --- 2024-06-22 19:13:49,945 DEBUG 2024-06-22 19:13:49,946 INFO 2024-06-22 19:13:49,946 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/stderr.txt 2024-06-22 19:13:50,745 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 19:13:50,746 DEBUG --- stdout --- 2024-06-22 19:13:50,746 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-06-22 19:13:50,746 DEBUG --- stderr --- 2024-06-22 19:13:50,746 DEBUG 2024-06-22 19:13:50,746 INFO - Download file (size 0k) 2024-06-22 19:13:50,747 INFO 2024-06-22 19:13:50,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-06-22 19:13:50,747 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:51,077 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:51,077 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:51,077 DEBUG --- stdout --- 2024-06-22 19:13:51,077 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:51,077 DEBUG --- stderr --- 2024-06-22 19:13:51,077 DEBUG source : /results/orders/login-tree-10/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-22 19:13:51,078 INFO 2024-06-22 19:13:51,078 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:51,078 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:51,405 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:51,406 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:51,406 DEBUG --- stdout --- 2024-06-22 19:13:51,406 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:51,406 DEBUG --- stderr --- 2024-06-22 19:13:51,406 DEBUG 2024-06-22 19:13:51,406 INFO 2024-06-22 19:13:51,406 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-22 19:13:51,406 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 19:13:52,148 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:52,148 DEBUG --- stdout --- 2024-06-22 19:13:52,149 DEBUG tar: Removing leading `/' from member names 2024-06-22 19:13:52,149 DEBUG --- stderr --- 2024-06-22 19:13:52,149 DEBUG 2024-06-22 19:13:52,149 INFO 2024-06-22 19:13:52,149 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:52,149 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:52,476 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:52,476 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:52,476 DEBUG --- stdout --- 2024-06-22 19:13:52,476 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:52,476 DEBUG --- stderr --- 2024-06-22 19:13:52,476 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 19:13:52,477 INFO 2024-06-22 19:13:52,477 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:52,477 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:52,784 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:52,784 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:52,784 DEBUG --- stdout --- 2024-06-22 19:13:52,784 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:52,784 DEBUG --- stderr --- 2024-06-22 19:13:52,784 DEBUG 2024-06-22 19:13:52,785 INFO 2024-06-22 19:13:52,785 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stdout.txt" ]; then echo "/results/orders/login-tree-10/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stdout.txt file found" 2024-06-22 19:13:52,785 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 19:13:53,589 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 19:13:53,589 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:53,589 DEBUG --- stdout --- 2024-06-22 19:13:53,589 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-06-22 19:13:53,589 DEBUG --- stderr --- 2024-06-22 19:13:53,589 DEBUG 2024-06-22 19:13:53,590 INFO 2024-06-22 19:13:53,590 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:53,590 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:53,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:53,904 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:53,904 DEBUG --- stdout --- 2024-06-22 19:13:53,904 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:53,904 DEBUG --- stderr --- 2024-06-22 19:13:53,904 DEBUG 2024-06-22 19:13:53,904 INFO 2024-06-22 19:13:53,904 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/stdout.txt 2024-06-22 19:13:54,707 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 19:13:54,707 DEBUG --- stdout --- 2024-06-22 19:13:54,709 DEBUG 712 /results/orders/login-tree-10/stdout.txt 2024-06-22 19:13:54,709 DEBUG --- stderr --- 2024-06-22 19:13:54,709 DEBUG 2024-06-22 19:13:54,709 INFO - Download file (size 712k) 2024-06-22 19:13:54,710 INFO 2024-06-22 19:13:54,710 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:54,710 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:55,032 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:55,033 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:55,033 DEBUG --- stdout --- 2024-06-22 19:13:55,033 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:55,033 DEBUG --- stderr --- 2024-06-22 19:13:55,033 DEBUG source : /results/orders/login-tree-10/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-22 19:13:55,033 INFO 2024-06-22 19:13:55,033 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:55,033 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:55,371 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:55,371 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:55,371 DEBUG --- stdout --- 2024-06-22 19:13:55,372 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:55,372 DEBUG --- stderr --- 2024-06-22 19:13:55,372 DEBUG 2024-06-22 19:13:55,373 INFO 2024-06-22 19:13:55,373 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-22 19:13:55,373 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 19:13:56,483 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:56,483 DEBUG --- stdout --- 2024-06-22 19:13:56,483 DEBUG tar: Removing leading `/' from member names 2024-06-22 19:13:56,483 DEBUG --- stderr --- 2024-06-22 19:13:56,483 DEBUG ________________________________________________________________________________ [2024-06-22 19:13:56] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 19:13:56,484 INFO 2024-06-22 19:13:56,484 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:56,484 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:56,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:56,793 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:56,793 DEBUG --- stdout --- 2024-06-22 19:13:56,793 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:56,793 DEBUG --- stderr --- 2024-06-22 19:13:56,793 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 19:13:56,793 INFO 2024-06-22 19:13:56,793 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:56,793 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:57,094 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:57,095 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:57,095 DEBUG --- stdout --- 2024-06-22 19:13:57,095 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:57,095 DEBUG --- stderr --- 2024-06-22 19:13:57,095 DEBUG 2024-06-22 19:13:57,095 INFO 2024-06-22 19:13:57,095 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/cmd.txt" ]; then echo "/results/orders/login-tree-10/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/cmd.txt file found" 2024-06-22 19:13:57,095 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 19:13:57,892 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 19:13:57,893 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:57,893 DEBUG --- stdout --- 2024-06-22 19:13:57,893 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-06-22 19:13:57,893 DEBUG --- stderr --- 2024-06-22 19:13:57,893 DEBUG 2024-06-22 19:13:57,893 INFO 2024-06-22 19:13:57,893 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:57,893 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:58,200 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:58,200 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:58,200 DEBUG --- stdout --- 2024-06-22 19:13:58,200 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:58,200 DEBUG --- stderr --- 2024-06-22 19:13:58,200 DEBUG 2024-06-22 19:13:58,200 INFO 2024-06-22 19:13:58,201 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/cmd.txt 2024-06-22 19:13:59,002 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 19:13:59,002 DEBUG --- stdout --- 2024-06-22 19:13:59,002 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-06-22 19:13:59,002 DEBUG --- stderr --- 2024-06-22 19:13:59,002 DEBUG 2024-06-22 19:13:59,002 INFO - Download file (size 4k) 2024-06-22 19:13:59,002 INFO 2024-06-22 19:13:59,002 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:59,002 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:59,304 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:59,304 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:59,304 DEBUG --- stdout --- 2024-06-22 19:13:59,304 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:59,304 DEBUG --- stderr --- 2024-06-22 19:13:59,304 DEBUG source : /results/orders/login-tree-10/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-22 19:13:59,304 INFO 2024-06-22 19:13:59,304 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:13:59,304 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:13:59,617 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:13:59,617 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:13:59,617 DEBUG --- stdout --- 2024-06-22 19:13:59,617 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:13:59,617 DEBUG --- stderr --- 2024-06-22 19:13:59,617 DEBUG 2024-06-22 19:13:59,618 INFO 2024-06-22 19:13:59,618 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-22 19:13:59,618 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 19:14:00,359 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:00,359 DEBUG --- stdout --- 2024-06-22 19:14:00,359 DEBUG tar: Removing leading `/' from member names 2024-06-22 19:14:00,359 DEBUG --- stderr --- 2024-06-22 19:14:00,359 DEBUG 2024-06-22 19:14:00,360 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt is small (1 lines), printing all lines: 2024-06-22 19:14:00,360 DEBUG ----- output ----- 2024-06-22 19:14:00,360 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-22 19:14:00,360 INFO ________________________________________________________________________________ [2024-06-22 19:14:00] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 19:14:00,360 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt is small (1 lines), printing all lines: 2024-06-22 19:14:00,360 DEBUG ----- output ----- 2024-06-22 19:14:00,360 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 19:14:00,360 INFO ________________________________________________________________________________ [2024-06-22 19:14:00] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 19:14:00,362 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/01_login-tree-10/stdout.txt is 11410 lines): 2024-06-22 19:14:00,363 DEBUG ----- output ----- 2024-06-22 19:14:00,363 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 19:14:00,363 INFO 19:11:22.583 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-22 19:14:00,363 INFO 19:11:27.312 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-22 19:14:00,363 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 19:14:00,363 INFO ^ 2024-06-22 19:14:00,363 INFO 19:11:27.315 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-22 19:14:00,363 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 19:14:00,363 INFO ^ 2024-06-22 19:14:00,363 INFO 19:11:29.071 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-22 19:14:00,363 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 19:14:00,363 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 19:14:00,363 INFO 2024-06-22 19:14:00,363 INFO ================================================================================ 2024-06-22 19:14:00,363 INFO 2024-06-22 19:11:36 5s elapsed 2024-06-22 19:14:00,363 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,363 INFO > Global (OK=8 KO=0 ) 2024-06-22 19:14:00,363 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-06-22 19:14:00,363 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-06-22 19:14:00,363 INFO 2024-06-22 19:14:00,363 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,363 INFO [- ] 0% 2024-06-22 19:14:00,363 INFO waiting: 36218 / active: 1 / done: 4 2024-06-22 19:14:00,363 INFO ================================================================================ 2024-06-22 19:14:00,363 INFO 2024-06-22 19:14:00,363 INFO 2024-06-22 19:14:00,363 INFO ================================================================================ 2024-06-22 19:14:00,363 INFO 2024-06-22 19:11:41 10s elapsed 2024-06-22 19:14:00,363 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,363 INFO > Global (OK=32 KO=0 ) 2024-06-22 19:14:00,363 INFO > restLoginInitiate (OK=16 KO=0 ) 2024-06-22 19:14:00,363 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-06-22 19:14:00,363 INFO 2024-06-22 19:14:00,364 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,364 INFO [- ] 0% 2024-06-22 19:14:00,364 INFO waiting: 36206 / active: 1 / done: 16 2024-06-22 19:14:00,364 INFO ================================================================================ 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO ================================================================================ 2024-06-22 19:14:00,364 INFO 2024-06-22 19:11:46 15s elapsed 2024-06-22 19:14:00,364 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,364 INFO > Global (OK=74 KO=0 ) 2024-06-22 19:14:00,364 INFO > restLoginInitiate (OK=37 KO=0 ) 2024-06-22 19:14:00,364 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,364 INFO [- ] 0% 2024-06-22 19:14:00,364 INFO waiting: 36185 / active: 1 / done: 37 2024-06-22 19:14:00,364 INFO ================================================================================ 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO ================================================================================ 2024-06-22 19:14:00,364 INFO 2024-06-22 19:11:51 20s elapsed 2024-06-22 19:14:00,364 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,364 INFO > Global (OK=131 KO=0 ) 2024-06-22 19:14:00,364 INFO > restLoginInitiate (OK=66 KO=0 ) 2024-06-22 19:14:00,364 INFO > restLoginUsernamePasswordCallback (OK=65 KO=0 ) 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,364 INFO [- ] 0% 2024-06-22 19:14:00,364 INFO waiting: 36156 / active: 2 / done: 65 2024-06-22 19:14:00,364 INFO ================================================================================ 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO ================================================================================ 2024-06-22 19:14:00,364 INFO 2024-06-22 19:11:56 25s elapsed 2024-06-22 19:14:00,364 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,364 INFO > Global (OK=207 KO=0 ) 2024-06-22 19:14:00,364 INFO > restLoginInitiate (OK=104 KO=0 ) 2024-06-22 19:14:00,364 INFO > restLoginUsernamePasswordCallback (OK=103 KO=0 ) 2024-06-22 19:14:00,364 INFO 2024-06-22 19:14:00,364 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,365 INFO [- ] 0% 2024-06-22 19:14:00,365 INFO waiting: 36118 / active: 2 / done: 103 2024-06-22 19:14:00,365 INFO ================================================================================ 2024-06-22 19:14:00,365 INFO 2024-06-22 19:14:00,365 INFO 2024-06-22 19:14:00,365 INFO ================================================================================ 2024-06-22 19:14:00,365 INFO 2024-06-22 19:12:01 30s elapsed 2024-06-22 19:14:00,365 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,365 INFO > Global (OK=299 KO=0 ) 2024-06-22 19:14:00,365 INFO > restLoginInitiate (OK=150 KO=0 ) 2024-06-22 19:14:00,365 INFO > restLoginUsernamePasswordCallback (OK=149 KO=0 ) 2024-06-22 19:14:00,365 INFO 2024-06-22 19:14:00,365 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,365 INFO [- ] 0% 2024-06-22 19:14:00,365 INFO waiting: 36073 / active: 1 / done: 149 2024-06-22 19:14:00,365 INFO ================================================================================ 2024-06-22 19:14:00,365 INFO 2024-06-22 19:14:00,365 INFO 2024-06-22 19:14:00,365 INFO ================================================================================ 2024-06-22 19:14:00,365 INFO 2024-06-22 19:12:06 35s elapsed 2024-06-22 19:14:00,365 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,366 INFO > Global (OK=392 KO=0 ) 2024-06-22 19:14:00,366 INFO > restLoginInitiate (OK=196 KO=0 ) 2024-06-22 19:14:00,366 INFO > restLoginUsernamePasswordCallback (OK=196 KO=0 ) 2024-06-22 19:14:00,366 INFO 2024-06-22 19:14:00,366 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,366 INFO [- ] 0% 2024-06-22 19:14:00,366 INFO waiting: 36026 / active: 1 / done: 196 2024-06-22 19:14:00,366 INFO [...] 2024-06-22 19:14:00,369 INFO 2024-06-22 19:14:00,369 INFO 2024-06-22 19:14:00,369 INFO ================================================================================ 2024-06-22 19:14:00,369 INFO 2024-06-22 20:11:46 3615s elapsed 2024-06-22 19:14:00,369 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,369 INFO > Global (OK=72175 KO=2 ) 2024-06-22 19:14:00,369 INFO > restLoginInitiate (OK=36084 KO=2 ) 2024-06-22 19:14:00,369 INFO > restLoginUsernamePasswordCallback (OK=36084 KO=0 ) 2024-06-22 19:14:00,369 INFO > restloginProgressiveProfileCallback (OK=7 KO=0 ) 2024-06-22 19:14:00,369 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 19:14:00,369 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-22 19:14:00,369 INFO 2024-06-22 19:14:00,369 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,369 INFO [######################################################################### ] 99% 2024-06-22 19:14:00,369 INFO waiting: 137 / active: 0 / done: 36086 2024-06-22 19:14:00,369 INFO ================================================================================ 2024-06-22 19:14:00,369 INFO 2024-06-22 19:14:00,369 INFO 2024-06-22 19:14:00,369 INFO ================================================================================ 2024-06-22 19:14:00,370 INFO 2024-06-22 20:11:51 3620s elapsed 2024-06-22 19:14:00,370 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,370 INFO > Global (OK=72253 KO=2 ) 2024-06-22 19:14:00,370 INFO > restLoginInitiate (OK=36123 KO=2 ) 2024-06-22 19:14:00,370 INFO > restLoginUsernamePasswordCallback (OK=36122 KO=0 ) 2024-06-22 19:14:00,370 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-22 19:14:00,370 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 19:14:00,370 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-22 19:14:00,370 INFO 2024-06-22 19:14:00,370 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,370 INFO [#########################################################################-] 99% 2024-06-22 19:14:00,370 INFO waiting: 97 / active: 2 / done: 36124 2024-06-22 19:14:00,370 INFO ================================================================================ 2024-06-22 19:14:00,370 INFO 2024-06-22 19:14:00,370 INFO 2024-06-22 19:14:00,370 INFO ================================================================================ 2024-06-22 19:14:00,370 INFO 2024-06-22 20:11:56 3625s elapsed 2024-06-22 19:14:00,370 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,370 INFO > Global (OK=72336 KO=2 ) 2024-06-22 19:14:00,370 INFO > restLoginInitiate (OK=36164 KO=2 ) 2024-06-22 19:14:00,370 INFO > restLoginUsernamePasswordCallback (OK=36164 KO=0 ) 2024-06-22 19:14:00,370 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-22 19:14:00,370 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 19:14:00,370 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-22 19:14:00,370 INFO 2024-06-22 19:14:00,370 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,370 INFO [######################################################################### ] 99% 2024-06-22 19:14:00,370 INFO waiting: 57 / active: 0 / done: 36166 2024-06-22 19:14:00,370 INFO ================================================================================ 2024-06-22 19:14:00,370 INFO 2024-06-22 19:14:00,370 INFO 2024-06-22 19:14:00,370 INFO ================================================================================ 2024-06-22 19:14:00,370 INFO 2024-06-22 20:12:01 3630s elapsed 2024-06-22 19:14:00,370 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,370 INFO > Global (OK=72448 KO=2 ) 2024-06-22 19:14:00,370 INFO > restLoginInitiate (OK=36221 KO=2 ) 2024-06-22 19:14:00,371 INFO > restLoginUsernamePasswordCallback (OK=36219 KO=0 ) 2024-06-22 19:14:00,371 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-22 19:14:00,371 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 19:14:00,371 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,371 INFO [#########################################################################-] 99% 2024-06-22 19:14:00,371 INFO waiting: 0 / active: 2 / done: 36221 2024-06-22 19:14:00,371 INFO ================================================================================ 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO ================================================================================ 2024-06-22 19:14:00,371 INFO 2024-06-22 20:12:01 3630s elapsed 2024-06-22 19:14:00,371 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 19:14:00,371 INFO > Global (OK=72450 KO=2 ) 2024-06-22 19:14:00,371 INFO > restLoginInitiate (OK=36221 KO=2 ) 2024-06-22 19:14:00,371 INFO > restLoginUsernamePasswordCallback (OK=36221 KO=0 ) 2024-06-22 19:14:00,371 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-22 19:14:00,371 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 19:14:00,371 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 19:14:00,371 INFO [##########################################################################]100% 2024-06-22 19:14:00,371 INFO waiting: 0 / active: 0 / done: 36223 2024-06-22 19:14:00,371 INFO ================================================================================ 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO ---------------------- 2024-06-22 19:14:00,371 INFO [22/Jun/2024 20:12:01] Create tar file login-tree-10.tar.gz 2024-06-22 19:14:00,371 INFO ---------------------- 2024-06-22 19:14:00,371 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 19:14:00,371 INFO RESULT_FOLDER=login-tree-10 2024-06-22 19:14:00,371 INFO SIMULATION_PATH=login-tree-10/loadtest-20240622181131048 2024-06-22 19:14:00,371 INFO PWD=/results/gatling/login-tree-10/loadtest-20240622181131048 2024-06-22 19:14:00,371 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-06-22 19:14:00,371 INFO /results/gatling 2024-06-22 19:14:00,371 INFO 2024-06-22 19:14:00,371 INFO ---------------------- 2024-06-22 19:14:00,371 INFO [22/Jun/2024 20:12:01] Update /results/gatling//latest 2024-06-22 19:14:00,372 INFO ---------------------- 2024-06-22 19:14:00,372 INFO 2024-06-22 19:14:00,372 INFO ---------------------- 2024-06-22 19:14:00,372 INFO [22/Jun/2024 20:12:01] Update /results/gatling//latest.tar.gz 2024-06-22 19:14:00,372 INFO ---------------------- 2024-06-22 19:14:00,372 INFO ________________________________________________________________________________ [2024-06-22 19:14:00] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 19:14:00,372 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt is small (0 lines), printing all lines: 2024-06-22 19:14:00,372 DEBUG ----- output ----- 2024-06-22 19:14:00,372 DEBUG 2024-06-22 19:14:00,372 INFO ________________________________________________________________________________ [2024-06-22 19:14:00] login-tree-10 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-22 19:14:00] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 19:14:00] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped