--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.loginProtect', 'testMode': 'open', 'throughput': 100, '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-22 04:22:50] login-tree-100 pre : N/A ________________________________________________________________________________ 2024-06-22 04:22:50 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 04:22:50 - 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 04:22:50,578 INFO 2024-06-22 04:22:50,578 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 04:22:50,578 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:50,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:50,895 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:50,895 DEBUG --- stdout --- 2024-06-22 04:22:50,895 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:50,896 DEBUG --- stderr --- 2024-06-22 04:22:50,896 DEBUG ________________________________________________________________________________ [2024-06-22 04:22:50] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 04:22:50,896 INFO 2024-06-22 04:22:50,896 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 04:22:50,897 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:51,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:51,217 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:51,217 DEBUG --- stdout --- 2024-06-22 04:22:51,217 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:51,217 DEBUG --- stderr --- 2024-06-22 04:22:51,217 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-p1" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 04:22:51] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 04:22:51,218 INFO 2024-06-22 04:22:51,218 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 04:22:51,218 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:51,536 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:51,536 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:51,536 DEBUG --- stdout --- 2024-06-22 04:22:51,536 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:51,536 DEBUG --- stderr --- 2024-06-22 04:22:51,536 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.loginProtect@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_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=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 04:22:51] 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 04:22:51,540 INFO 2024-06-22 04:22:51,540 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 04:22:51,541 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 04:22:51,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 04:22:51,863 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:51,863 DEBUG --- stdout --- 2024-06-22 04:22:51,863 DEBUG WAITING 2024-06-22 04:22:51,863 DEBUG --- stderr --- 2024-06-22 04:22:51,864 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-06-22 04:22:51] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 04:22:51,864 INFO 2024-06-22 04:22:51,864 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 04:22:51,864 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:52,185 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:52,185 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:52,185 DEBUG --- stdout --- 2024-06-22 04:22:52,185 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:52,185 DEBUG --- stderr --- 2024-06-22 04:22:52,185 DEBUG 2024-06-22 04:22:52,186 INFO 2024-06-22 04:22:52,186 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 04:22:52,186 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 04:22:53,578 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:53,578 DEBUG --- stdout --- 2024-06-22 04:22:53,578 DEBUG 2024-06-22 04:22:53,579 DEBUG --- stderr --- 2024-06-22 04:22:53,579 DEBUG Order has been successfully forwarded to overseer 2024-06-22 04:22:53 - INFO: Check if Gatling simulation started successfully 2024-06-22 04:22:53,579 INFO 2024-06-22 04:22:53,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-22 04:22:53,579 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:53,905 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:53,906 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:53,906 DEBUG --- stdout --- 2024-06-22 04:22:53,906 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:53,906 DEBUG --- stderr --- 2024-06-22 04:22:53,906 DEBUG 2024-06-22 04:22:53,906 INFO 2024-06-22 04:22:53,906 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 04:22:53,906 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 04:22:54,662 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 04:23:00,418 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-22 04:23:06,184 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-22 04:23:06,184 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:23:06,185 DEBUG --- stdout --- 2024-06-22 04:23:06,186 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 05:22:56.141 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 05:23:00.710 [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"); ^ 05:23:00.713 [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"); ^ 05:23:02.393 [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 04:23:06,186 DEBUG --- stderr --- 2024-06-22 04:23:06,186 DEBUG ________________________________________________________________________________ [2024-06-22 04:23:06] 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 02s, 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 05:25:16] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 05:25:16,119 INFO 2024-06-22 05:25:16,120 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 05:25:16,120 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:16,453 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:16,454 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:16,454 DEBUG --- stdout --- 2024-06-22 05:25:16,454 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:16,454 DEBUG --- stderr --- 2024-06-22 05:25:16,454 DEBUG 2024-06-22 05:25:16,454 INFO 2024-06-22 05:25:16,454 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 05:25:16,455 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 05:25:17,231 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:17,231 DEBUG --- stdout --- 2024-06-22 05:25:17,231 DEBUG 2024-06-22 05:25:17,231 DEBUG --- stderr --- 2024-06-22 05:25:17,231 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 05:25:17] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 05:25:17 - 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 05:25:17,232 INFO 2024-06-22 05:25:17,232 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 05:25:17,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:17,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:17,544 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:17,544 DEBUG --- stdout --- 2024-06-22 05:25:17,544 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:17,544 DEBUG --- stderr --- 2024-06-22 05:25:17,544 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 05:25:17,544 INFO 2024-06-22 05:25:17,545 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 05:25:17,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:17,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:17,864 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:17,864 DEBUG --- stdout --- 2024-06-22 05:25:17,864 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:17,864 DEBUG --- stderr --- 2024-06-22 05:25:17,864 DEBUG 2024-06-22 05:25:17,865 INFO 2024-06-22 05:25:17,865 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 05:25:17,865 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 05:25:18,683 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 05:25:18,683 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:18,683 DEBUG --- stdout --- 2024-06-22 05:25:18,683 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-06-22 05:25:18,683 DEBUG --- stderr --- 2024-06-22 05:25:18,683 DEBUG 2024-06-22 05:25:18,683 INFO 2024-06-22 05:25:18,683 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 05:25:18,684 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:18,989 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:18,989 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:18,989 DEBUG --- stdout --- 2024-06-22 05:25:18,989 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:18,989 DEBUG --- stderr --- 2024-06-22 05:25:18,989 DEBUG 2024-06-22 05:25:18,989 INFO 2024-06-22 05:25:18,990 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 05:25:19,793 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 05:25:19,794 DEBUG --- stdout --- 2024-06-22 05:25:19,794 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-06-22 05:25:19,794 DEBUG --- stderr --- 2024-06-22 05:25:19,794 DEBUG 2024-06-22 05:25:19,794 INFO - Download file (size 4k) 2024-06-22 05:25:19,795 INFO 2024-06-22 05:25:19,795 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 05:25:19,795 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:20,109 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:20,109 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:20,110 DEBUG --- stdout --- 2024-06-22 05:25:20,110 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:20,110 DEBUG --- stderr --- 2024-06-22 05:25:20,110 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 05:25:20,110 INFO 2024-06-22 05:25:20,110 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 05:25:20,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:20,426 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:20,426 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:20,426 DEBUG --- stdout --- 2024-06-22 05:25:20,426 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:20,426 DEBUG --- stderr --- 2024-06-22 05:25:20,426 DEBUG 2024-06-22 05:25:20,427 INFO 2024-06-22 05:25:20,427 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 05:25:20,427 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 05:25:21,177 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:21,177 DEBUG --- stdout --- 2024-06-22 05:25:21,177 DEBUG tar: Removing leading `/' from member names 2024-06-22 05:25:21,177 DEBUG --- stderr --- 2024-06-22 05:25:21,177 DEBUG 2024-06-22 05:25:21,178 INFO 2024-06-22 05:25:21,178 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 05:25:21,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:21,489 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:21,489 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:21,489 DEBUG --- stdout --- 2024-06-22 05:25:21,489 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:21,489 DEBUG --- stderr --- 2024-06-22 05:25:21,489 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 05:25:21,490 INFO 2024-06-22 05:25:21,490 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 05:25:21,490 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:21,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:21,792 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:21,792 DEBUG --- stdout --- 2024-06-22 05:25:21,792 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:21,792 DEBUG --- stderr --- 2024-06-22 05:25:21,792 DEBUG 2024-06-22 05:25:21,793 INFO 2024-06-22 05:25:21,793 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 05:25:21,793 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 05:25:22,633 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 05:25:22,633 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:22,633 DEBUG --- stdout --- 2024-06-22 05:25:22,633 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-06-22 05:25:22,633 DEBUG --- stderr --- 2024-06-22 05:25:22,633 DEBUG 2024-06-22 05:25:22,633 INFO 2024-06-22 05:25:22,634 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 05:25:22,634 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:22,960 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:22,960 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:22,960 DEBUG --- stdout --- 2024-06-22 05:25:22,960 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:22,960 DEBUG --- stderr --- 2024-06-22 05:25:22,960 DEBUG 2024-06-22 05:25:22,960 INFO 2024-06-22 05:25:22,961 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 05:25:23,790 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 05:25:23,790 DEBUG --- stdout --- 2024-06-22 05:25:23,791 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-06-22 05:25:23,791 DEBUG --- stderr --- 2024-06-22 05:25:23,791 DEBUG 2024-06-22 05:25:23,791 INFO - Download file (size 0k) 2024-06-22 05:25:23,791 INFO 2024-06-22 05:25:23,791 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 05:25:23,791 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:24,109 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:24,110 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:24,110 DEBUG --- stdout --- 2024-06-22 05:25:24,110 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:24,110 DEBUG --- stderr --- 2024-06-22 05:25:24,110 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 05:25:24,110 INFO 2024-06-22 05:25:24,110 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 05:25:24,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:24,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:24,427 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:24,427 DEBUG --- stdout --- 2024-06-22 05:25:24,428 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:24,428 DEBUG --- stderr --- 2024-06-22 05:25:24,428 DEBUG 2024-06-22 05:25:24,428 INFO 2024-06-22 05:25:24,428 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 05:25:24,428 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 05:25:25,221 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:25,221 DEBUG --- stdout --- 2024-06-22 05:25:25,221 DEBUG tar: Removing leading `/' from member names 2024-06-22 05:25:25,221 DEBUG --- stderr --- 2024-06-22 05:25:25,221 DEBUG 2024-06-22 05:25:25,222 INFO 2024-06-22 05:25:25,222 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 05:25:25,222 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:25,536 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:25,537 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:25,537 DEBUG --- stdout --- 2024-06-22 05:25:25,537 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:25,537 DEBUG --- stderr --- 2024-06-22 05:25:25,537 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 05:25:25,537 INFO 2024-06-22 05:25:25,537 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 05:25:25,537 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:25,856 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:25,856 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:25,856 DEBUG --- stdout --- 2024-06-22 05:25:25,856 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:25,856 DEBUG --- stderr --- 2024-06-22 05:25:25,856 DEBUG 2024-06-22 05:25:25,857 INFO 2024-06-22 05:25:25,857 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 05:25:25,857 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 05:25:26,671 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 05:25:26,672 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:26,672 DEBUG --- stdout --- 2024-06-22 05:25:26,672 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-06-22 05:25:26,672 DEBUG --- stderr --- 2024-06-22 05:25:26,672 DEBUG 2024-06-22 05:25:26,672 INFO 2024-06-22 05:25:26,672 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 05:25:26,672 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:26,986 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:26,986 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:26,986 DEBUG --- stdout --- 2024-06-22 05:25:26,987 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:26,987 DEBUG --- stderr --- 2024-06-22 05:25:26,987 DEBUG 2024-06-22 05:25:26,987 INFO 2024-06-22 05:25:26,987 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 05:25:27,860 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 05:25:27,860 DEBUG --- stdout --- 2024-06-22 05:25:27,861 DEBUG 1660 /results/orders/login-tree-100/stdout.txt 2024-06-22 05:25:27,861 DEBUG --- stderr --- 2024-06-22 05:25:27,861 DEBUG 2024-06-22 05:25:27,861 INFO - Download file (size 1660k) 2024-06-22 05:25:27,861 INFO 2024-06-22 05:25:27,861 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 05:25:27,862 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:28,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:28,179 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:28,179 DEBUG --- stdout --- 2024-06-22 05:25:28,179 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:28,179 DEBUG --- stderr --- 2024-06-22 05:25:28,179 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 05:25:28,179 INFO 2024-06-22 05:25:28,179 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 05:25:28,179 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:28,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:28,502 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:28,502 DEBUG --- stdout --- 2024-06-22 05:25:28,502 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:28,502 DEBUG --- stderr --- 2024-06-22 05:25:28,502 DEBUG 2024-06-22 05:25:28,503 INFO 2024-06-22 05:25:28,503 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 05:25:28,503 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 05:25:29,644 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:29,644 DEBUG --- stdout --- 2024-06-22 05:25:29,645 DEBUG tar: Removing leading `/' from member names 2024-06-22 05:25:29,645 DEBUG --- stderr --- 2024-06-22 05:25:29,645 DEBUG ________________________________________________________________________________ [2024-06-22 05:25:29] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 05:25:29,645 INFO 2024-06-22 05:25:29,646 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 05:25:29,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:29,960 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:29,960 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:29,960 DEBUG --- stdout --- 2024-06-22 05:25:29,961 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:29,961 DEBUG --- stderr --- 2024-06-22 05:25:29,961 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 05:25:29,961 INFO 2024-06-22 05:25:29,961 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 05:25:29,961 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:30,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:30,277 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:30,278 DEBUG --- stdout --- 2024-06-22 05:25:30,278 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:30,278 DEBUG --- stderr --- 2024-06-22 05:25:30,278 DEBUG 2024-06-22 05:25:30,278 INFO 2024-06-22 05:25:30,278 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 05:25:30,278 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 05:25:31,112 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 05:25:31,112 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:31,112 DEBUG --- stdout --- 2024-06-22 05:25:31,112 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-06-22 05:25:31,112 DEBUG --- stderr --- 2024-06-22 05:25:31,112 DEBUG 2024-06-22 05:25:31,112 INFO 2024-06-22 05:25:31,112 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 05:25:31,112 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:31,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:31,429 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:31,430 DEBUG --- stdout --- 2024-06-22 05:25:31,430 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:31,430 DEBUG --- stderr --- 2024-06-22 05:25:31,430 DEBUG 2024-06-22 05:25:31,430 INFO 2024-06-22 05:25:31,430 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-100/cmd.txt 2024-06-22 05:25:32,238 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 05:25:32,238 DEBUG --- stdout --- 2024-06-22 05:25:32,239 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-06-22 05:25:32,240 DEBUG --- stderr --- 2024-06-22 05:25:32,240 DEBUG 2024-06-22 05:25:32,240 INFO - Download file (size 4k) 2024-06-22 05:25:32,240 INFO 2024-06-22 05:25:32,241 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 05:25:32,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:32,556 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:32,556 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:32,556 DEBUG --- stdout --- 2024-06-22 05:25:32,556 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:32,557 DEBUG --- stderr --- 2024-06-22 05:25:32,557 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 05:25:32,557 INFO 2024-06-22 05:25:32,557 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 05:25:32,557 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 05:25:32,872 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 05:25:32,872 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:32,872 DEBUG --- stdout --- 2024-06-22 05:25:32,872 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 05:25:32,872 DEBUG --- stderr --- 2024-06-22 05:25:32,872 DEBUG 2024-06-22 05:25:32,873 INFO 2024-06-22 05:25:32,873 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 05:25:32,873 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 05:25:33,617 INFO [loop_until]: OK (rc = 0) 2024-06-22 05:25:33,617 DEBUG --- stdout --- 2024-06-22 05:25:33,617 DEBUG tar: Removing leading `/' from member names 2024-06-22 05:25:33,617 DEBUG --- stderr --- 2024-06-22 05:25:33,617 DEBUG 2024-06-22 05:25:33,618 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 05:25:33,618 DEBUG ----- output ----- 2024-06-22 05:25:33,618 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-p1" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-22 05:25:33,618 INFO ________________________________________________________________________________ [2024-06-22 05:25:33] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 05:25:33,619 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 05:25:33,619 DEBUG ----- output ----- 2024-06-22 05:25:33,619 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 05:25:33,619 INFO ________________________________________________________________________________ [2024-06-22 05:25:33] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 05:25:33,623 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 21885 lines): 2024-06-22 05:25:33,623 DEBUG ----- output ----- 2024-06-22 05:25:33,623 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 05:25:33,623 INFO 05:22:56.141 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-22 05:25:33,623 INFO 05:23:00.710 [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 05:25:33,623 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 05:25:33,623 INFO ^ 2024-06-22 05:25:33,623 INFO 05:23:00.713 [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 05:25:33,623 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 05:25:33,623 INFO ^ 2024-06-22 05:25:33,623 INFO 05:23:02.393 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-22 05:25:33,623 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 05:25:33,623 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 05:25:33,623 INFO 2024-06-22 05:25:33,623 INFO ================================================================================ 2024-06-22 05:25:33,623 INFO 2024-06-22 05:23:10 5s elapsed 2024-06-22 05:25:33,623 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,623 INFO > Global (OK=155 KO=0 ) 2024-06-22 05:25:33,623 INFO > protectLoginInitiate (OK=41 KO=0 ) 2024-06-22 05:25:33,623 INFO > PingOneProtectInitializeCallback (OK=40 KO=0 ) 2024-06-22 05:25:33,623 INFO > restLoginUsernamePasswordCallback (OK=39 KO=0 ) 2024-06-22 05:25:33,623 INFO > PingOneProtectEvaluationCallback (OK=35 KO=0 ) 2024-06-22 05:25:33,623 INFO 2024-06-22 05:25:33,623 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,623 INFO [- ] 0% 2024-06-22 05:25:33,623 INFO waiting: 361871 / active: 7 / done: 35 2024-06-22 05:25:33,623 INFO ================================================================================ 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO ================================================================================ 2024-06-22 05:25:33,624 INFO 2024-06-22 05:23:15 10s elapsed 2024-06-22 05:25:33,624 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,624 INFO > Global (OK=644 KO=0 ) 2024-06-22 05:25:33,624 INFO > protectLoginInitiate (OK=165 KO=0 ) 2024-06-22 05:25:33,624 INFO > PingOneProtectInitializeCallback (OK=164 KO=0 ) 2024-06-22 05:25:33,624 INFO > restLoginUsernamePasswordCallback (OK=162 KO=0 ) 2024-06-22 05:25:33,624 INFO > PingOneProtectEvaluationCallback (OK=153 KO=0 ) 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,624 INFO [- ] 0% 2024-06-22 05:25:33,624 INFO waiting: 361746 / active: 14 / done: 153 2024-06-22 05:25:33,624 INFO ================================================================================ 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO ================================================================================ 2024-06-22 05:25:33,624 INFO 2024-06-22 05:23:20 15s elapsed 2024-06-22 05:25:33,624 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,624 INFO > Global (OK=1466 KO=0 ) 2024-06-22 05:25:33,624 INFO > protectLoginInitiate (OK=373 KO=0 ) 2024-06-22 05:25:33,624 INFO > PingOneProtectInitializeCallback (OK=371 KO=0 ) 2024-06-22 05:25:33,624 INFO > restLoginUsernamePasswordCallback (OK=368 KO=0 ) 2024-06-22 05:25:33,624 INFO > PingOneProtectEvaluationCallback (OK=354 KO=0 ) 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,624 INFO [- ] 0% 2024-06-22 05:25:33,624 INFO waiting: 361537 / active: 22 / done: 354 2024-06-22 05:25:33,624 INFO ================================================================================ 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO 2024-06-22 05:25:33,624 INFO ================================================================================ 2024-06-22 05:25:33,624 INFO 2024-06-22 05:23:25 20s elapsed 2024-06-22 05:25:33,624 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,624 INFO > Global (OK=2621 KO=0 ) 2024-06-22 05:25:33,624 INFO > protectLoginInitiate (OK=664 KO=0 ) 2024-06-22 05:25:33,624 INFO > PingOneProtectInitializeCallback (OK=661 KO=0 ) 2024-06-22 05:25:33,624 INFO > restLoginUsernamePasswordCallback (OK=657 KO=0 ) 2024-06-22 05:25:33,624 INFO > PingOneProtectEvaluationCallback (OK=639 KO=0 ) 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,625 INFO [- ] 0% 2024-06-22 05:25:33,625 INFO waiting: 361246 / active: 28 / done: 639 2024-06-22 05:25:33,625 INFO ================================================================================ 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO ================================================================================ 2024-06-22 05:25:33,625 INFO 2024-06-22 05:23:30 25s elapsed 2024-06-22 05:25:33,625 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,625 INFO > Global (OK=4110 KO=0 ) 2024-06-22 05:25:33,625 INFO > protectLoginInitiate (OK=1038 KO=0 ) 2024-06-22 05:25:33,625 INFO > PingOneProtectInitializeCallback (OK=1034 KO=0 ) 2024-06-22 05:25:33,625 INFO > restLoginUsernamePasswordCallback (OK=1030 KO=0 ) 2024-06-22 05:25:33,625 INFO > PingOneProtectEvaluationCallback (OK=1008 KO=0 ) 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,625 INFO [- ] 0% 2024-06-22 05:25:33,625 INFO waiting: 360871 / active: 34 / done: 1008 2024-06-22 05:25:33,625 INFO ================================================================================ 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO ================================================================================ 2024-06-22 05:25:33,625 INFO 2024-06-22 05:23:35 30s elapsed 2024-06-22 05:25:33,625 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,625 INFO > Global (OK=5930 KO=0 ) 2024-06-22 05:25:33,625 INFO > protectLoginInitiate (OK=1496 KO=0 ) 2024-06-22 05:25:33,625 INFO > PingOneProtectInitializeCallback (OK=1492 KO=0 ) 2024-06-22 05:25:33,625 INFO > restLoginUsernamePasswordCallback (OK=1484 KO=0 ) 2024-06-22 05:25:33,625 INFO > PingOneProtectEvaluationCallback (OK=1458 KO=0 ) 2024-06-22 05:25:33,625 INFO 2024-06-22 05:25:33,625 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,625 INFO [- ] 0% 2024-06-22 05:25:33,625 INFO waiting: 360412 / active: 43 / done: 1458 2024-06-22 05:25:33,625 INFO ================================================================================ 2024-06-22 05:25:33,625 INFO [...] 2024-06-22 05:25:33,631 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,631 INFO > Global (OK=1453320 KO=66 ) 2024-06-22 05:25:33,632 INFO > protectLoginInitiate (OK=361439 KO=4 ) 2024-06-22 05:25:33,632 INFO > PingOneProtectInitializeCallback (OK=361427 KO=4 ) 2024-06-22 05:25:33,632 INFO > restLoginUsernamePasswordCallback (OK=361415 KO=6 ) 2024-06-22 05:25:33,632 INFO > PingOneProtectEvaluationCallback (OK=361360 KO=28 ) 2024-06-22 05:25:33,632 INFO > restloginProgressiveProfileCallback (OK=7679 KO=24 ) 2024-06-22 05:25:33,632 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 05:25:33,632 INFO > jsonPath($.tokenId).find.exists, found nothing 24 (29.27%) 2024-06-22 05:25:33,632 INFO > status.find.is(200), but actually found 502 22 (26.83%) 2024-06-22 05:25:33,632 INFO > status.find.is(200), but actually found 401 20 (24.39%) 2024-06-22 05:25:33,632 INFO > PingOneProtectInitializeCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,632 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,632 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,632 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,632 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,632 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,632 INFO > restloginProgressiveProfileCallback: Failed to build request: 4 ( 4.88%) 2024-06-22 05:25:33,632 INFO No attribute named 'callbacks' is defined 2024-06-22 05:25:33,632 INFO 2024-06-22 05:25:33,632 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,632 INFO [#########################################################################-] 99% 2024-06-22 05:25:33,632 INFO waiting: 465 / active: 57 / done: 361391 2024-06-22 05:25:33,632 INFO ================================================================================ 2024-06-22 05:25:33,632 INFO 2024-06-22 05:25:33,632 INFO 2024-06-22 05:25:33,632 INFO ================================================================================ 2024-06-22 05:25:33,632 INFO 2024-06-22 06:23:35 3630s elapsed 2024-06-22 05:25:33,632 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,632 INFO > Global (OK=1455250 KO=66 ) 2024-06-22 05:25:33,632 INFO > protectLoginInitiate (OK=361902 KO=4 ) 2024-06-22 05:25:33,632 INFO > PingOneProtectInitializeCallback (OK=361895 KO=4 ) 2024-06-22 05:25:33,632 INFO > restLoginUsernamePasswordCallback (OK=361890 KO=6 ) 2024-06-22 05:25:33,632 INFO > PingOneProtectEvaluationCallback (OK=361855 KO=28 ) 2024-06-22 05:25:33,632 INFO > restloginProgressiveProfileCallback (OK=7708 KO=24 ) 2024-06-22 05:25:33,632 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 05:25:33,632 INFO > jsonPath($.tokenId).find.exists, found nothing 24 (29.27%) 2024-06-22 05:25:33,632 INFO > status.find.is(200), but actually found 502 22 (26.83%) 2024-06-22 05:25:33,632 INFO > status.find.is(200), but actually found 401 20 (24.39%) 2024-06-22 05:25:33,632 INFO > PingOneProtectInitializeCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,632 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,632 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO > restloginProgressiveProfileCallback: Failed to build request: 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO No attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO 2024-06-22 05:25:33,633 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,633 INFO [#########################################################################-] 99% 2024-06-22 05:25:33,633 INFO waiting: 0 / active: 27 / done: 361886 2024-06-22 05:25:33,633 INFO ================================================================================ 2024-06-22 05:25:33,633 INFO 2024-06-22 05:25:33,633 INFO 2024-06-22 05:25:33,633 INFO ================================================================================ 2024-06-22 05:25:33,633 INFO 2024-06-22 06:23:35 3630s elapsed 2024-06-22 05:25:33,633 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 05:25:33,633 INFO > Global (OK=1455308 KO=66 ) 2024-06-22 05:25:33,633 INFO > protectLoginInitiate (OK=361909 KO=4 ) 2024-06-22 05:25:33,633 INFO > PingOneProtectInitializeCallback (OK=361905 KO=4 ) 2024-06-22 05:25:33,633 INFO > restLoginUsernamePasswordCallback (OK=361903 KO=6 ) 2024-06-22 05:25:33,633 INFO > PingOneProtectEvaluationCallback (OK=361881 KO=28 ) 2024-06-22 05:25:33,633 INFO > restloginProgressiveProfileCallback (OK=7710 KO=24 ) 2024-06-22 05:25:33,633 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 05:25:33,633 INFO > jsonPath($.tokenId).find.exists, found nothing 24 (29.27%) 2024-06-22 05:25:33,633 INFO > status.find.is(200), but actually found 502 22 (26.83%) 2024-06-22 05:25:33,633 INFO > status.find.is(200), but actually found 401 20 (24.39%) 2024-06-22 05:25:33,633 INFO > PingOneProtectInitializeCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO > restloginProgressiveProfileCallback: Failed to build request: 4 ( 4.88%) 2024-06-22 05:25:33,633 INFO No attribute named 'callbacks' is defined 2024-06-22 05:25:33,633 INFO 2024-06-22 05:25:33,633 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 05:25:33,633 INFO [##########################################################################]100% 2024-06-22 05:25:33,633 INFO waiting: 0 / active: 0 / done: 361913 2024-06-22 05:25:33,633 INFO ================================================================================ 2024-06-22 05:25:33,633 INFO 2024-06-22 05:25:33,633 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 05:25:33,634 INFO 2024-06-22 05:25:33,634 INFO ---------------------- 2024-06-22 05:25:33,634 INFO [22/Jun/2024 06:23:35] Create tar file login-tree-100.tar.gz 2024-06-22 05:25:33,634 INFO ---------------------- 2024-06-22 05:25:33,634 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 05:25:33,634 INFO RESULT_FOLDER=login-tree-100 2024-06-22 05:25:33,634 INFO SIMULATION_PATH=login-tree-100/loadtest-20240622042304506 2024-06-22 05:25:33,634 INFO PWD=/results/gatling/login-tree-100/loadtest-20240622042304506 2024-06-22 05:25:33,634 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-06-22 05:25:33,634 INFO /results/gatling 2024-06-22 05:25:33,634 INFO 2024-06-22 05:25:33,634 INFO ---------------------- 2024-06-22 05:25:33,634 INFO [22/Jun/2024 06:23:35] Update /results/gatling//latest 2024-06-22 05:25:33,634 INFO ---------------------- 2024-06-22 05:25:33,634 INFO 2024-06-22 05:25:33,634 INFO ---------------------- 2024-06-22 05:25:33,634 INFO [22/Jun/2024 06:23:35] Update /results/gatling//latest.tar.gz 2024-06-22 05:25:33,634 INFO ---------------------- 2024-06-22 05:25:33,634 INFO ________________________________________________________________________________ [2024-06-22 05:25:33] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 05:25:33,635 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 05:25:33,635 DEBUG ----- output ----- 2024-06-22 05:25:33,635 DEBUG 2024-06-22 05:25:33,635 INFO ________________________________________________________________________________ [2024-06-22 05:25:33] 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 05:25:33] 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 05:25:33] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped