--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-23 21:19:55] login-tree-10 pre : N/A ________________________________________________________________________________ 2024-06-23 21:19:55 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 21:19:55 - 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-23 21:19:55,465 INFO 2024-06-23 21:19:55,466 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-23 21:19:55,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 21:19:55,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 21:19:55,801 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:55,801 DEBUG --- stdout --- 2024-06-23 21:19:55,801 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 21:19:55,801 DEBUG --- stderr --- 2024-06-23 21:19:55,801 DEBUG 2024-06-23 21:19:55 - INFO: Get overseer pod resources requests memory 2024-06-23 21:19:55,802 INFO 2024-06-23 21:19:55,802 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-23 21:19:55,802 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 21:19:56,147 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:56,147 DEBUG --- stdout --- 2024-06-23 21:19:56,147 DEBUG 4Gi 2024-06-23 21:19:56,147 DEBUG --- stderr --- 2024-06-23 21:19:56,147 DEBUG ________________________________________________________________________________ [2024-06-23 21:19:56] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 21:19:56,148 INFO 2024-06-23 21:19:56,148 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-23 21:19:56,148 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 21:19:56,472 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 21:19:56,472 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:56,473 DEBUG --- stdout --- 2024-06-23 21:19:56,473 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 21:19:56,473 DEBUG --- stderr --- 2024-06-23 21:19:56,473 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-23 21:19:56] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 21:19:56,473 INFO 2024-06-23 21:19:56,474 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-23 21:19:56,474 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 21:19:56,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 21:19:56,792 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:56,792 DEBUG --- stdout --- 2024-06-23 21:19:56,792 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 21:19:56,792 DEBUG --- stderr --- 2024-06-23 21:19:56,792 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-23 21:19:56] 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-23 21:19:56,794 INFO 2024-06-23 21:19:56,794 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 21:19:56,794 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 21:19:57,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 21:19:57,100 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:57,100 DEBUG --- stdout --- 2024-06-23 21:19:57,100 DEBUG WAITING 2024-06-23 21:19:57,100 DEBUG --- stderr --- 2024-06-23 21:19:57,100 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-06-23 21:19:57] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 21:19:57,101 INFO 2024-06-23 21:19:57,101 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-23 21:19:57,101 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 21:19:57,420 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 21:19:57,420 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:57,420 DEBUG --- stdout --- 2024-06-23 21:19:57,420 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 21:19:57,420 DEBUG --- stderr --- 2024-06-23 21:19:57,420 DEBUG 2024-06-23 21:19:57,420 INFO 2024-06-23 21:19:57,421 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-23 21:19:57,421 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 21:19:58,808 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:58,808 DEBUG --- stdout --- 2024-06-23 21:19:58,808 DEBUG 2024-06-23 21:19:58,808 DEBUG --- stderr --- 2024-06-23 21:19:58,809 DEBUG Order has been successfully forwarded to overseer 2024-06-23 21:19:58 - INFO: Check if Gatling simulation started successfully 2024-06-23 21:19:58,809 INFO 2024-06-23 21:19:58,809 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-23 21:19:58,809 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 21:19:59,133 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 21:19:59,133 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:19:59,135 DEBUG --- stdout --- 2024-06-23 21:19:59,135 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 21:19:59,135 DEBUG --- stderr --- 2024-06-23 21:19:59,135 DEBUG 2024-06-23 21:19:59,136 INFO 2024-06-23 21:19:59,136 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-23 21:19:59,136 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 21:19:59,880 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 21:20:05,637 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-23 21:20:11,392 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-23 21:20:11,392 INFO [loop_until]: OK (rc = 0) 2024-06-23 21:20:11,394 DEBUG --- stdout --- 2024-06-23 21:20:11,394 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 22:20:00.891 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 22:20:04.716 [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"); ^ 22:20:04.717 [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"); ^ 22:20:06.248 [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-23 21:20:11,394 DEBUG --- stderr --- 2024-06-23 21:20:11,394 DEBUG ________________________________________________________________________________ [2024-06-23 21:20:11] 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 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 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 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-23 22:22:21] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 22:22:21,449 INFO 2024-06-23 22:22:21,450 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-23 22:22:21,450 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:21,782 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:21,782 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:21,782 DEBUG --- stdout --- 2024-06-23 22:22:21,782 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:21,783 DEBUG --- stderr --- 2024-06-23 22:22:21,783 DEBUG 2024-06-23 22:22:21,783 INFO 2024-06-23 22:22:21,783 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-23 22:22:21,783 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 22:22:22,591 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:22,591 DEBUG --- stdout --- 2024-06-23 22:22:22,591 DEBUG 2024-06-23 22:22:22,591 DEBUG --- stderr --- 2024-06-23 22:22:22,591 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 22:22:22] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 22:22:22 - 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-23 22:22:22,592 INFO 2024-06-23 22:22:22,592 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 22:22:22,592 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:22,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:22,913 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:22,913 DEBUG --- stdout --- 2024-06-23 22:22:22,913 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:22,913 DEBUG --- stderr --- 2024-06-23 22:22:22,913 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 22:22:22,913 INFO 2024-06-23 22:22:22,913 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-23 22:22:22,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:23,231 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:23,231 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:23,231 DEBUG --- stdout --- 2024-06-23 22:22:23,231 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:23,231 DEBUG --- stderr --- 2024-06-23 22:22:23,231 DEBUG 2024-06-23 22:22:23,232 INFO 2024-06-23 22:22:23,232 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-23 22:22:23,232 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 22:22:24,048 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 22:22:24,048 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:24,048 DEBUG --- stdout --- 2024-06-23 22:22:24,049 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-06-23 22:22:24,049 DEBUG --- stderr --- 2024-06-23 22:22:24,049 DEBUG 2024-06-23 22:22:24,049 INFO 2024-06-23 22:22:24,049 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-23 22:22:24,049 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:24,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:24,369 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:24,369 DEBUG --- stdout --- 2024-06-23 22:22:24,369 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:24,369 DEBUG --- stderr --- 2024-06-23 22:22:24,369 DEBUG 2024-06-23 22:22:24,370 INFO 2024-06-23 22:22:24,370 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-23 22:22:25,189 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 22:22:25,189 DEBUG --- stdout --- 2024-06-23 22:22:25,189 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-06-23 22:22:25,189 DEBUG --- stderr --- 2024-06-23 22:22:25,189 DEBUG 2024-06-23 22:22:25,189 INFO - Download file (size 4k) 2024-06-23 22:22:25,190 INFO 2024-06-23 22:22:25,190 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-23 22:22:25,190 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:25,513 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:25,513 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:25,514 DEBUG --- stdout --- 2024-06-23 22:22:25,514 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:25,514 DEBUG --- stderr --- 2024-06-23 22:22:25,514 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-23 22:22:25,515 INFO 2024-06-23 22:22:25,515 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 22:22:25,515 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:25,839 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:25,839 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:25,839 DEBUG --- stdout --- 2024-06-23 22:22:25,839 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:25,839 DEBUG --- stderr --- 2024-06-23 22:22:25,839 DEBUG 2024-06-23 22:22:25,839 INFO 2024-06-23 22:22:25,840 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-23 22:22:25,840 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 22:22:26,585 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:26,585 DEBUG --- stdout --- 2024-06-23 22:22:26,585 DEBUG tar: Removing leading `/' from member names 2024-06-23 22:22:26,585 DEBUG --- stderr --- 2024-06-23 22:22:26,585 DEBUG 2024-06-23 22:22:26,586 INFO 2024-06-23 22:22:26,586 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-23 22:22:26,586 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:26,925 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:26,926 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:26,926 DEBUG --- stdout --- 2024-06-23 22:22:26,926 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:26,926 DEBUG --- stderr --- 2024-06-23 22:22:26,926 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 22:22:26,926 INFO 2024-06-23 22:22:26,926 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-23 22:22:26,926 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:27,240 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:27,240 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:27,240 DEBUG --- stdout --- 2024-06-23 22:22:27,240 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:27,240 DEBUG --- stderr --- 2024-06-23 22:22:27,240 DEBUG 2024-06-23 22:22:27,241 INFO 2024-06-23 22:22:27,241 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-23 22:22:27,241 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 22:22:28,039 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 22:22:28,039 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:28,039 DEBUG --- stdout --- 2024-06-23 22:22:28,039 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-06-23 22:22:28,039 DEBUG --- stderr --- 2024-06-23 22:22:28,039 DEBUG 2024-06-23 22:22:28,040 INFO 2024-06-23 22:22:28,040 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-23 22:22:28,040 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:28,344 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:28,344 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:28,344 DEBUG --- stdout --- 2024-06-23 22:22:28,345 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:28,345 DEBUG --- stderr --- 2024-06-23 22:22:28,345 DEBUG 2024-06-23 22:22:28,345 INFO 2024-06-23 22:22:28,345 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-23 22:22:29,146 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 22:22:29,146 DEBUG --- stdout --- 2024-06-23 22:22:29,146 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-06-23 22:22:29,146 DEBUG --- stderr --- 2024-06-23 22:22:29,146 DEBUG 2024-06-23 22:22:29,146 INFO - Download file (size 0k) 2024-06-23 22:22:29,146 INFO 2024-06-23 22:22:29,146 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-23 22:22:29,147 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:29,453 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:29,453 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:29,453 DEBUG --- stdout --- 2024-06-23 22:22:29,453 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:29,453 DEBUG --- stderr --- 2024-06-23 22:22:29,453 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-23 22:22:29,454 INFO 2024-06-23 22:22:29,454 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-23 22:22:29,454 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:29,759 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:29,759 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:29,759 DEBUG --- stdout --- 2024-06-23 22:22:29,759 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:29,759 DEBUG --- stderr --- 2024-06-23 22:22:29,760 DEBUG 2024-06-23 22:22:29,760 INFO 2024-06-23 22:22:29,760 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-23 22:22:29,761 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 22:22:30,539 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:30,539 DEBUG --- stdout --- 2024-06-23 22:22:30,539 DEBUG tar: Removing leading `/' from member names 2024-06-23 22:22:30,539 DEBUG --- stderr --- 2024-06-23 22:22:30,539 DEBUG 2024-06-23 22:22:30,540 INFO 2024-06-23 22:22:30,540 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-23 22:22:30,540 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:30,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:30,864 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:30,864 DEBUG --- stdout --- 2024-06-23 22:22:30,864 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:30,864 DEBUG --- stderr --- 2024-06-23 22:22:30,864 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 22:22:30,864 INFO 2024-06-23 22:22:30,864 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 22:22:30,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:31,182 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:31,182 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:31,182 DEBUG --- stdout --- 2024-06-23 22:22:31,182 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:31,182 DEBUG --- stderr --- 2024-06-23 22:22:31,182 DEBUG 2024-06-23 22:22:31,183 INFO 2024-06-23 22:22:31,183 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-23 22:22:31,183 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 22:22:31,996 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 22:22:31,996 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:31,997 DEBUG --- stdout --- 2024-06-23 22:22:31,997 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-06-23 22:22:31,997 DEBUG --- stderr --- 2024-06-23 22:22:31,997 DEBUG 2024-06-23 22:22:31,997 INFO 2024-06-23 22:22:31,997 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-23 22:22:31,997 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:32,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:32,320 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:32,320 DEBUG --- stdout --- 2024-06-23 22:22:32,320 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:32,320 DEBUG --- stderr --- 2024-06-23 22:22:32,320 DEBUG 2024-06-23 22:22:32,320 INFO 2024-06-23 22:22:32,320 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-23 22:22:33,139 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 22:22:33,139 DEBUG --- stdout --- 2024-06-23 22:22:33,140 DEBUG 676 /results/orders/login-tree-10/stdout.txt 2024-06-23 22:22:33,140 DEBUG --- stderr --- 2024-06-23 22:22:33,140 DEBUG 2024-06-23 22:22:33,140 INFO - Download file (size 676k) 2024-06-23 22:22:33,140 INFO 2024-06-23 22:22:33,140 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-23 22:22:33,140 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:33,456 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:33,457 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:33,457 DEBUG --- stdout --- 2024-06-23 22:22:33,457 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:33,457 DEBUG --- stderr --- 2024-06-23 22:22:33,457 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-23 22:22:33,457 INFO 2024-06-23 22:22:33,457 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-23 22:22:33,457 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:33,827 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:33,827 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:33,827 DEBUG --- stdout --- 2024-06-23 22:22:33,827 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:33,827 DEBUG --- stderr --- 2024-06-23 22:22:33,827 DEBUG 2024-06-23 22:22:33,827 INFO 2024-06-23 22:22:33,828 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-23 22:22:33,828 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 22:22:34,907 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:34,907 DEBUG --- stdout --- 2024-06-23 22:22:34,907 DEBUG tar: Removing leading `/' from member names 2024-06-23 22:22:34,907 DEBUG --- stderr --- 2024-06-23 22:22:34,907 DEBUG ________________________________________________________________________________ [2024-06-23 22:22:34] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 22:22:34,908 INFO 2024-06-23 22:22:34,908 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-23 22:22:34,908 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:35,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:35,223 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:35,223 DEBUG --- stdout --- 2024-06-23 22:22:35,223 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:35,223 DEBUG --- stderr --- 2024-06-23 22:22:35,223 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 22:22:35,223 INFO 2024-06-23 22:22:35,224 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-23 22:22:35,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:35,547 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:35,547 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:35,548 DEBUG --- stdout --- 2024-06-23 22:22:35,548 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:35,548 DEBUG --- stderr --- 2024-06-23 22:22:35,548 DEBUG 2024-06-23 22:22:35,549 INFO 2024-06-23 22:22:35,549 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-23 22:22:35,549 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 22:22:36,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 22:22:36,379 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:36,379 DEBUG --- stdout --- 2024-06-23 22:22:36,379 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-06-23 22:22:36,379 DEBUG --- stderr --- 2024-06-23 22:22:36,379 DEBUG 2024-06-23 22:22:36,379 INFO 2024-06-23 22:22:36,380 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-23 22:22:36,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:36,707 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:36,707 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:36,707 DEBUG --- stdout --- 2024-06-23 22:22:36,707 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:36,707 DEBUG --- stderr --- 2024-06-23 22:22:36,707 DEBUG 2024-06-23 22:22:36,708 INFO 2024-06-23 22:22:36,708 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-23 22:22:37,609 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 22:22:37,610 DEBUG --- stdout --- 2024-06-23 22:22:37,610 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-06-23 22:22:37,610 DEBUG --- stderr --- 2024-06-23 22:22:37,610 DEBUG 2024-06-23 22:22:37,610 INFO - Download file (size 4k) 2024-06-23 22:22:37,610 INFO 2024-06-23 22:22:37,610 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-23 22:22:37,610 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:37,930 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:37,930 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:37,930 DEBUG --- stdout --- 2024-06-23 22:22:37,931 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:37,931 DEBUG --- stderr --- 2024-06-23 22:22:37,931 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-23 22:22:37,931 INFO 2024-06-23 22:22:37,931 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-23 22:22:37,931 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:38,248 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:38,248 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:38,248 DEBUG --- stdout --- 2024-06-23 22:22:38,248 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:38,248 DEBUG --- stderr --- 2024-06-23 22:22:38,248 DEBUG 2024-06-23 22:22:38,249 INFO 2024-06-23 22:22:38,249 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-23 22:22:38,249 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 22:22:38,993 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:38,993 DEBUG --- stdout --- 2024-06-23 22:22:38,993 DEBUG tar: Removing leading `/' from member names 2024-06-23 22:22:38,993 DEBUG --- stderr --- 2024-06-23 22:22:38,993 DEBUG 2024-06-23 22:22:38,994 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-23 22:22:38,994 DEBUG ----- output ----- 2024-06-23 22:22:38,994 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-23 22:22:38,994 INFO ________________________________________________________________________________ [2024-06-23 22:22:38] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 22:22:38,994 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-23 22:22:38,994 DEBUG ----- output ----- 2024-06-23 22:22:38,994 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 22:22:38,994 INFO ________________________________________________________________________________ [2024-06-23 22:22:38] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 22:22:38,996 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 10949 lines): 2024-06-23 22:22:38,996 DEBUG ----- output ----- 2024-06-23 22:22:38,996 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 22:22:38,996 INFO 22:20:00.891 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-23 22:22:38,996 INFO 22:20:04.716 [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-23 22:22:38,996 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 22:22:38,996 INFO ^ 2024-06-23 22:22:38,996 INFO 22:20:04.717 [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-23 22:22:38,996 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 22:22:38,997 INFO ^ 2024-06-23 22:22:38,997 INFO 22:20:06.248 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-23 22:22:38,997 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 22:22:38,997 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO ================================================================================ 2024-06-23 22:22:38,997 INFO 2024-06-23 22:20:13 5s elapsed 2024-06-23 22:22:38,997 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,997 INFO > Global (OK=8 KO=0 ) 2024-06-23 22:22:38,997 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-06-23 22:22:38,997 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,997 INFO [- ] 0% 2024-06-23 22:22:38,997 INFO waiting: 36104 / active: 1 / done: 4 2024-06-23 22:22:38,997 INFO ================================================================================ 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO ================================================================================ 2024-06-23 22:22:38,997 INFO 2024-06-23 22:20:18 10s elapsed 2024-06-23 22:22:38,997 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,997 INFO > Global (OK=32 KO=0 ) 2024-06-23 22:22:38,997 INFO > restLoginInitiate (OK=16 KO=0 ) 2024-06-23 22:22:38,997 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,997 INFO [- ] 0% 2024-06-23 22:22:38,997 INFO waiting: 36092 / active: 1 / done: 16 2024-06-23 22:22:38,997 INFO ================================================================================ 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,997 INFO ================================================================================ 2024-06-23 22:22:38,997 INFO 2024-06-23 22:20:23 15s elapsed 2024-06-23 22:22:38,997 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,997 INFO > Global (OK=74 KO=0 ) 2024-06-23 22:22:38,997 INFO > restLoginInitiate (OK=37 KO=0 ) 2024-06-23 22:22:38,997 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-06-23 22:22:38,997 INFO 2024-06-23 22:22:38,998 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,998 INFO [- ] 0% 2024-06-23 22:22:38,998 INFO waiting: 36071 / active: 1 / done: 37 2024-06-23 22:22:38,998 INFO ================================================================================ 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO ================================================================================ 2024-06-23 22:22:38,998 INFO 2024-06-23 22:20:28 20s elapsed 2024-06-23 22:22:38,998 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,998 INFO > Global (OK=131 KO=0 ) 2024-06-23 22:22:38,998 INFO > restLoginInitiate (OK=66 KO=0 ) 2024-06-23 22:22:38,998 INFO > restLoginUsernamePasswordCallback (OK=65 KO=0 ) 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,998 INFO [- ] 0% 2024-06-23 22:22:38,998 INFO waiting: 36042 / active: 2 / done: 65 2024-06-23 22:22:38,998 INFO ================================================================================ 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO ================================================================================ 2024-06-23 22:22:38,998 INFO 2024-06-23 22:20:33 25s elapsed 2024-06-23 22:22:38,998 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,998 INFO > Global (OK=207 KO=0 ) 2024-06-23 22:22:38,998 INFO > restLoginInitiate (OK=104 KO=0 ) 2024-06-23 22:22:38,998 INFO > restLoginUsernamePasswordCallback (OK=103 KO=0 ) 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,998 INFO [- ] 0% 2024-06-23 22:22:38,998 INFO waiting: 36004 / active: 2 / done: 103 2024-06-23 22:22:38,998 INFO ================================================================================ 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO 2024-06-23 22:22:38,998 INFO ================================================================================ 2024-06-23 22:22:38,999 INFO 2024-06-23 22:20:38 30s elapsed 2024-06-23 22:22:38,999 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,999 INFO > Global (OK=299 KO=0 ) 2024-06-23 22:22:38,999 INFO > restLoginInitiate (OK=150 KO=0 ) 2024-06-23 22:22:38,999 INFO > restLoginUsernamePasswordCallback (OK=149 KO=0 ) 2024-06-23 22:22:38,999 INFO 2024-06-23 22:22:38,999 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,999 INFO [- ] 0% 2024-06-23 22:22:38,999 INFO waiting: 35959 / active: 1 / done: 149 2024-06-23 22:22:38,999 INFO ================================================================================ 2024-06-23 22:22:38,999 INFO 2024-06-23 22:22:38,999 INFO 2024-06-23 22:22:38,999 INFO ================================================================================ 2024-06-23 22:22:38,999 INFO 2024-06-23 22:20:43 35s elapsed 2024-06-23 22:22:38,999 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:38,999 INFO > Global (OK=397 KO=0 ) 2024-06-23 22:22:38,999 INFO > restLoginInitiate (OK=199 KO=0 ) 2024-06-23 22:22:38,999 INFO > restLoginUsernamePasswordCallback (OK=198 KO=0 ) 2024-06-23 22:22:38,999 INFO 2024-06-23 22:22:38,999 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:38,999 INFO [- ] 0% 2024-06-23 22:22:38,999 INFO waiting: 35910 / active: 1 / done: 198 2024-06-23 22:22:38,999 INFO [...] 2024-06-23 22:22:39,002 INFO 2024-06-23 22:22:39,002 INFO 2024-06-23 22:22:39,002 INFO ================================================================================ 2024-06-23 22:22:39,002 INFO 2024-06-23 23:20:23 3615s elapsed 2024-06-23 22:22:39,002 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:39,002 INFO > Global (OK=71932 KO=1 ) 2024-06-23 22:22:39,002 INFO > restLoginInitiate (OK=35963 KO=1 ) 2024-06-23 22:22:39,002 INFO > restLoginUsernamePasswordCallback (OK=35961 KO=0 ) 2024-06-23 22:22:39,002 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-23 22:22:39,002 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 22:22:39,002 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-23 22:22:39,002 INFO 2024-06-23 22:22:39,002 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:39,002 INFO [#########################################################################-] 99% 2024-06-23 22:22:39,002 INFO waiting: 145 / active: 2 / done: 35962 2024-06-23 22:22:39,002 INFO ================================================================================ 2024-06-23 22:22:39,002 INFO 2024-06-23 22:22:39,002 INFO 2024-06-23 22:22:39,002 INFO ================================================================================ 2024-06-23 22:22:39,002 INFO 2024-06-23 23:20:28 3620s elapsed 2024-06-23 22:22:39,003 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:39,003 INFO > Global (OK=72027 KO=1 ) 2024-06-23 22:22:39,003 INFO > restLoginInitiate (OK=36010 KO=1 ) 2024-06-23 22:22:39,003 INFO > restLoginUsernamePasswordCallback (OK=36009 KO=0 ) 2024-06-23 22:22:39,003 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-23 22:22:39,003 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 22:22:39,003 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-23 22:22:39,003 INFO 2024-06-23 22:22:39,003 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:39,003 INFO [#########################################################################-] 99% 2024-06-23 22:22:39,003 INFO waiting: 97 / active: 2 / done: 36010 2024-06-23 22:22:39,003 INFO ================================================================================ 2024-06-23 22:22:39,003 INFO 2024-06-23 22:22:39,003 INFO 2024-06-23 22:22:39,003 INFO ================================================================================ 2024-06-23 22:22:39,003 INFO 2024-06-23 23:20:33 3625s elapsed 2024-06-23 22:22:39,003 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:39,003 INFO > Global (OK=72126 KO=1 ) 2024-06-23 22:22:39,003 INFO > restLoginInitiate (OK=36060 KO=1 ) 2024-06-23 22:22:39,003 INFO > restLoginUsernamePasswordCallback (OK=36058 KO=0 ) 2024-06-23 22:22:39,003 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-23 22:22:39,003 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 22:22:39,003 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-23 22:22:39,003 INFO 2024-06-23 22:22:39,003 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:39,003 INFO [#########################################################################-] 99% 2024-06-23 22:22:39,003 INFO waiting: 47 / active: 3 / done: 36059 2024-06-23 22:22:39,003 INFO ================================================================================ 2024-06-23 22:22:39,003 INFO 2024-06-23 22:22:39,003 INFO 2024-06-23 22:22:39,003 INFO ================================================================================ 2024-06-23 22:22:39,003 INFO 2024-06-23 23:20:38 3630s elapsed 2024-06-23 22:22:39,003 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:39,003 INFO > Global (OK=72220 KO=1 ) 2024-06-23 22:22:39,003 INFO > restLoginInitiate (OK=36107 KO=1 ) 2024-06-23 22:22:39,003 INFO > restLoginUsernamePasswordCallback (OK=36105 KO=0 ) 2024-06-23 22:22:39,003 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-23 22:22:39,003 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 22:22:39,003 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:39,004 INFO [#########################################################################-] 99% 2024-06-23 22:22:39,004 INFO waiting: 0 / active: 3 / done: 36106 2024-06-23 22:22:39,004 INFO ================================================================================ 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO ================================================================================ 2024-06-23 22:22:39,004 INFO 2024-06-23 23:20:39 3630s elapsed 2024-06-23 22:22:39,004 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 22:22:39,004 INFO > Global (OK=72224 KO=1 ) 2024-06-23 22:22:39,004 INFO > restLoginInitiate (OK=36108 KO=1 ) 2024-06-23 22:22:39,004 INFO > restLoginUsernamePasswordCallback (OK=36108 KO=0 ) 2024-06-23 22:22:39,004 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-23 22:22:39,004 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 22:22:39,004 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 22:22:39,004 INFO [##########################################################################]100% 2024-06-23 22:22:39,004 INFO waiting: 0 / active: 0 / done: 36109 2024-06-23 22:22:39,004 INFO ================================================================================ 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO ---------------------- 2024-06-23 22:22:39,004 INFO [23/Jun/2024 23:20:39] Create tar file login-tree-10.tar.gz 2024-06-23 22:22:39,004 INFO ---------------------- 2024-06-23 22:22:39,004 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 22:22:39,004 INFO RESULT_FOLDER=login-tree-10 2024-06-23 22:22:39,004 INFO SIMULATION_PATH=login-tree-10/loadtest-20240623212008315 2024-06-23 22:22:39,004 INFO PWD=/results/gatling/login-tree-10/loadtest-20240623212008315 2024-06-23 22:22:39,004 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-06-23 22:22:39,004 INFO /results/gatling 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO ---------------------- 2024-06-23 22:22:39,004 INFO [23/Jun/2024 23:20:39] Update /results/gatling//latest 2024-06-23 22:22:39,004 INFO ---------------------- 2024-06-23 22:22:39,004 INFO 2024-06-23 22:22:39,004 INFO ---------------------- 2024-06-23 22:22:39,004 INFO [23/Jun/2024 23:20:39] Update /results/gatling//latest.tar.gz 2024-06-23 22:22:39,005 INFO ---------------------- 2024-06-23 22:22:39,005 INFO ________________________________________________________________________________ [2024-06-23 22:22:39] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 22:22:39,005 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-23 22:22:39,005 DEBUG ----- output ----- 2024-06-23 22:22:39,005 DEBUG 2024-06-23 22:22:39,005 INFO ________________________________________________________________________________ [2024-06-23 22:22:39] 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-23 22:22:39] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 22:22:39] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped