--Task-- name: login-tree-100 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-10'] preceding_task: login-tree-10 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 100, '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 22:22:39] login-tree-100 pre : N/A ________________________________________________________________________________ 2024-06-23 22:22:39 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 22:22:39 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-100/stdout.txt Pod log : /results/orders/login-tree-100 2024-06-23 22:22:39,812 INFO 2024-06-23 22:22:39,813 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:39,813 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:40,135 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:40,135 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:40,135 DEBUG --- stdout --- 2024-06-23 22:22:40,135 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:40,135 DEBUG --- stderr --- 2024-06-23 22:22:40,135 DEBUG ________________________________________________________________________________ [2024-06-23 22:22:40] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 22:22:40,136 INFO 2024-06-23 22:22:40,136 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:40,136 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:40,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:40,503 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:40,503 DEBUG --- stdout --- 2024-06-23 22:22:40,503 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:40,503 DEBUG --- stderr --- 2024-06-23 22:22:40,503 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 22:22:40] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 22:22:40,504 INFO 2024-06-23 22:22:40,504 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:40,504 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:40,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:40,833 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:40,834 DEBUG --- stdout --- 2024-06-23 22:22:40,835 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:40,835 DEBUG --- stderr --- 2024-06-23 22:22:40,835 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json { "name": "login-tree-100", "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-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-100", "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=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-23 22:22:40] login-tree-100 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 22:22:40,837 INFO 2024-06-23 22:22:40,837 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 22:22:40,837 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 22:22:41,078 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 22:22:41,078 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:41,078 DEBUG --- stdout --- 2024-06-23 22:22:41,078 DEBUG WAITING 2024-06-23 22:22:41,078 DEBUG --- stderr --- 2024-06-23 22:22:41,078 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-23 22:22:41] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 22:22:41,079 INFO 2024-06-23 22:22:41,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-23 22:22:41,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:41,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:41,398 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:41,398 DEBUG --- stdout --- 2024-06-23 22:22:41,398 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:41,398 DEBUG --- stderr --- 2024-06-23 22:22:41,398 DEBUG 2024-06-23 22:22:41,398 INFO 2024-06-23 22:22:41,398 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/02_login-tree-100/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 22:22:41,399 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 22:22:42,758 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:42,758 DEBUG --- stdout --- 2024-06-23 22:22:42,758 DEBUG 2024-06-23 22:22:42,758 DEBUG --- stderr --- 2024-06-23 22:22:42,758 DEBUG Order has been successfully forwarded to overseer 2024-06-23 22:22:42 - INFO: Check if Gatling simulation started successfully 2024-06-23 22:22:42,759 INFO 2024-06-23 22:22:42,759 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 22:22:42,759 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 22:22:43,074 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 22:22:43,074 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:43,074 DEBUG --- stdout --- 2024-06-23 22:22:43,075 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 22:22:43,075 DEBUG --- stderr --- 2024-06-23 22:22:43,075 DEBUG 2024-06-23 22:22:43,075 INFO 2024-06-23 22:22:43,075 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-100/stdout.txt | grep "started..." 2024-06-23 22:22:43,075 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 22:22:43,821 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 22:22:49,556 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-23 22:22:55,312 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-23 22:22:55,312 INFO [loop_until]: OK (rc = 0) 2024-06-23 22:22:55,312 DEBUG --- stdout --- 2024-06-23 22:22:55,312 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:22:44.892 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:22:48.812 [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"); ^ 23:22:48.815 [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"); ^ 23:22:50.460 [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 22:22:55,312 DEBUG --- stderr --- 2024-06-23 22:22:55,312 DEBUG ________________________________________________________________________________ [2024-06-23 22:22:55] login-tree-100 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 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 23:25:05] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 23:25:05,459 INFO 2024-06-23 23:25:05,459 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 23:25:05,459 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:05,781 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:05,781 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:05,781 DEBUG --- stdout --- 2024-06-23 23:25:05,781 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:05,781 DEBUG --- stderr --- 2024-06-23 23:25:05,781 DEBUG 2024-06-23 23:25:05,782 INFO 2024-06-23 23:25:05,782 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 23:25:05,782 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 23:25:06,562 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:06,562 DEBUG --- stdout --- 2024-06-23 23:25:06,562 DEBUG 2024-06-23 23:25:06,562 DEBUG --- stderr --- 2024-06-23 23:25:06,562 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 23:25:06] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 23:25:06 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json 2024-06-23 23:25:06,563 INFO 2024-06-23 23:25:06,563 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 23:25:06,563 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:06,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:06,942 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:06,942 DEBUG --- stdout --- 2024-06-23 23:25:06,942 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:06,942 DEBUG --- stderr --- 2024-06-23 23:25:06,942 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 23:25:06,943 INFO 2024-06-23 23:25:06,943 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 23:25:06,943 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:07,278 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:07,278 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:07,278 DEBUG --- stdout --- 2024-06-23 23:25:07,279 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:07,279 DEBUG --- stderr --- 2024-06-23 23:25:07,279 DEBUG 2024-06-23 23:25:07,279 INFO 2024-06-23 23:25:07,279 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-100/rc.txt" ]; then echo "/results/orders/login-tree-100/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/rc.txt file found" 2024-06-23 23:25:07,279 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 23:25:08,112 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 23:25:08,112 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:08,112 DEBUG --- stdout --- 2024-06-23 23:25:08,112 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-06-23 23:25:08,112 DEBUG --- stderr --- 2024-06-23 23:25:08,112 DEBUG 2024-06-23 23:25:08,113 INFO 2024-06-23 23:25:08,113 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 23:25:08,113 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:08,434 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:08,435 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:08,435 DEBUG --- stdout --- 2024-06-23 23:25:08,435 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:08,435 DEBUG --- stderr --- 2024-06-23 23:25:08,435 DEBUG 2024-06-23 23:25:08,435 INFO 2024-06-23 23:25:08,435 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-100/rc.txt 2024-06-23 23:25:09,262 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 23:25:09,263 DEBUG --- stdout --- 2024-06-23 23:25:09,263 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-06-23 23:25:09,263 DEBUG --- stderr --- 2024-06-23 23:25:09,263 DEBUG 2024-06-23 23:25:09,263 INFO - Download file (size 4k) 2024-06-23 23:25:09,263 INFO 2024-06-23 23:25:09,263 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 23:25:09,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:09,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:09,569 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:09,569 DEBUG --- stdout --- 2024-06-23 23:25:09,569 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:09,569 DEBUG --- stderr --- 2024-06-23 23:25:09,569 DEBUG source : /results/orders/login-tree-100/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-06-23 23:25:09,569 INFO 2024-06-23 23:25:09,569 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 23:25:09,569 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:09,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:09,878 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:09,879 DEBUG --- stdout --- 2024-06-23 23:25:09,879 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:09,879 DEBUG --- stderr --- 2024-06-23 23:25:09,879 DEBUG 2024-06-23 23:25:09,879 INFO 2024-06-23 23:25:09,879 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-06-23 23:25:09,879 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 23:25:10,624 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:10,624 DEBUG --- stdout --- 2024-06-23 23:25:10,624 DEBUG tar: Removing leading `/' from member names 2024-06-23 23:25:10,624 DEBUG --- stderr --- 2024-06-23 23:25:10,624 DEBUG 2024-06-23 23:25:10,625 INFO 2024-06-23 23:25:10,625 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 23:25:10,625 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:10,955 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:10,956 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:10,956 DEBUG --- stdout --- 2024-06-23 23:25:10,956 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:10,956 DEBUG --- stderr --- 2024-06-23 23:25:10,956 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 23:25:10,956 INFO 2024-06-23 23:25:10,956 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 23:25:10,956 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:11,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:11,278 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:11,278 DEBUG --- stdout --- 2024-06-23 23:25:11,278 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:11,278 DEBUG --- stderr --- 2024-06-23 23:25:11,278 DEBUG 2024-06-23 23:25:11,279 INFO 2024-06-23 23:25:11,279 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-100/stderr.txt" ]; then echo "/results/orders/login-tree-100/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stderr.txt file found" 2024-06-23 23:25:11,279 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 23:25:12,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 23:25:12,101 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:12,101 DEBUG --- stdout --- 2024-06-23 23:25:12,101 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-06-23 23:25:12,101 DEBUG --- stderr --- 2024-06-23 23:25:12,101 DEBUG 2024-06-23 23:25:12,101 INFO 2024-06-23 23:25:12,102 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 23:25:12,102 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:12,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:12,430 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:12,430 DEBUG --- stdout --- 2024-06-23 23:25:12,430 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:12,430 DEBUG --- stderr --- 2024-06-23 23:25:12,430 DEBUG 2024-06-23 23:25:12,430 INFO 2024-06-23 23:25:12,430 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-100/stderr.txt 2024-06-23 23:25:13,241 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 23:25:13,241 DEBUG --- stdout --- 2024-06-23 23:25:13,241 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-06-23 23:25:13,241 DEBUG --- stderr --- 2024-06-23 23:25:13,241 DEBUG 2024-06-23 23:25:13,241 INFO - Download file (size 0k) 2024-06-23 23:25:13,242 INFO 2024-06-23 23:25:13,242 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 23:25:13,242 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:13,560 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:13,560 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:13,560 DEBUG --- stdout --- 2024-06-23 23:25:13,560 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:13,560 DEBUG --- stderr --- 2024-06-23 23:25:13,560 DEBUG source : /results/orders/login-tree-100/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-06-23 23:25:13,561 INFO 2024-06-23 23:25:13,561 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 23:25:13,561 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:13,884 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:13,884 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:13,884 DEBUG --- stdout --- 2024-06-23 23:25:13,884 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:13,884 DEBUG --- stderr --- 2024-06-23 23:25:13,885 DEBUG 2024-06-23 23:25:13,885 INFO 2024-06-23 23:25:13,885 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-06-23 23:25:13,885 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 23:25:14,671 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:14,672 DEBUG --- stdout --- 2024-06-23 23:25:14,672 DEBUG tar: Removing leading `/' from member names 2024-06-23 23:25:14,672 DEBUG --- stderr --- 2024-06-23 23:25:14,672 DEBUG 2024-06-23 23:25:14,672 INFO 2024-06-23 23:25:14,672 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 23:25:14,672 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:14,979 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:14,980 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:14,980 DEBUG --- stdout --- 2024-06-23 23:25:14,980 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:14,980 DEBUG --- stderr --- 2024-06-23 23:25:14,980 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 23:25:14,980 INFO 2024-06-23 23:25:14,980 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 23:25:14,980 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:15,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:15,282 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:15,282 DEBUG --- stdout --- 2024-06-23 23:25:15,282 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:15,282 DEBUG --- stderr --- 2024-06-23 23:25:15,282 DEBUG 2024-06-23 23:25:15,283 INFO 2024-06-23 23:25:15,283 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-100/stdout.txt" ]; then echo "/results/orders/login-tree-100/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stdout.txt file found" 2024-06-23 23:25:15,283 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 23:25:16,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 23:25:16,083 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:16,083 DEBUG --- stdout --- 2024-06-23 23:25:16,083 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-06-23 23:25:16,083 DEBUG --- stderr --- 2024-06-23 23:25:16,083 DEBUG 2024-06-23 23:25:16,083 INFO 2024-06-23 23:25:16,083 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 23:25:16,083 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:16,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:16,390 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:16,390 DEBUG --- stdout --- 2024-06-23 23:25:16,391 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:16,391 DEBUG --- stderr --- 2024-06-23 23:25:16,391 DEBUG 2024-06-23 23:25:16,391 INFO 2024-06-23 23:25:16,391 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-100/stdout.txt 2024-06-23 23:25:17,195 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 23:25:17,195 DEBUG --- stdout --- 2024-06-23 23:25:17,195 DEBUG 796 /results/orders/login-tree-100/stdout.txt 2024-06-23 23:25:17,195 DEBUG --- stderr --- 2024-06-23 23:25:17,195 DEBUG 2024-06-23 23:25:17,195 INFO - Download file (size 796k) 2024-06-23 23:25:17,196 INFO 2024-06-23 23:25:17,196 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 23:25:17,196 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:17,514 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:17,514 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:17,514 DEBUG --- stdout --- 2024-06-23 23:25:17,514 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:17,515 DEBUG --- stderr --- 2024-06-23 23:25:17,515 DEBUG source : /results/orders/login-tree-100/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-06-23 23:25:17,515 INFO 2024-06-23 23:25:17,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 23:25:17,515 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:17,839 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:17,839 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:17,839 DEBUG --- stdout --- 2024-06-23 23:25:17,840 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:17,840 DEBUG --- stderr --- 2024-06-23 23:25:17,840 DEBUG 2024-06-23 23:25:17,840 INFO 2024-06-23 23:25:17,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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-06-23 23:25:17,840 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 23:25:18,928 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:18,928 DEBUG --- stdout --- 2024-06-23 23:25:18,928 DEBUG tar: Removing leading `/' from member names 2024-06-23 23:25:18,928 DEBUG --- stderr --- 2024-06-23 23:25:18,928 DEBUG ________________________________________________________________________________ [2024-06-23 23:25:18] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 23:25:18,929 INFO 2024-06-23 23:25:18,929 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 23:25:18,929 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:19,247 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:19,247 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:19,247 DEBUG --- stdout --- 2024-06-23 23:25:19,247 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:19,247 DEBUG --- stderr --- 2024-06-23 23:25:19,247 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 23:25:19,248 INFO 2024-06-23 23:25:19,248 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 23:25:19,248 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:19,551 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:19,551 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:19,551 DEBUG --- stdout --- 2024-06-23 23:25:19,551 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:19,551 DEBUG --- stderr --- 2024-06-23 23:25:19,551 DEBUG 2024-06-23 23:25:19,552 INFO 2024-06-23 23:25:19,552 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-100/cmd.txt" ]; then echo "/results/orders/login-tree-100/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/cmd.txt file found" 2024-06-23 23:25:19,552 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 23:25:20,351 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 23:25:20,352 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:20,352 DEBUG --- stdout --- 2024-06-23 23:25:20,352 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-06-23 23:25:20,352 DEBUG --- stderr --- 2024-06-23 23:25:20,352 DEBUG 2024-06-23 23:25:20,352 INFO 2024-06-23 23:25:20,353 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 23:25:20,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:20,681 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:20,681 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:20,681 DEBUG --- stdout --- 2024-06-23 23:25:20,681 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:20,681 DEBUG --- stderr --- 2024-06-23 23:25:20,681 DEBUG 2024-06-23 23:25:20,681 INFO 2024-06-23 23:25:20,682 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-100/cmd.txt 2024-06-23 23:25:21,508 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 23:25:21,508 DEBUG --- stdout --- 2024-06-23 23:25:21,508 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-06-23 23:25:21,508 DEBUG --- stderr --- 2024-06-23 23:25:21,508 DEBUG 2024-06-23 23:25:21,508 INFO - Download file (size 4k) 2024-06-23 23:25:21,509 INFO 2024-06-23 23:25:21,509 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 23:25:21,509 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:21,816 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:21,816 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:21,816 DEBUG --- stdout --- 2024-06-23 23:25:21,816 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:21,816 DEBUG --- stderr --- 2024-06-23 23:25:21,816 DEBUG source : /results/orders/login-tree-100/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-06-23 23:25:21,816 INFO 2024-06-23 23:25:21,816 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 23:25:21,816 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 23:25:22,120 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 23:25:22,120 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:22,120 DEBUG --- stdout --- 2024-06-23 23:25:22,120 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 23:25:22,120 DEBUG --- stderr --- 2024-06-23 23:25:22,120 DEBUG 2024-06-23 23:25:22,121 INFO 2024-06-23 23:25:22,121 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-06-23 23:25:22,121 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 23:25:22,901 INFO [loop_until]: OK (rc = 0) 2024-06-23 23:25:22,901 DEBUG --- stdout --- 2024-06-23 23:25:22,901 DEBUG tar: Removing leading `/' from member names 2024-06-23 23:25:22,901 DEBUG --- stderr --- 2024-06-23 23:25:22,901 DEBUG 2024-06-23 23:25:22,902 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt is small (1 lines), printing all lines: 2024-06-23 23:25:22,902 DEBUG ----- output ----- 2024-06-23 23:25:22,902 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 23:25:22,902 INFO ________________________________________________________________________________ [2024-06-23 23:25:22] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 23:25:22,902 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt is small (1 lines), printing all lines: 2024-06-23 23:25:22,902 DEBUG ----- output ----- 2024-06-23 23:25:22,902 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 23:25:22,903 INFO ________________________________________________________________________________ [2024-06-23 23:25:22] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 23:25:22,905 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/02_login-tree-100/stdout.txt is 12194 lines): 2024-06-23 23:25:22,905 DEBUG ----- output ----- 2024-06-23 23:25:22,905 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 23:25:22,905 INFO 23:22:44.892 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-23 23:25:22,905 INFO 23:22:48.812 [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 23:25:22,905 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 23:25:22,905 INFO ^ 2024-06-23 23:25:22,905 INFO 23:22:48.815 [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 23:25:22,905 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 23:25:22,905 INFO ^ 2024-06-23 23:25:22,905 INFO 23:22:50.460 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-23 23:25:22,905 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 23:25:22,905 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 23:25:22,905 INFO 2024-06-23 23:25:22,905 INFO ================================================================================ 2024-06-23 23:25:22,905 INFO 2024-06-23 23:22:57 5s elapsed 2024-06-23 23:25:22,905 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,905 INFO > Global (OK=80 KO=0 ) 2024-06-23 23:25:22,905 INFO > restLoginInitiate (OK=41 KO=0 ) 2024-06-23 23:25:22,905 INFO > restLoginUsernamePasswordCallback (OK=39 KO=0 ) 2024-06-23 23:25:22,905 INFO 2024-06-23 23:25:22,905 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,905 INFO [- ] 0% 2024-06-23 23:25:22,905 INFO waiting: 362470 / active: 3 / done: 39 2024-06-23 23:25:22,905 INFO ================================================================================ 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO ================================================================================ 2024-06-23 23:25:22,906 INFO 2024-06-23 23:23:02 10s elapsed 2024-06-23 23:25:22,906 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,906 INFO > Global (OK=326 KO=0 ) 2024-06-23 23:25:22,906 INFO > restLoginInitiate (OK=165 KO=0 ) 2024-06-23 23:25:22,906 INFO > restLoginUsernamePasswordCallback (OK=161 KO=0 ) 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,906 INFO [- ] 0% 2024-06-23 23:25:22,906 INFO waiting: 362345 / active: 6 / done: 161 2024-06-23 23:25:22,906 INFO ================================================================================ 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO ================================================================================ 2024-06-23 23:25:22,906 INFO 2024-06-23 23:23:07 15s elapsed 2024-06-23 23:25:22,906 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,906 INFO > Global (OK=741 KO=0 ) 2024-06-23 23:25:22,906 INFO > restLoginInitiate (OK=374 KO=0 ) 2024-06-23 23:25:22,906 INFO > restLoginUsernamePasswordCallback (OK=367 KO=0 ) 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,906 INFO [- ] 0% 2024-06-23 23:25:22,906 INFO waiting: 362136 / active: 9 / done: 367 2024-06-23 23:25:22,906 INFO ================================================================================ 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO 2024-06-23 23:25:22,906 INFO ================================================================================ 2024-06-23 23:25:22,906 INFO 2024-06-23 23:23:12 20s elapsed 2024-06-23 23:25:22,906 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,906 INFO > Global (OK=1321 KO=0 ) 2024-06-23 23:25:22,906 INFO > restLoginInitiate (OK=664 KO=0 ) 2024-06-23 23:25:22,906 INFO > restLoginUsernamePasswordCallback (OK=656 KO=0 ) 2024-06-23 23:25:22,906 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,907 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,907 INFO [- ] 0% 2024-06-23 23:25:22,907 INFO waiting: 361845 / active: 11 / done: 656 2024-06-23 23:25:22,907 INFO ================================================================================ 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,907 INFO ================================================================================ 2024-06-23 23:25:22,907 INFO 2024-06-23 23:23:17 25s elapsed 2024-06-23 23:25:22,907 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,907 INFO > Global (OK=2068 KO=0 ) 2024-06-23 23:25:22,907 INFO > restLoginInitiate (OK=1038 KO=0 ) 2024-06-23 23:25:22,907 INFO > restLoginUsernamePasswordCallback (OK=1029 KO=0 ) 2024-06-23 23:25:22,907 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,907 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,907 INFO [- ] 0% 2024-06-23 23:25:22,907 INFO waiting: 361470 / active: 13 / done: 1029 2024-06-23 23:25:22,907 INFO ================================================================================ 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,907 INFO ================================================================================ 2024-06-23 23:25:22,907 INFO 2024-06-23 23:23:22 30s elapsed 2024-06-23 23:25:22,907 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,907 INFO > Global (OK=2981 KO=0 ) 2024-06-23 23:25:22,907 INFO > restLoginInitiate (OK=1496 KO=0 ) 2024-06-23 23:25:22,907 INFO > restLoginUsernamePasswordCallback (OK=1484 KO=0 ) 2024-06-23 23:25:22,907 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 23:25:22,907 INFO 2024-06-23 23:25:22,908 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,908 INFO [- ] 0% 2024-06-23 23:25:22,908 INFO waiting: 361011 / active: 17 / done: 1484 2024-06-23 23:25:22,908 INFO ================================================================================ 2024-06-23 23:25:22,908 INFO 2024-06-23 23:25:22,908 INFO 2024-06-23 23:25:22,908 INFO ================================================================================ 2024-06-23 23:25:22,908 INFO 2024-06-23 23:23:27 35s elapsed 2024-06-23 23:25:22,908 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,908 INFO > Global (OK=4029 KO=0 ) 2024-06-23 23:25:22,908 INFO > restLoginInitiate (OK=2020 KO=0 ) 2024-06-23 23:25:22,908 INFO > restLoginUsernamePasswordCallback (OK=2008 KO=0 ) 2024-06-23 23:25:22,908 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 23:25:22,908 INFO [...] 2024-06-23 23:25:22,911 INFO 2024-06-23 23:25:22,911 INFO 2024-06-23 23:25:22,911 INFO ================================================================================ 2024-06-23 23:25:22,911 INFO 2024-06-24 00:23:07 3615s elapsed 2024-06-23 23:25:22,911 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,911 INFO > Global (OK=729727 KO=15 ) 2024-06-23 23:25:22,911 INFO > restLoginInitiate (OK=360999 KO=8 ) 2024-06-23 23:25:22,911 INFO > restLoginUsernamePasswordCallback (OK=360976 KO=7 ) 2024-06-23 23:25:22,911 INFO > restloginProgressiveProfileCallback (OK=7752 KO=0 ) 2024-06-23 23:25:22,911 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 23:25:22,911 INFO > status.find.is(200), but actually found 502 15 (100.0%) 2024-06-23 23:25:22,911 INFO 2024-06-23 23:25:22,911 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,911 INFO [#########################################################################-] 99% 2024-06-23 23:25:22,911 INFO waiting: 1502 / active: 19 / done: 360991 2024-06-23 23:25:22,911 INFO ================================================================================ 2024-06-23 23:25:22,911 INFO 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO ================================================================================ 2024-06-23 23:25:22,912 INFO 2024-06-24 00:23:12 3620s elapsed 2024-06-23 23:25:22,912 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,912 INFO > Global (OK=730704 KO=15 ) 2024-06-23 23:25:22,912 INFO > restLoginInitiate (OK=361470 KO=8 ) 2024-06-23 23:25:22,912 INFO > restLoginUsernamePasswordCallback (OK=361449 KO=7 ) 2024-06-23 23:25:22,912 INFO > restloginProgressiveProfileCallback (OK=7785 KO=0 ) 2024-06-23 23:25:22,912 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 23:25:22,912 INFO > status.find.is(200), but actually found 502 15 (100.0%) 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,912 INFO [#########################################################################-] 99% 2024-06-23 23:25:22,912 INFO waiting: 1024 / active: 24 / done: 361464 2024-06-23 23:25:22,912 INFO ================================================================================ 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO ================================================================================ 2024-06-23 23:25:22,912 INFO 2024-06-24 00:23:17 3625s elapsed 2024-06-23 23:25:22,912 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,912 INFO > Global (OK=731746 KO=15 ) 2024-06-23 23:25:22,912 INFO > restLoginInitiate (OK=361975 KO=8 ) 2024-06-23 23:25:22,912 INFO > restLoginUsernamePasswordCallback (OK=361958 KO=7 ) 2024-06-23 23:25:22,912 INFO > restloginProgressiveProfileCallback (OK=7813 KO=0 ) 2024-06-23 23:25:22,912 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 23:25:22,912 INFO > status.find.is(200), but actually found 502 15 (100.0%) 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,912 INFO [#########################################################################-] 99% 2024-06-23 23:25:22,912 INFO waiting: 522 / active: 17 / done: 361973 2024-06-23 23:25:22,912 INFO ================================================================================ 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO 2024-06-23 23:25:22,912 INFO ================================================================================ 2024-06-23 23:25:22,912 INFO 2024-06-24 00:23:22 3630s elapsed 2024-06-23 23:25:22,912 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,912 INFO > Global (OK=732821 KO=15 ) 2024-06-23 23:25:22,912 INFO > restLoginInitiate (OK=362500 KO=8 ) 2024-06-23 23:25:22,912 INFO > restLoginUsernamePasswordCallback (OK=362475 KO=7 ) 2024-06-23 23:25:22,913 INFO > restloginProgressiveProfileCallback (OK=7846 KO=0 ) 2024-06-23 23:25:22,913 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 23:25:22,913 INFO > status.find.is(200), but actually found 502 15 (100.0%) 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,913 INFO [#########################################################################-] 99% 2024-06-23 23:25:22,913 INFO waiting: 0 / active: 22 / done: 362490 2024-06-23 23:25:22,913 INFO ================================================================================ 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO ================================================================================ 2024-06-23 23:25:22,913 INFO 2024-06-24 00:23:23 3630s elapsed 2024-06-23 23:25:22,913 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 23:25:22,913 INFO > Global (OK=732849 KO=15 ) 2024-06-23 23:25:22,913 INFO > restLoginInitiate (OK=362504 KO=8 ) 2024-06-23 23:25:22,913 INFO > restLoginUsernamePasswordCallback (OK=362497 KO=7 ) 2024-06-23 23:25:22,913 INFO > restloginProgressiveProfileCallback (OK=7848 KO=0 ) 2024-06-23 23:25:22,913 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 23:25:22,913 INFO > status.find.is(200), but actually found 502 15 (100.0%) 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 23:25:22,913 INFO [##########################################################################]100% 2024-06-23 23:25:22,913 INFO waiting: 0 / active: 0 / done: 362512 2024-06-23 23:25:22,913 INFO ================================================================================ 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO ---------------------- 2024-06-23 23:25:22,913 INFO [24/Jun/2024 00:23:23] Create tar file login-tree-100.tar.gz 2024-06-23 23:25:22,913 INFO ---------------------- 2024-06-23 23:25:22,913 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 23:25:22,913 INFO RESULT_FOLDER=login-tree-100 2024-06-23 23:25:22,913 INFO SIMULATION_PATH=login-tree-100/loadtest-20240623222252519 2024-06-23 23:25:22,913 INFO PWD=/results/gatling/login-tree-100/loadtest-20240623222252519 2024-06-23 23:25:22,913 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-06-23 23:25:22,913 INFO /results/gatling 2024-06-23 23:25:22,913 INFO 2024-06-23 23:25:22,913 INFO ---------------------- 2024-06-23 23:25:22,913 INFO [24/Jun/2024 00:23:23] Update /results/gatling//latest 2024-06-23 23:25:22,913 INFO ---------------------- 2024-06-23 23:25:22,914 INFO 2024-06-23 23:25:22,914 INFO ---------------------- 2024-06-23 23:25:22,914 INFO [24/Jun/2024 00:23:23] Update /results/gatling//latest.tar.gz 2024-06-23 23:25:22,914 INFO ---------------------- 2024-06-23 23:25:22,914 INFO ________________________________________________________________________________ [2024-06-23 23:25:22] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 23:25:22,914 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt is small (0 lines), printing all lines: 2024-06-23 23:25:22,914 DEBUG ----- output ----- 2024-06-23 23:25:22,914 DEBUG 2024-06-23 23:25:22,914 INFO ________________________________________________________________________________ [2024-06-23 23:25:22] login-tree-100 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 23:25:22] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 23:25:22] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped