--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-22 19:14:01] login-tree-100 pre : N/A ________________________________________________________________________________ 2024-06-22 19:14:01 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 19:14:01 - 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-22 19:14:01,209 INFO 2024-06-22 19:14:01,210 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:14:01,210 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:14:01,511 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:14:01,511 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:01,511 DEBUG --- stdout --- 2024-06-22 19:14:01,511 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:14:01,511 DEBUG --- stderr --- 2024-06-22 19:14:01,511 DEBUG ________________________________________________________________________________ [2024-06-22 19:14:01] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 19:14:01,512 INFO 2024-06-22 19:14:01,512 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:14:01,512 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:14:01,817 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:14:01,817 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:01,817 DEBUG --- stdout --- 2024-06-22 19:14:01,817 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:14:01,817 DEBUG --- stderr --- 2024-06-22 19:14:01,817 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-22 19:14:01] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 19:14:01,818 INFO 2024-06-22 19:14:01,818 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:14:01,818 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:14:02,131 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:14:02,131 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:02,131 DEBUG --- stdout --- 2024-06-22 19:14:02,131 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:14:02,131 DEBUG --- stderr --- 2024-06-22 19:14:02,131 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-22 19:14:02] 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-22 19:14:02,134 INFO 2024-06-22 19:14:02,134 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 19:14:02,134 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 19:14:02,374 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 19:14:02,374 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:02,374 DEBUG --- stdout --- 2024-06-22 19:14:02,374 DEBUG WAITING 2024-06-22 19:14:02,374 DEBUG --- stderr --- 2024-06-22 19:14:02,375 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-22 19:14:02] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 19:14:02,375 INFO 2024-06-22 19:14:02,375 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 19:14:02,375 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:14:02,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:14:02,689 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:02,689 DEBUG --- stdout --- 2024-06-22 19:14:02,689 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:14:02,689 DEBUG --- stderr --- 2024-06-22 19:14:02,689 DEBUG 2024-06-22 19:14:02,689 INFO 2024-06-22 19:14:02,689 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-22 19:14:02,689 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 19:14:04,048 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:04,049 DEBUG --- stdout --- 2024-06-22 19:14:04,049 DEBUG 2024-06-22 19:14:04,049 DEBUG --- stderr --- 2024-06-22 19:14:04,049 DEBUG Order has been successfully forwarded to overseer 2024-06-22 19:14:04 - INFO: Check if Gatling simulation started successfully 2024-06-22 19:14:04,049 INFO 2024-06-22 19:14:04,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-22 19:14:04,049 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 19:14:04,356 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 19:14:04,356 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:04,356 DEBUG --- stdout --- 2024-06-22 19:14:04,356 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 19:14:04,356 DEBUG --- stderr --- 2024-06-22 19:14:04,356 DEBUG 2024-06-22 19:14:04,357 INFO 2024-06-22 19:14:04,357 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-22 19:14:04,357 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 19:14:05,097 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 19:14:10,863 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-22 19:14:16,609 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-22 19:14:16,610 INFO [loop_until]: OK (rc = 0) 2024-06-22 19:14:16,610 DEBUG --- stdout --- 2024-06-22 19:14:16,610 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 20:14:06.406 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 20:14:11.480 [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"); ^ 20:14:11.482 [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"); ^ 20:14:13.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-22 19:14:16,610 DEBUG --- stderr --- 2024-06-22 19:14:16,610 DEBUG ________________________________________________________________________________ [2024-06-22 19:14:16] 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-22 20:16:26] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 20:16:26,729 INFO 2024-06-22 20:16:26,729 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:26,730 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:27,072 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:27,073 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:27,073 DEBUG --- stdout --- 2024-06-22 20:16:27,073 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:27,073 DEBUG --- stderr --- 2024-06-22 20:16:27,073 DEBUG 2024-06-22 20:16:27,073 INFO 2024-06-22 20:16:27,073 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-22 20:16:27,073 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 20:16:27,872 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:27,872 DEBUG --- stdout --- 2024-06-22 20:16:27,872 DEBUG 2024-06-22 20:16:27,872 DEBUG --- stderr --- 2024-06-22 20:16:27,872 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 20:16:27] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 20:16:27 - 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-22 20:16:27,874 INFO 2024-06-22 20:16:27,874 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:27,874 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:28,196 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:28,197 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:28,197 DEBUG --- stdout --- 2024-06-22 20:16:28,197 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:28,197 DEBUG --- stderr --- 2024-06-22 20:16:28,197 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 20:16:28,197 INFO 2024-06-22 20:16:28,197 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:28,197 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:28,512 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:28,512 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:28,512 DEBUG --- stdout --- 2024-06-22 20:16:28,512 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:28,512 DEBUG --- stderr --- 2024-06-22 20:16:28,512 DEBUG 2024-06-22 20:16:28,513 INFO 2024-06-22 20:16:28,513 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-22 20:16:28,513 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 20:16:29,330 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 20:16:29,330 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:29,330 DEBUG --- stdout --- 2024-06-22 20:16:29,331 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-06-22 20:16:29,331 DEBUG --- stderr --- 2024-06-22 20:16:29,331 DEBUG 2024-06-22 20:16:29,331 INFO 2024-06-22 20:16:29,331 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:29,331 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:29,646 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:29,646 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:29,646 DEBUG --- stdout --- 2024-06-22 20:16:29,646 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:29,647 DEBUG --- stderr --- 2024-06-22 20:16:29,647 DEBUG 2024-06-22 20:16:29,647 INFO 2024-06-22 20:16:29,647 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-22 20:16:30,468 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 20:16:30,468 DEBUG --- stdout --- 2024-06-22 20:16:30,468 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-06-22 20:16:30,468 DEBUG --- stderr --- 2024-06-22 20:16:30,468 DEBUG 2024-06-22 20:16:30,468 INFO - Download file (size 4k) 2024-06-22 20:16:30,469 INFO 2024-06-22 20:16:30,469 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:30,469 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:30,795 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:30,795 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:30,795 DEBUG --- stdout --- 2024-06-22 20:16:30,795 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:30,795 DEBUG --- stderr --- 2024-06-22 20:16:30,795 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-22 20:16:30,796 INFO 2024-06-22 20:16:30,796 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:30,796 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:31,114 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:31,114 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:31,114 DEBUG --- stdout --- 2024-06-22 20:16:31,114 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:31,114 DEBUG --- stderr --- 2024-06-22 20:16:31,114 DEBUG 2024-06-22 20:16:31,115 INFO 2024-06-22 20:16:31,115 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-22 20:16:31,115 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 20:16:31,911 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:31,911 DEBUG --- stdout --- 2024-06-22 20:16:31,911 DEBUG tar: Removing leading `/' from member names 2024-06-22 20:16:31,911 DEBUG --- stderr --- 2024-06-22 20:16:31,911 DEBUG 2024-06-22 20:16:31,912 INFO 2024-06-22 20:16:31,912 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:31,912 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:32,233 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:32,233 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:32,233 DEBUG --- stdout --- 2024-06-22 20:16:32,233 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:32,233 DEBUG --- stderr --- 2024-06-22 20:16:32,233 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 20:16:32,234 INFO 2024-06-22 20:16:32,234 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:32,234 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:32,552 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:32,552 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:32,552 DEBUG --- stdout --- 2024-06-22 20:16:32,552 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:32,552 DEBUG --- stderr --- 2024-06-22 20:16:32,552 DEBUG 2024-06-22 20:16:32,553 INFO 2024-06-22 20:16:32,553 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-22 20:16:32,553 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 20:16:33,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 20:16:33,369 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:33,369 DEBUG --- stdout --- 2024-06-22 20:16:33,369 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-06-22 20:16:33,369 DEBUG --- stderr --- 2024-06-22 20:16:33,369 DEBUG 2024-06-22 20:16:33,369 INFO 2024-06-22 20:16:33,369 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:33,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:33,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:33,689 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:33,689 DEBUG --- stdout --- 2024-06-22 20:16:33,689 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:33,689 DEBUG --- stderr --- 2024-06-22 20:16:33,689 DEBUG 2024-06-22 20:16:33,689 INFO 2024-06-22 20:16:33,689 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-22 20:16:34,503 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 20:16:34,503 DEBUG --- stdout --- 2024-06-22 20:16:34,504 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-06-22 20:16:34,504 DEBUG --- stderr --- 2024-06-22 20:16:34,504 DEBUG 2024-06-22 20:16:34,504 INFO - Download file (size 0k) 2024-06-22 20:16:34,504 INFO 2024-06-22 20:16:34,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-22 20:16:34,504 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:34,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:34,845 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:34,845 DEBUG --- stdout --- 2024-06-22 20:16:34,845 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:34,845 DEBUG --- stderr --- 2024-06-22 20:16:34,845 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-22 20:16:34,845 INFO 2024-06-22 20:16:34,845 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:34,845 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:35,166 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:35,166 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:35,166 DEBUG --- stdout --- 2024-06-22 20:16:35,166 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:35,166 DEBUG --- stderr --- 2024-06-22 20:16:35,166 DEBUG 2024-06-22 20:16:35,167 INFO 2024-06-22 20:16:35,167 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-22 20:16:35,167 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 20:16:35,905 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:35,905 DEBUG --- stdout --- 2024-06-22 20:16:35,905 DEBUG tar: Removing leading `/' from member names 2024-06-22 20:16:35,905 DEBUG --- stderr --- 2024-06-22 20:16:35,905 DEBUG 2024-06-22 20:16:35,906 INFO 2024-06-22 20:16:35,906 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:35,906 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:36,226 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:36,226 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:36,226 DEBUG --- stdout --- 2024-06-22 20:16:36,227 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:36,227 DEBUG --- stderr --- 2024-06-22 20:16:36,227 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 20:16:36,227 INFO 2024-06-22 20:16:36,227 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:36,227 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:36,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:36,542 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:36,542 DEBUG --- stdout --- 2024-06-22 20:16:36,542 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:36,542 DEBUG --- stderr --- 2024-06-22 20:16:36,542 DEBUG 2024-06-22 20:16:36,543 INFO 2024-06-22 20:16:36,543 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-22 20:16:36,543 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 20:16:37,383 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 20:16:37,384 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:37,385 DEBUG --- stdout --- 2024-06-22 20:16:37,385 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-06-22 20:16:37,385 DEBUG --- stderr --- 2024-06-22 20:16:37,385 DEBUG 2024-06-22 20:16:37,386 INFO 2024-06-22 20:16:37,386 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:37,386 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:37,714 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:37,714 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:37,714 DEBUG --- stdout --- 2024-06-22 20:16:37,714 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:37,714 DEBUG --- stderr --- 2024-06-22 20:16:37,715 DEBUG 2024-06-22 20:16:37,715 INFO 2024-06-22 20:16:37,715 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-22 20:16:38,542 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 20:16:38,542 DEBUG --- stdout --- 2024-06-22 20:16:38,542 DEBUG 828 /results/orders/login-tree-100/stdout.txt 2024-06-22 20:16:38,542 DEBUG --- stderr --- 2024-06-22 20:16:38,542 DEBUG 2024-06-22 20:16:38,542 INFO - Download file (size 828k) 2024-06-22 20:16:38,543 INFO 2024-06-22 20:16:38,543 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:38,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:38,855 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:38,856 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:38,856 DEBUG --- stdout --- 2024-06-22 20:16:38,856 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:38,856 DEBUG --- stderr --- 2024-06-22 20:16:38,856 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-22 20:16:38,856 INFO 2024-06-22 20:16:38,856 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:38,856 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:39,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:39,174 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:39,174 DEBUG --- stdout --- 2024-06-22 20:16:39,174 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:39,174 DEBUG --- stderr --- 2024-06-22 20:16:39,174 DEBUG 2024-06-22 20:16:39,175 INFO 2024-06-22 20:16:39,175 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-22 20:16:39,175 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 20:16:40,248 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:40,248 DEBUG --- stdout --- 2024-06-22 20:16:40,249 DEBUG tar: Removing leading `/' from member names 2024-06-22 20:16:40,249 DEBUG --- stderr --- 2024-06-22 20:16:40,249 DEBUG ________________________________________________________________________________ [2024-06-22 20:16:40] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 20:16:40,250 INFO 2024-06-22 20:16:40,250 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:40,250 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:40,569 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:40,569 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:40,569 DEBUG --- stdout --- 2024-06-22 20:16:40,569 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:40,569 DEBUG --- stderr --- 2024-06-22 20:16:40,569 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 20:16:40,570 INFO 2024-06-22 20:16:40,570 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:40,570 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:40,901 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:40,901 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:40,901 DEBUG --- stdout --- 2024-06-22 20:16:40,901 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:40,901 DEBUG --- stderr --- 2024-06-22 20:16:40,901 DEBUG 2024-06-22 20:16:40,902 INFO 2024-06-22 20:16:40,902 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-22 20:16:40,902 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 20:16:41,738 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 20:16:41,738 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:41,738 DEBUG --- stdout --- 2024-06-22 20:16:41,738 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-06-22 20:16:41,738 DEBUG --- stderr --- 2024-06-22 20:16:41,738 DEBUG 2024-06-22 20:16:41,738 INFO 2024-06-22 20:16:41,738 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:41,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:42,044 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:42,045 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:42,045 DEBUG --- stdout --- 2024-06-22 20:16:42,045 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:42,045 DEBUG --- stderr --- 2024-06-22 20:16:42,045 DEBUG 2024-06-22 20:16:42,045 INFO 2024-06-22 20:16:42,045 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-22 20:16:42,859 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 20:16:42,859 DEBUG --- stdout --- 2024-06-22 20:16:42,859 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-06-22 20:16:42,859 DEBUG --- stderr --- 2024-06-22 20:16:42,859 DEBUG 2024-06-22 20:16:42,860 INFO - Download file (size 4k) 2024-06-22 20:16:42,860 INFO 2024-06-22 20:16:42,860 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:42,860 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:43,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:43,174 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:43,174 DEBUG --- stdout --- 2024-06-22 20:16:43,174 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:43,174 DEBUG --- stderr --- 2024-06-22 20:16:43,174 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-22 20:16:43,174 INFO 2024-06-22 20:16:43,175 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 20:16:43,175 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 20:16:43,481 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 20:16:43,481 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:43,481 DEBUG --- stdout --- 2024-06-22 20:16:43,481 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 20:16:43,481 DEBUG --- stderr --- 2024-06-22 20:16:43,481 DEBUG 2024-06-22 20:16:43,481 INFO 2024-06-22 20:16:43,482 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-22 20:16:43,482 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 20:16:44,210 INFO [loop_until]: OK (rc = 0) 2024-06-22 20:16:44,210 DEBUG --- stdout --- 2024-06-22 20:16:44,211 DEBUG tar: Removing leading `/' from member names 2024-06-22 20:16:44,211 DEBUG --- stderr --- 2024-06-22 20:16:44,211 DEBUG 2024-06-22 20:16:44,211 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-22 20:16:44,211 DEBUG ----- output ----- 2024-06-22 20:16:44,211 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-22 20:16:44,211 INFO ________________________________________________________________________________ [2024-06-22 20:16:44] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 20:16:44,212 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-22 20:16:44,212 DEBUG ----- output ----- 2024-06-22 20:16:44,212 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 20:16:44,212 INFO ________________________________________________________________________________ [2024-06-22 20:16:44] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 20:16:44,214 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 12347 lines): 2024-06-22 20:16:44,214 DEBUG ----- output ----- 2024-06-22 20:16:44,214 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 20:16:44,214 INFO 20:14:06.406 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-22 20:16:44,214 INFO 20:14:11.480 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-22 20:16:44,214 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 20:16:44,214 INFO ^ 2024-06-22 20:16:44,214 INFO 20:14:11.482 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-22 20:16:44,215 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 20:16:44,215 INFO ^ 2024-06-22 20:16:44,215 INFO 20:14:13.248 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-22 20:16:44,215 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 20:16:44,215 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO ================================================================================ 2024-06-22 20:16:44,215 INFO 2024-06-22 20:14:20 5s elapsed 2024-06-22 20:16:44,215 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,215 INFO > Global (OK=80 KO=0 ) 2024-06-22 20:16:44,215 INFO > restLoginInitiate (OK=41 KO=0 ) 2024-06-22 20:16:44,215 INFO > restLoginUsernamePasswordCallback (OK=39 KO=0 ) 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,215 INFO [- ] 0% 2024-06-22 20:16:44,215 INFO waiting: 361812 / active: 3 / done: 39 2024-06-22 20:16:44,215 INFO ================================================================================ 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO ================================================================================ 2024-06-22 20:16:44,215 INFO 2024-06-22 20:14:25 10s elapsed 2024-06-22 20:16:44,215 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,215 INFO > Global (OK=327 KO=0 ) 2024-06-22 20:16:44,215 INFO > restLoginInitiate (OK=165 KO=0 ) 2024-06-22 20:16:44,215 INFO > restLoginUsernamePasswordCallback (OK=162 KO=0 ) 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,215 INFO [- ] 0% 2024-06-22 20:16:44,215 INFO waiting: 361687 / active: 5 / done: 162 2024-06-22 20:16:44,215 INFO ================================================================================ 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,215 INFO ================================================================================ 2024-06-22 20:16:44,215 INFO 2024-06-22 20:14:30 15s elapsed 2024-06-22 20:16:44,215 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,215 INFO > Global (OK=743 KO=0 ) 2024-06-22 20:16:44,215 INFO > restLoginInitiate (OK=374 KO=0 ) 2024-06-22 20:16:44,215 INFO > restLoginUsernamePasswordCallback (OK=369 KO=0 ) 2024-06-22 20:16:44,215 INFO 2024-06-22 20:16:44,216 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,216 INFO [- ] 0% 2024-06-22 20:16:44,216 INFO waiting: 361478 / active: 7 / done: 369 2024-06-22 20:16:44,216 INFO ================================================================================ 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO ================================================================================ 2024-06-22 20:16:44,216 INFO 2024-06-22 20:14:35 20s elapsed 2024-06-22 20:16:44,216 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,216 INFO > Global (OK=1322 KO=0 ) 2024-06-22 20:16:44,216 INFO > restLoginInitiate (OK=664 KO=0 ) 2024-06-22 20:16:44,216 INFO > restLoginUsernamePasswordCallback (OK=658 KO=0 ) 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,216 INFO [- ] 0% 2024-06-22 20:16:44,216 INFO waiting: 361186 / active: 10 / done: 658 2024-06-22 20:16:44,216 INFO ================================================================================ 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO ================================================================================ 2024-06-22 20:16:44,216 INFO 2024-06-22 20:14:40 25s elapsed 2024-06-22 20:16:44,216 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,216 INFO > Global (OK=2068 KO=0 ) 2024-06-22 20:16:44,216 INFO > restLoginInitiate (OK=1038 KO=0 ) 2024-06-22 20:16:44,216 INFO > restLoginUsernamePasswordCallback (OK=1029 KO=0 ) 2024-06-22 20:16:44,216 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,216 INFO [- ] 0% 2024-06-22 20:16:44,216 INFO waiting: 360811 / active: 14 / done: 1029 2024-06-22 20:16:44,216 INFO ================================================================================ 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,216 INFO ================================================================================ 2024-06-22 20:16:44,216 INFO 2024-06-22 20:14:45 30s elapsed 2024-06-22 20:16:44,216 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,216 INFO > Global (OK=2983 KO=0 ) 2024-06-22 20:16:44,216 INFO > restLoginInitiate (OK=1497 KO=0 ) 2024-06-22 20:16:44,216 INFO > restLoginUsernamePasswordCallback (OK=1485 KO=0 ) 2024-06-22 20:16:44,216 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-22 20:16:44,216 INFO 2024-06-22 20:16:44,217 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,217 INFO [- ] 0% 2024-06-22 20:16:44,217 INFO waiting: 360354 / active: 15 / done: 1485 2024-06-22 20:16:44,217 INFO ================================================================================ 2024-06-22 20:16:44,217 INFO 2024-06-22 20:16:44,217 INFO 2024-06-22 20:16:44,217 INFO ================================================================================ 2024-06-22 20:16:44,217 INFO 2024-06-22 20:14:50 35s elapsed 2024-06-22 20:16:44,217 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,217 INFO > Global (OK=3996 KO=0 ) 2024-06-22 20:16:44,217 INFO > restLoginInitiate (OK=2002 KO=0 ) 2024-06-22 20:16:44,217 INFO > restLoginUsernamePasswordCallback (OK=1993 KO=0 ) 2024-06-22 20:16:44,217 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-22 20:16:44,217 INFO 2024-06-22 20:16:44,217 INFO [...] 2024-06-22 20:16:44,220 INFO 2024-06-22 20:16:44,220 INFO 2024-06-22 20:16:44,220 INFO ================================================================================ 2024-06-22 20:16:44,220 INFO 2024-06-22 21:14:30 3615s elapsed 2024-06-22 20:16:44,220 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,220 INFO > Global (OK=728342 KO=18 ) 2024-06-22 20:16:44,220 INFO > restLoginInitiate (OK=360291 KO=9 ) 2024-06-22 20:16:44,220 INFO > restLoginUsernamePasswordCallback (OK=360271 KO=7 ) 2024-06-22 20:16:44,220 INFO > restloginProgressiveProfileCallback (OK=7780 KO=2 ) 2024-06-22 20:16:44,220 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 20:16:44,220 INFO > status.find.is(200), but actually found 502 18 (100.0%) 2024-06-22 20:16:44,220 INFO 2024-06-22 20:16:44,220 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,220 INFO [#########################################################################-] 99% 2024-06-22 20:16:44,220 INFO waiting: 1546 / active: 23 / done: 360285 2024-06-22 20:16:44,221 INFO ================================================================================ 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO ================================================================================ 2024-06-22 20:16:44,221 INFO 2024-06-22 21:14:35 3620s elapsed 2024-06-22 20:16:44,221 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,221 INFO > Global (OK=729354 KO=18 ) 2024-06-22 20:16:44,221 INFO > restLoginInitiate (OK=360786 KO=9 ) 2024-06-22 20:16:44,221 INFO > restLoginUsernamePasswordCallback (OK=360765 KO=7 ) 2024-06-22 20:16:44,221 INFO > restloginProgressiveProfileCallback (OK=7803 KO=2 ) 2024-06-22 20:16:44,221 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 20:16:44,221 INFO > status.find.is(200), but actually found 502 18 (100.0%) 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,221 INFO [#########################################################################-] 99% 2024-06-22 20:16:44,221 INFO waiting: 1055 / active: 18 / done: 360781 2024-06-22 20:16:44,221 INFO ================================================================================ 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO ================================================================================ 2024-06-22 20:16:44,221 INFO 2024-06-22 21:14:40 3625s elapsed 2024-06-22 20:16:44,221 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,221 INFO > Global (OK=730411 KO=18 ) 2024-06-22 20:16:44,221 INFO > restLoginInitiate (OK=361300 KO=9 ) 2024-06-22 20:16:44,221 INFO > restLoginUsernamePasswordCallback (OK=361280 KO=7 ) 2024-06-22 20:16:44,221 INFO > restloginProgressiveProfileCallback (OK=7831 KO=2 ) 2024-06-22 20:16:44,221 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 20:16:44,221 INFO > status.find.is(200), but actually found 502 18 (100.0%) 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,221 INFO [#########################################################################-] 99% 2024-06-22 20:16:44,221 INFO waiting: 542 / active: 18 / done: 361294 2024-06-22 20:16:44,221 INFO ================================================================================ 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO 2024-06-22 20:16:44,221 INFO ================================================================================ 2024-06-22 20:16:44,221 INFO 2024-06-22 21:14:45 3630s elapsed 2024-06-22 20:16:44,221 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,222 INFO > Global (OK=731513 KO=18 ) 2024-06-22 20:16:44,222 INFO > restLoginInitiate (OK=361839 KO=9 ) 2024-06-22 20:16:44,222 INFO > restLoginUsernamePasswordCallback (OK=361813 KO=7 ) 2024-06-22 20:16:44,222 INFO > restloginProgressiveProfileCallback (OK=7861 KO=2 ) 2024-06-22 20:16:44,222 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 20:16:44,222 INFO > status.find.is(200), but actually found 502 18 (100.0%) 2024-06-22 20:16:44,222 INFO 2024-06-22 20:16:44,222 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,222 INFO [#########################################################################-] 99% 2024-06-22 20:16:44,222 INFO waiting: 0 / active: 25 / done: 361829 2024-06-22 20:16:44,222 INFO ================================================================================ 2024-06-22 20:16:44,222 INFO 2024-06-22 20:16:44,222 INFO 2024-06-22 20:16:44,222 INFO ================================================================================ 2024-06-22 20:16:44,222 INFO 2024-06-22 21:14:46 3630s elapsed 2024-06-22 20:16:44,222 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 20:16:44,222 INFO > Global (OK=731545 KO=18 ) 2024-06-22 20:16:44,222 INFO > restLoginInitiate (OK=361845 KO=9 ) 2024-06-22 20:16:44,222 INFO > restLoginUsernamePasswordCallback (OK=361838 KO=7 ) 2024-06-22 20:16:44,222 INFO > restloginProgressiveProfileCallback (OK=7862 KO=2 ) 2024-06-22 20:16:44,222 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 20:16:44,222 INFO > status.find.is(200), but actually found 502 18 (100.0%) 2024-06-22 20:16:44,222 INFO 2024-06-22 20:16:44,222 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-22 20:16:44,222 INFO [##########################################################################]100% 2024-06-22 20:16:44,222 INFO waiting: 0 / active: 0 / done: 361854 2024-06-22 20:16:44,222 INFO ================================================================================ 2024-06-22 20:16:44,222 INFO 2024-06-22 20:16:44,222 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 20:16:44,222 INFO 2024-06-22 20:16:44,222 INFO ---------------------- 2024-06-22 20:16:44,222 INFO [22/Jun/2024 21:14:46] Create tar file login-tree-100.tar.gz 2024-06-22 20:16:44,222 INFO ---------------------- 2024-06-22 20:16:44,222 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 20:16:44,222 INFO RESULT_FOLDER=login-tree-100 2024-06-22 20:16:44,223 INFO SIMULATION_PATH=login-tree-100/loadtest-20240622191415320 2024-06-22 20:16:44,223 INFO PWD=/results/gatling/login-tree-100/loadtest-20240622191415320 2024-06-22 20:16:44,223 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-06-22 20:16:44,223 INFO /results/gatling 2024-06-22 20:16:44,223 INFO 2024-06-22 20:16:44,223 INFO ---------------------- 2024-06-22 20:16:44,223 INFO [22/Jun/2024 21:14:46] Update /results/gatling//latest 2024-06-22 20:16:44,223 INFO ---------------------- 2024-06-22 20:16:44,223 INFO 2024-06-22 20:16:44,223 INFO ---------------------- 2024-06-22 20:16:44,223 INFO [22/Jun/2024 21:14:46] Update /results/gatling//latest.tar.gz 2024-06-22 20:16:44,223 INFO ---------------------- 2024-06-22 20:16:44,223 INFO ________________________________________________________________________________ [2024-06-22 20:16:44] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 20:16:44,223 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-22 20:16:44,223 DEBUG ----- output ----- 2024-06-22 20:16:44,223 DEBUG 2024-06-22 20:16:44,223 INFO ________________________________________________________________________________ [2024-06-22 20:16:44] 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-22 20:16:44] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 20:16:44] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped