--Task-- name: login-tree-50 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.loginProtect', 'testMode': 'open', 'throughput': 50, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-13 00:52:33] login-tree-50 pre : N/A ________________________________________________________________________________ 2024-06-13 00:52:33 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-13 00:52:33 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-50/stdout.txt Pod log : /results/orders/login-tree-50 2024-06-13 00:52:33,116 INFO 2024-06-13 00:52:33,116 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-13 00:52:33,116 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:33,422 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:33,422 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:33,422 DEBUG --- stdout --- 2024-06-13 00:52:33,422 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:33,422 DEBUG --- stderr --- 2024-06-13 00:52:33,422 DEBUG ________________________________________________________________________________ [2024-06-13 00:52:33] login-tree-50 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-13 00:52:33,423 INFO 2024-06-13 00:52:33,423 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-13 00:52:33,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:33,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:33,740 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:33,740 DEBUG --- stdout --- 2024-06-13 00:52:33,740 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:33,740 DEBUG --- stderr --- 2024-06-13 00:52:33,740 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.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-50 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-13 00:52:33] login-tree-50 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-13 00:52:33,741 INFO 2024-06-13 00:52:33,741 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-13 00:52:33,741 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:34,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:34,051 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:34,051 DEBUG --- stdout --- 2024-06-13 00:52:34,051 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:34,051 DEBUG --- stderr --- 2024-06-13 00:52:34,051 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/order.json { "name": "login-tree-50", "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.loginProtect@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-50 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-50", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -Dlogin_tree=Login_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=50 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-13 00:52:34] login-tree-50 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-13 00:52:34,053 INFO 2024-06-13 00:52:34,053 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 00:52:34,054 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 00:52:34,293 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 00:52:34,293 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:34,293 DEBUG --- stdout --- 2024-06-13 00:52:34,293 DEBUG WAITING 2024-06-13 00:52:34,293 DEBUG --- stderr --- 2024-06-13 00:52:34,293 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-13 00:52:34] login-tree-50 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-13 00:52:34,294 INFO 2024-06-13 00:52:34,294 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-13 00:52:34,294 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:34,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:34,597 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:34,597 DEBUG --- stdout --- 2024-06-13 00:52:34,597 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:34,597 DEBUG --- stderr --- 2024-06-13 00:52:34,597 DEBUG 2024-06-13 00:52:34,598 INFO 2024-06-13 00:52:34,598 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-50/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-13 00:52:34,598 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 00:52:35,955 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:35,955 DEBUG --- stdout --- 2024-06-13 00:52:35,956 DEBUG 2024-06-13 00:52:35,956 DEBUG --- stderr --- 2024-06-13 00:52:35,957 DEBUG Order has been successfully forwarded to overseer 2024-06-13 00:52:35 - INFO: Check if Gatling simulation started successfully 2024-06-13 00:52:35,957 INFO 2024-06-13 00:52:35,957 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-13 00:52:35,957 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:36,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:36,266 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:36,266 DEBUG --- stdout --- 2024-06-13 00:52:36,266 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:36,266 DEBUG --- stderr --- 2024-06-13 00:52:36,266 DEBUG 2024-06-13 00:52:36,266 INFO 2024-06-13 00:52:36,266 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-50/stdout.txt | grep "started..." 2024-06-13 00:52:36,266 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 00:52:37,007 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-13 00:52:42,741 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-13 00:52:48,481 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-13 00:52:48,481 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:48,481 DEBUG --- stdout --- 2024-06-13 00:52:48,481 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 01:52:38.258 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 01:52:42.092 [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"); ^ 01:52:42.095 [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"); ^ 01:52:43.671 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 00:52:48,481 DEBUG --- stderr --- 2024-06-13 00:52:48,481 DEBUG ________________________________________________________________________________ [2024-06-13 00:52:48] login-tree-50 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 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-13 01:54:58] login-tree-50 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-13 01:54:58,579 INFO 2024-06-13 01:54:58,579 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-13 01:54:58,579 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:54:58,913 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:54:58,913 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:54:58,913 DEBUG --- stdout --- 2024-06-13 01:54:58,914 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:54:58,914 DEBUG --- stderr --- 2024-06-13 01:54:58,914 DEBUG 2024-06-13 01:54:58,914 INFO 2024-06-13 01:54:58,914 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-13 01:54:58,914 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 01:54:59,711 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:54:59,712 DEBUG --- stdout --- 2024-06-13 01:54:59,712 DEBUG 2024-06-13 01:54:59,712 DEBUG --- stderr --- 2024-06-13 01:54:59,712 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-13 01:54:59] login-tree-50 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-13 01:54:59 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/order.json 2024-06-13 01:54:59,713 INFO 2024-06-13 01:54:59,713 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-13 01:54:59,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:00,026 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:00,026 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:00,026 DEBUG --- stdout --- 2024-06-13 01:55:00,027 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:00,027 DEBUG --- stderr --- 2024-06-13 01:55:00,027 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 01:55:00,027 INFO 2024-06-13 01:55:00,027 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-13 01:55:00,027 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:00,335 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:00,335 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:00,335 DEBUG --- stdout --- 2024-06-13 01:55:00,335 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:00,335 DEBUG --- stderr --- 2024-06-13 01:55:00,335 DEBUG 2024-06-13 01:55:00,336 INFO 2024-06-13 01:55:00,336 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-50/rc.txt" ]; then echo "/results/orders/login-tree-50/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-50/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/rc.txt file found" 2024-06-13 01:55:00,336 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 01:55:01,136 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 01:55:01,136 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:01,136 DEBUG --- stdout --- 2024-06-13 01:55:01,136 DEBUG /results/orders/login-tree-50/rc.txt file found 2024-06-13 01:55:01,136 DEBUG --- stderr --- 2024-06-13 01:55:01,136 DEBUG 2024-06-13 01:55:01,136 INFO 2024-06-13 01:55:01,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-13 01:55:01,137 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:01,448 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:01,448 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:01,448 DEBUG --- stdout --- 2024-06-13 01:55:01,448 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:01,448 DEBUG --- stderr --- 2024-06-13 01:55:01,448 DEBUG 2024-06-13 01:55:01,448 INFO 2024-06-13 01:55:01,449 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-50/rc.txt 2024-06-13 01:55:02,262 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 01:55:02,262 DEBUG --- stdout --- 2024-06-13 01:55:02,262 DEBUG 4 /results/orders/login-tree-50/rc.txt 2024-06-13 01:55:02,262 DEBUG --- stderr --- 2024-06-13 01:55:02,262 DEBUG 2024-06-13 01:55:02,262 INFO - Download file (size 4k) 2024-06-13 01:55:02,263 INFO 2024-06-13 01:55:02,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-13 01:55:02,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:02,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:02,561 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:02,561 DEBUG --- stdout --- 2024-06-13 01:55:02,561 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:02,561 DEBUG --- stderr --- 2024-06-13 01:55:02,562 DEBUG source : /results/orders/login-tree-50/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/rc.txt 2024-06-13 01:55:02,562 INFO 2024-06-13 01:55:02,562 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-13 01:55:02,562 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:02,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:02,863 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:02,863 DEBUG --- stdout --- 2024-06-13 01:55:02,863 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:02,863 DEBUG --- stderr --- 2024-06-13 01:55:02,863 DEBUG 2024-06-13 01:55:02,864 INFO 2024-06-13 01:55:02,864 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-50/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/rc.txt 2024-06-13 01:55:02,864 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 01:55:03,592 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:03,592 DEBUG --- stdout --- 2024-06-13 01:55:03,592 DEBUG tar: Removing leading `/' from member names 2024-06-13 01:55:03,592 DEBUG --- stderr --- 2024-06-13 01:55:03,592 DEBUG 2024-06-13 01:55:03,593 INFO 2024-06-13 01:55:03,593 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-13 01:55:03,593 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:03,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:03,906 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:03,906 DEBUG --- stdout --- 2024-06-13 01:55:03,906 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:03,906 DEBUG --- stderr --- 2024-06-13 01:55:03,906 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 01:55:03,907 INFO 2024-06-13 01:55:03,907 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-13 01:55:03,907 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:04,235 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:04,235 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:04,235 DEBUG --- stdout --- 2024-06-13 01:55:04,235 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:04,235 DEBUG --- stderr --- 2024-06-13 01:55:04,235 DEBUG 2024-06-13 01:55:04,236 INFO 2024-06-13 01:55:04,236 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-50/stderr.txt" ]; then echo "/results/orders/login-tree-50/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-50/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/stderr.txt file found" 2024-06-13 01:55:04,236 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 01:55:05,036 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 01:55:05,037 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:05,037 DEBUG --- stdout --- 2024-06-13 01:55:05,037 DEBUG /results/orders/login-tree-50/stderr.txt file found 2024-06-13 01:55:05,037 DEBUG --- stderr --- 2024-06-13 01:55:05,037 DEBUG 2024-06-13 01:55:05,037 INFO 2024-06-13 01:55:05,037 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-13 01:55:05,037 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:05,336 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:05,336 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:05,336 DEBUG --- stdout --- 2024-06-13 01:55:05,336 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:05,336 DEBUG --- stderr --- 2024-06-13 01:55:05,336 DEBUG 2024-06-13 01:55:05,336 INFO 2024-06-13 01:55:05,336 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-50/stderr.txt 2024-06-13 01:55:06,131 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 01:55:06,131 DEBUG --- stdout --- 2024-06-13 01:55:06,131 DEBUG 0 /results/orders/login-tree-50/stderr.txt 2024-06-13 01:55:06,131 DEBUG --- stderr --- 2024-06-13 01:55:06,131 DEBUG 2024-06-13 01:55:06,132 INFO - Download file (size 0k) 2024-06-13 01:55:06,132 INFO 2024-06-13 01:55:06,132 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-13 01:55:06,132 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:06,431 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:06,431 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:06,431 DEBUG --- stdout --- 2024-06-13 01:55:06,431 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:06,431 DEBUG --- stderr --- 2024-06-13 01:55:06,431 DEBUG source : /results/orders/login-tree-50/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stderr.txt 2024-06-13 01:55:06,431 INFO 2024-06-13 01:55:06,431 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-13 01:55:06,431 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:06,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:06,728 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:06,728 DEBUG --- stdout --- 2024-06-13 01:55:06,728 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:06,728 DEBUG --- stderr --- 2024-06-13 01:55:06,728 DEBUG 2024-06-13 01:55:06,728 INFO 2024-06-13 01:55:06,728 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-50/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stderr.txt 2024-06-13 01:55:06,728 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 01:55:07,537 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:07,537 DEBUG --- stdout --- 2024-06-13 01:55:07,537 DEBUG tar: Removing leading `/' from member names 2024-06-13 01:55:07,538 DEBUG --- stderr --- 2024-06-13 01:55:07,538 DEBUG 2024-06-13 01:55:07,538 INFO 2024-06-13 01:55:07,538 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-13 01:55:07,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:07,847 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:07,848 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:07,848 DEBUG --- stdout --- 2024-06-13 01:55:07,848 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:07,848 DEBUG --- stderr --- 2024-06-13 01:55:07,848 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 01:55:07,848 INFO 2024-06-13 01:55:07,848 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-13 01:55:07,848 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:08,147 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:08,148 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:08,148 DEBUG --- stdout --- 2024-06-13 01:55:08,148 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:08,148 DEBUG --- stderr --- 2024-06-13 01:55:08,148 DEBUG 2024-06-13 01:55:08,148 INFO 2024-06-13 01:55:08,149 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-50/stdout.txt" ]; then echo "/results/orders/login-tree-50/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-50/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/stdout.txt file found" 2024-06-13 01:55:08,149 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 01:55:08,948 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 01:55:08,948 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:08,948 DEBUG --- stdout --- 2024-06-13 01:55:08,949 DEBUG /results/orders/login-tree-50/stdout.txt file found 2024-06-13 01:55:08,949 DEBUG --- stderr --- 2024-06-13 01:55:08,949 DEBUG 2024-06-13 01:55:08,949 INFO 2024-06-13 01:55:08,949 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-13 01:55:08,949 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:09,247 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:09,247 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:09,247 DEBUG --- stdout --- 2024-06-13 01:55:09,247 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:09,247 DEBUG --- stderr --- 2024-06-13 01:55:09,247 DEBUG 2024-06-13 01:55:09,247 INFO 2024-06-13 01:55:09,248 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-50/stdout.txt 2024-06-13 01:55:10,052 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 01:55:10,053 DEBUG --- stdout --- 2024-06-13 01:55:10,054 DEBUG 1384 /results/orders/login-tree-50/stdout.txt 2024-06-13 01:55:10,054 DEBUG --- stderr --- 2024-06-13 01:55:10,054 DEBUG 2024-06-13 01:55:10,054 INFO - Download file (size 1384k) 2024-06-13 01:55:10,055 INFO 2024-06-13 01:55:10,055 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-13 01:55:10,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:10,372 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:10,373 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:10,373 DEBUG --- stdout --- 2024-06-13 01:55:10,373 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:10,373 DEBUG --- stderr --- 2024-06-13 01:55:10,373 DEBUG source : /results/orders/login-tree-50/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stdout.txt 2024-06-13 01:55:10,373 INFO 2024-06-13 01:55:10,373 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-13 01:55:10,373 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:10,690 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:10,690 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:10,691 DEBUG --- stdout --- 2024-06-13 01:55:10,691 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:10,691 DEBUG --- stderr --- 2024-06-13 01:55:10,691 DEBUG 2024-06-13 01:55:10,691 INFO 2024-06-13 01:55:10,691 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-50/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stdout.txt 2024-06-13 01:55:10,691 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 01:55:11,830 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:11,830 DEBUG --- stdout --- 2024-06-13 01:55:11,831 DEBUG tar: Removing leading `/' from member names 2024-06-13 01:55:11,831 DEBUG --- stderr --- 2024-06-13 01:55:11,831 DEBUG ________________________________________________________________________________ [2024-06-13 01:55:11] login-tree-50 show_cmd : Display order command ________________________________________________________________________________ 2024-06-13 01:55:11,831 INFO 2024-06-13 01:55:11,831 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-13 01:55:11,831 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:12,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:12,129 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:12,129 DEBUG --- stdout --- 2024-06-13 01:55:12,129 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:12,129 DEBUG --- stderr --- 2024-06-13 01:55:12,129 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 01:55:12,130 INFO 2024-06-13 01:55:12,130 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-13 01:55:12,130 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:12,430 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:12,430 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:12,430 DEBUG --- stdout --- 2024-06-13 01:55:12,430 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:12,430 DEBUG --- stderr --- 2024-06-13 01:55:12,430 DEBUG 2024-06-13 01:55:12,431 INFO 2024-06-13 01:55:12,431 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-50/cmd.txt" ]; then echo "/results/orders/login-tree-50/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-50/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/cmd.txt file found" 2024-06-13 01:55:12,431 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 01:55:13,243 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 01:55:13,244 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:13,244 DEBUG --- stdout --- 2024-06-13 01:55:13,244 DEBUG /results/orders/login-tree-50/cmd.txt file found 2024-06-13 01:55:13,244 DEBUG --- stderr --- 2024-06-13 01:55:13,244 DEBUG 2024-06-13 01:55:13,244 INFO 2024-06-13 01:55:13,244 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-13 01:55:13,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:13,556 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:13,556 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:13,556 DEBUG --- stdout --- 2024-06-13 01:55:13,556 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:13,556 DEBUG --- stderr --- 2024-06-13 01:55:13,556 DEBUG 2024-06-13 01:55:13,556 INFO 2024-06-13 01:55:13,557 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-50/cmd.txt 2024-06-13 01:55:14,359 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 01:55:14,359 DEBUG --- stdout --- 2024-06-13 01:55:14,359 DEBUG 4 /results/orders/login-tree-50/cmd.txt 2024-06-13 01:55:14,359 DEBUG --- stderr --- 2024-06-13 01:55:14,359 DEBUG 2024-06-13 01:55:14,359 INFO - Download file (size 4k) 2024-06-13 01:55:14,359 INFO 2024-06-13 01:55:14,359 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-13 01:55:14,359 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:14,670 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:14,671 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:14,671 DEBUG --- stdout --- 2024-06-13 01:55:14,671 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:14,671 DEBUG --- stderr --- 2024-06-13 01:55:14,671 DEBUG source : /results/orders/login-tree-50/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/cmd.txt 2024-06-13 01:55:14,671 INFO 2024-06-13 01:55:14,671 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-13 01:55:14,671 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:14,987 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:14,987 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:14,987 DEBUG --- stdout --- 2024-06-13 01:55:14,987 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:14,987 DEBUG --- stderr --- 2024-06-13 01:55:14,987 DEBUG 2024-06-13 01:55:14,988 INFO 2024-06-13 01:55:14,988 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-50/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/cmd.txt 2024-06-13 01:55:14,988 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 01:55:15,775 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:15,775 DEBUG --- stdout --- 2024-06-13 01:55:15,775 DEBUG tar: Removing leading `/' from member names 2024-06-13 01:55:15,775 DEBUG --- stderr --- 2024-06-13 01:55:15,775 DEBUG 2024-06-13 01:55:15,775 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/cmd.txt is small (1 lines), printing all lines: 2024-06-13 01:55:15,775 DEBUG ----- output ----- 2024-06-13 01:55:15,776 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.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-50 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-13 01:55:15,776 INFO ________________________________________________________________________________ [2024-06-13 01:55:15] login-tree-50 show_rc : Display rc order ________________________________________________________________________________ 2024-06-13 01:55:15,776 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/rc.txt is small (1 lines), printing all lines: 2024-06-13 01:55:15,776 DEBUG ----- output ----- 2024-06-13 01:55:15,776 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 01:55:15,776 INFO ________________________________________________________________________________ [2024-06-13 01:55:15] login-tree-50 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-13 01:55:15,779 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-50/stdout.txt is 20539 lines): 2024-06-13 01:55:15,780 DEBUG ----- output ----- 2024-06-13 01:55:15,780 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-13 01:55:15,780 INFO 01:52:38.258 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-13 01:55:15,780 INFO 01:52:42.092 [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-13 01:55:15,780 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-13 01:55:15,780 INFO ^ 2024-06-13 01:55:15,780 INFO 01:52:42.095 [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-13 01:55:15,780 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-13 01:55:15,780 INFO ^ 2024-06-13 01:55:15,780 INFO 01:52:43.671 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-13 01:55:15,780 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-13 01:55:15,780 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 01:55:15,780 INFO 2024-06-13 01:55:15,780 INFO ================================================================================ 2024-06-13 01:55:15,780 INFO 2024-06-13 01:52:51 5s elapsed 2024-06-13 01:55:15,780 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,780 INFO > Global (OK=70 KO=0 ) 2024-06-13 01:55:15,780 INFO > protectLoginInitiate (OK=20 KO=0 ) 2024-06-13 01:55:15,780 INFO > PingOneProtectInitializeCallback (OK=19 KO=0 ) 2024-06-13 01:55:15,780 INFO > restLoginUsernamePasswordCallback (OK=18 KO=0 ) 2024-06-13 01:55:15,780 INFO > PingOneProtectEvaluationCallback (OK=13 KO=0 ) 2024-06-13 01:55:15,780 INFO 2024-06-13 01:55:15,780 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,780 INFO [- ] 0% 2024-06-13 01:55:15,780 INFO waiting: 181498 / active: 8 / done: 13 2024-06-13 01:55:15,780 INFO ================================================================================ 2024-06-13 01:55:15,780 INFO 2024-06-13 01:55:15,780 INFO 2024-06-13 01:55:15,780 INFO ================================================================================ 2024-06-13 01:55:15,780 INFO 2024-06-13 01:52:56 10s elapsed 2024-06-13 01:55:15,780 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,780 INFO > Global (OK=310 KO=0 ) 2024-06-13 01:55:15,780 INFO > protectLoginInitiate (OK=82 KO=0 ) 2024-06-13 01:55:15,780 INFO > PingOneProtectInitializeCallback (OK=80 KO=0 ) 2024-06-13 01:55:15,781 INFO > restLoginUsernamePasswordCallback (OK=77 KO=0 ) 2024-06-13 01:55:15,781 INFO > PingOneProtectEvaluationCallback (OK=71 KO=0 ) 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,781 INFO [- ] 0% 2024-06-13 01:55:15,781 INFO waiting: 181435 / active: 13 / done: 71 2024-06-13 01:55:15,781 INFO ================================================================================ 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO ================================================================================ 2024-06-13 01:55:15,781 INFO 2024-06-13 01:53:01 15s elapsed 2024-06-13 01:55:15,781 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,781 INFO > Global (OK=713 KO=0 ) 2024-06-13 01:55:15,781 INFO > protectLoginInitiate (OK=185 KO=0 ) 2024-06-13 01:55:15,781 INFO > PingOneProtectInitializeCallback (OK=182 KO=0 ) 2024-06-13 01:55:15,781 INFO > restLoginUsernamePasswordCallback (OK=180 KO=0 ) 2024-06-13 01:55:15,781 INFO > PingOneProtectEvaluationCallback (OK=166 KO=0 ) 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,781 INFO [- ] 0% 2024-06-13 01:55:15,781 INFO waiting: 181331 / active: 22 / done: 166 2024-06-13 01:55:15,781 INFO ================================================================================ 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO ================================================================================ 2024-06-13 01:55:15,781 INFO 2024-06-13 01:53:06 20s elapsed 2024-06-13 01:55:15,781 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,781 INFO > Global (OK=1281 KO=0 ) 2024-06-13 01:55:15,781 INFO > protectLoginInitiate (OK=330 KO=0 ) 2024-06-13 01:55:15,781 INFO > PingOneProtectInitializeCallback (OK=326 KO=0 ) 2024-06-13 01:55:15,781 INFO > restLoginUsernamePasswordCallback (OK=321 KO=0 ) 2024-06-13 01:55:15,781 INFO > PingOneProtectEvaluationCallback (OK=304 KO=0 ) 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,781 INFO [- ] 0% 2024-06-13 01:55:15,781 INFO waiting: 181185 / active: 30 / done: 304 2024-06-13 01:55:15,781 INFO ================================================================================ 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO 2024-06-13 01:55:15,781 INFO ================================================================================ 2024-06-13 01:55:15,781 INFO 2024-06-13 01:53:11 25s elapsed 2024-06-13 01:55:15,781 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,782 INFO > Global (OK=2019 KO=0 ) 2024-06-13 01:55:15,782 INFO > protectLoginInitiate (OK=516 KO=0 ) 2024-06-13 01:55:15,782 INFO > PingOneProtectInitializeCallback (OK=510 KO=0 ) 2024-06-13 01:55:15,782 INFO > restLoginUsernamePasswordCallback (OK=505 KO=0 ) 2024-06-13 01:55:15,782 INFO > PingOneProtectEvaluationCallback (OK=488 KO=0 ) 2024-06-13 01:55:15,782 INFO 2024-06-13 01:55:15,782 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,782 INFO [- ] 0% 2024-06-13 01:55:15,782 INFO waiting: 180998 / active: 33 / done: 488 2024-06-13 01:55:15,782 INFO ================================================================================ 2024-06-13 01:55:15,782 INFO 2024-06-13 01:55:15,782 INFO 2024-06-13 01:55:15,782 INFO ================================================================================ 2024-06-13 01:55:15,782 INFO 2024-06-13 01:53:16 30s elapsed 2024-06-13 01:55:15,782 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,782 INFO > Global (OK=2928 KO=0 ) 2024-06-13 01:55:15,782 INFO > protectLoginInitiate (OK=745 KO=0 ) 2024-06-13 01:55:15,782 INFO > PingOneProtectInitializeCallback (OK=740 KO=0 ) 2024-06-13 01:55:15,782 INFO > restLoginUsernamePasswordCallback (OK=734 KO=0 ) 2024-06-13 01:55:15,782 INFO > PingOneProtectEvaluationCallback (OK=709 KO=0 ) 2024-06-13 01:55:15,782 INFO 2024-06-13 01:55:15,782 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,782 INFO [- ] 0% 2024-06-13 01:55:15,782 INFO waiting: 180768 / active: 42 / done: 709 2024-06-13 01:55:15,782 INFO ================================================================================ 2024-06-13 01:55:15,782 INFO [...] 2024-06-13 01:55:15,788 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,788 INFO > Global (OK=726468 KO=22 ) 2024-06-13 01:55:15,788 INFO > protectLoginInitiate (OK=181274 KO=2 ) 2024-06-13 01:55:15,788 INFO > PingOneProtectInitializeCallback (OK=181266 KO=0 ) 2024-06-13 01:55:15,788 INFO > restLoginUsernamePasswordCallback (OK=181262 KO=1 ) 2024-06-13 01:55:15,788 INFO > PingOneProtectEvaluationCallback (OK=181229 KO=11 ) 2024-06-13 01:55:15,788 INFO > restloginProgressiveProfileCallback (OK=1437 KO=8 ) 2024-06-13 01:55:15,788 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 01:55:15,788 INFO > status.find.is(200), but actually found 401 8 (26.67%) 2024-06-13 01:55:15,788 INFO > jsonPath($.tokenId).find.exists, found nothing 8 (26.67%) 2024-06-13 01:55:15,788 INFO > status.find.is(200), but actually found 502 6 (20.00%) 2024-06-13 01:55:15,788 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,788 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,788 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,788 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,788 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,788 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,788 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 6.67%) 2024-06-13 01:55:15,788 INFO No attribute named 'callbacks' is defined 2024-06-13 01:55:15,788 INFO 2024-06-13 01:55:15,788 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,788 INFO [#########################################################################-] 99% 2024-06-13 01:55:15,788 INFO waiting: 238 / active: 39 / done: 181242 2024-06-13 01:55:15,788 INFO ================================================================================ 2024-06-13 01:55:15,788 INFO 2024-06-13 01:55:15,788 INFO 2024-06-13 01:55:15,788 INFO ================================================================================ 2024-06-13 01:55:15,788 INFO 2024-06-13 02:53:16 3630s elapsed 2024-06-13 01:55:15,788 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,788 INFO > Global (OK=727444 KO=22 ) 2024-06-13 01:55:15,789 INFO > protectLoginInitiate (OK=181514 KO=2 ) 2024-06-13 01:55:15,789 INFO > PingOneProtectInitializeCallback (OK=181510 KO=0 ) 2024-06-13 01:55:15,789 INFO > restLoginUsernamePasswordCallback (OK=181503 KO=1 ) 2024-06-13 01:55:15,789 INFO > PingOneProtectEvaluationCallback (OK=181477 KO=11 ) 2024-06-13 01:55:15,789 INFO > restloginProgressiveProfileCallback (OK=1440 KO=8 ) 2024-06-13 01:55:15,789 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 01:55:15,789 INFO > status.find.is(200), but actually found 401 8 (26.67%) 2024-06-13 01:55:15,789 INFO > jsonPath($.tokenId).find.exists, found nothing 8 (26.67%) 2024-06-13 01:55:15,789 INFO > status.find.is(200), but actually found 502 6 (20.00%) 2024-06-13 01:55:15,789 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,789 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,789 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,789 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,789 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,789 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,789 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 6.67%) 2024-06-13 01:55:15,789 INFO No attribute named 'callbacks' is defined 2024-06-13 01:55:15,789 INFO 2024-06-13 01:55:15,789 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,789 INFO [#########################################################################-] 99% 2024-06-13 01:55:15,789 INFO waiting: 0 / active: 29 / done: 181490 2024-06-13 01:55:15,789 INFO ================================================================================ 2024-06-13 01:55:15,789 INFO 2024-06-13 01:55:15,789 INFO 2024-06-13 01:55:15,789 INFO ================================================================================ 2024-06-13 01:55:15,789 INFO 2024-06-13 02:53:17 3630s elapsed 2024-06-13 01:55:15,790 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 01:55:15,790 INFO > Global (OK=727499 KO=22 ) 2024-06-13 01:55:15,790 INFO > protectLoginInitiate (OK=181517 KO=2 ) 2024-06-13 01:55:15,790 INFO > PingOneProtectInitializeCallback (OK=181517 KO=0 ) 2024-06-13 01:55:15,790 INFO > restLoginUsernamePasswordCallback (OK=181516 KO=1 ) 2024-06-13 01:55:15,790 INFO > PingOneProtectEvaluationCallback (OK=181506 KO=11 ) 2024-06-13 01:55:15,790 INFO > restloginProgressiveProfileCallback (OK=1443 KO=8 ) 2024-06-13 01:55:15,790 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 01:55:15,790 INFO > status.find.is(200), but actually found 401 8 (26.67%) 2024-06-13 01:55:15,790 INFO > jsonPath($.tokenId).find.exists, found nothing 8 (26.67%) 2024-06-13 01:55:15,790 INFO > status.find.is(200), but actually found 502 6 (20.00%) 2024-06-13 01:55:15,790 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,790 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,790 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,790 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,790 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 6.67%) 2024-06-13 01:55:15,790 INFO attribute named 'callbacks' is defined 2024-06-13 01:55:15,790 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 6.67%) 2024-06-13 01:55:15,790 INFO No attribute named 'callbacks' is defined 2024-06-13 01:55:15,790 INFO 2024-06-13 01:55:15,790 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 01:55:15,790 INFO [##########################################################################]100% 2024-06-13 01:55:15,790 INFO waiting: 0 / active: 0 / done: 181519 2024-06-13 01:55:15,791 INFO ================================================================================ 2024-06-13 01:55:15,791 INFO 2024-06-13 01:55:15,791 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-13 01:55:15,791 INFO 2024-06-13 01:55:15,791 INFO ---------------------- 2024-06-13 01:55:15,791 INFO [13/Jun/2024 02:53:17] Create tar file login-tree-50.tar.gz 2024-06-13 01:55:15,791 INFO ---------------------- 2024-06-13 01:55:15,791 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-13 01:55:15,791 INFO RESULT_FOLDER=login-tree-50 2024-06-13 01:55:15,791 INFO SIMULATION_PATH=login-tree-50/loadtest-20240613005245772 2024-06-13 01:55:15,791 INFO PWD=/results/gatling/login-tree-50/loadtest-20240613005245772 2024-06-13 01:55:15,791 INFO [cmd] tar -czvf /results/gatling//login-tree-50.tar.gz --exclude=simulation.log * 2024-06-13 01:55:15,791 INFO /results/gatling 2024-06-13 01:55:15,791 INFO 2024-06-13 01:55:15,791 INFO ---------------------- 2024-06-13 01:55:15,791 INFO [13/Jun/2024 02:53:17] Update /results/gatling//latest 2024-06-13 01:55:15,791 INFO ---------------------- 2024-06-13 01:55:15,791 INFO 2024-06-13 01:55:15,791 INFO ---------------------- 2024-06-13 01:55:15,791 INFO [13/Jun/2024 02:53:17] Update /results/gatling//latest.tar.gz 2024-06-13 01:55:15,791 INFO ---------------------- 2024-06-13 01:55:15,791 INFO ________________________________________________________________________________ [2024-06-13 01:55:15] login-tree-50 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-13 01:55:15,792 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stderr.txt is small (0 lines), printing all lines: 2024-06-13 01:55:15,792 DEBUG ----- output ----- 2024-06-13 01:55:15,792 DEBUG 2024-06-13 01:55:15,792 INFO ________________________________________________________________________________ [2024-06-13 01:55:15] login-tree-50 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-13 01:55:15] login-tree-50 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-13 01:55:15] login-tree-50 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped