--Task-- name: login-tree-10 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: [] preceding_task: None options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 10, '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 03:20:06] login-tree-10 pre : N/A ________________________________________________________________________________ 2024-06-22 03:20:06 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 03:20:06 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-10/stdout.txt Pod log : /results/orders/login-tree-10 2024-06-22 03:20:06,263 INFO 2024-06-22 03:20:06,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-22 03:20:06,263 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 03:20:06,580 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 03:20:06,580 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:06,580 DEBUG --- stdout --- 2024-06-22 03:20:06,581 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 03:20:06,581 DEBUG --- stderr --- 2024-06-22 03:20:06,581 DEBUG 2024-06-22 03:20:06 - INFO: Get overseer pod resources requests memory 2024-06-22 03:20:06,581 INFO 2024-06-22 03:20:06,581 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-5b6f9464fd-slbm8 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-06-22 03:20:06,581 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 03:20:06,916 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:06,917 DEBUG --- stdout --- 2024-06-22 03:20:06,917 DEBUG 4Gi 2024-06-22 03:20:06,917 DEBUG --- stderr --- 2024-06-22 03:20:06,917 DEBUG ________________________________________________________________________________ [2024-06-22 03:20:06] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 03:20:06,917 INFO 2024-06-22 03:20:06,917 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 03:20:06,918 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 03:20:07,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 03:20:07,238 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:07,238 DEBUG --- stdout --- 2024-06-22 03:20:07,238 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 03:20:07,238 DEBUG --- stderr --- 2024-06-22 03:20:07,238 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-10 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 03:20:07] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 03:20:07,239 INFO 2024-06-22 03:20:07,239 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 03:20:07,239 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 03:20:07,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 03:20:07,568 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:07,569 DEBUG --- stdout --- 2024-06-22 03:20:07,569 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 03:20:07,569 DEBUG --- stderr --- 2024-06-22 03:20:07,569 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json { "name": "login-tree-10", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.loginProtect@ema-perf-p1\" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-10", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login_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=10 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 03:20:07] login-tree-10 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-22 03:20:07,571 INFO 2024-06-22 03:20:07,571 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 03:20:07,571 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 03:20:07,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 03:20:07,869 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:07,869 DEBUG --- stdout --- 2024-06-22 03:20:07,869 DEBUG WAITING 2024-06-22 03:20:07,869 DEBUG --- stderr --- 2024-06-22 03:20:07,869 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 26 0 --:--:-- --:--:-- --:--:-- 26 ________________________________________________________________________________ [2024-06-22 03:20:07] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 03:20:07,869 INFO 2024-06-22 03:20:07,869 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 03:20:07,870 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 03:20:08,187 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 03:20:08,187 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:08,187 DEBUG --- stdout --- 2024-06-22 03:20:08,187 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 03:20:08,187 DEBUG --- stderr --- 2024-06-22 03:20:08,187 DEBUG 2024-06-22 03:20:08,188 INFO 2024-06-22 03:20:08,188 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 03:20:08,188 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 03:20:09,573 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:09,573 DEBUG --- stdout --- 2024-06-22 03:20:09,574 DEBUG 2024-06-22 03:20:09,574 DEBUG --- stderr --- 2024-06-22 03:20:09,574 DEBUG Order has been successfully forwarded to overseer 2024-06-22 03:20:09 - INFO: Check if Gatling simulation started successfully 2024-06-22 03:20:09,574 INFO 2024-06-22 03:20:09,574 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 03:20:09,574 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 03:20:09,880 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 03:20:09,880 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:09,880 DEBUG --- stdout --- 2024-06-22 03:20:09,880 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 03:20:09,880 DEBUG --- stderr --- 2024-06-22 03:20:09,880 DEBUG 2024-06-22 03:20:09,881 INFO 2024-06-22 03:20:09,881 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-10/stdout.txt | grep "started..." 2024-06-22 03:20:09,881 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 03:20:10,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 03:20:16,351 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-22 03:20:22,100 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-22 03:20:22,100 INFO [loop_until]: OK (rc = 0) 2024-06-22 03:20:22,101 DEBUG --- stdout --- 2024-06-22 03:20:22,101 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 04:20:11.820 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 04:20:16.485 [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"); ^ 04:20:16.488 [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"); ^ 04:20:18.197 [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 03:20:22,101 DEBUG --- stderr --- 2024-06-22 03:20:22,101 DEBUG ________________________________________________________________________________ [2024-06-22 03:20:22] login-tree-10 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-22 04:22:32] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-22 04:22:32,266 INFO 2024-06-22 04:22:32,266 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:32,266 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:32,590 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:32,590 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:32,590 DEBUG --- stdout --- 2024-06-22 04:22:32,590 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:32,590 DEBUG --- stderr --- 2024-06-22 04:22:32,590 DEBUG 2024-06-22 04:22:32,591 INFO 2024-06-22 04:22:32,591 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 04:22:32,591 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 04:22:33,374 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:33,374 DEBUG --- stdout --- 2024-06-22 04:22:33,375 DEBUG 2024-06-22 04:22:33,375 DEBUG --- stderr --- 2024-06-22 04:22:33,375 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-22 04:22:33] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-22 04:22:33 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json 2024-06-22 04:22:33,376 INFO 2024-06-22 04:22:33,376 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:33,376 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:33,682 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:33,683 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:33,683 DEBUG --- stdout --- 2024-06-22 04:22:33,683 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:33,683 DEBUG --- stderr --- 2024-06-22 04:22:33,683 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 04:22:33,683 INFO 2024-06-22 04:22:33,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 04:22:33,683 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:33,990 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:33,990 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:33,990 DEBUG --- stdout --- 2024-06-22 04:22:33,990 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:33,991 DEBUG --- stderr --- 2024-06-22 04:22:33,991 DEBUG 2024-06-22 04:22:33,991 INFO 2024-06-22 04:22:33,991 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/rc.txt" ]; then echo "/results/orders/login-tree-10/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/rc.txt file found" 2024-06-22 04:22:33,991 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 04:22:34,799 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 04:22:34,799 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:34,799 DEBUG --- stdout --- 2024-06-22 04:22:34,799 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-06-22 04:22:34,799 DEBUG --- stderr --- 2024-06-22 04:22:34,799 DEBUG 2024-06-22 04:22:34,800 INFO 2024-06-22 04:22:34,800 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:34,800 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:35,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:35,176 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:35,176 DEBUG --- stdout --- 2024-06-22 04:22:35,176 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:35,176 DEBUG --- stderr --- 2024-06-22 04:22:35,176 DEBUG 2024-06-22 04:22:35,177 INFO 2024-06-22 04:22:35,177 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/rc.txt 2024-06-22 04:22:36,006 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 04:22:36,007 DEBUG --- stdout --- 2024-06-22 04:22:36,007 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-06-22 04:22:36,007 DEBUG --- stderr --- 2024-06-22 04:22:36,007 DEBUG 2024-06-22 04:22:36,007 INFO - Download file (size 4k) 2024-06-22 04:22:36,007 INFO 2024-06-22 04:22:36,007 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:36,007 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:36,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:36,331 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:36,331 DEBUG --- stdout --- 2024-06-22 04:22:36,331 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:36,331 DEBUG --- stderr --- 2024-06-22 04:22:36,331 DEBUG source : /results/orders/login-tree-10/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-22 04:22:36,332 INFO 2024-06-22 04:22:36,332 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:36,332 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:36,653 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:36,654 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:36,654 DEBUG --- stdout --- 2024-06-22 04:22:36,654 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:36,654 DEBUG --- stderr --- 2024-06-22 04:22:36,654 DEBUG 2024-06-22 04:22:36,654 INFO 2024-06-22 04:22:36,655 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-22 04:22:36,655 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 04:22:37,450 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:37,450 DEBUG --- stdout --- 2024-06-22 04:22:37,450 DEBUG tar: Removing leading `/' from member names 2024-06-22 04:22:37,450 DEBUG --- stderr --- 2024-06-22 04:22:37,450 DEBUG 2024-06-22 04:22:37,451 INFO 2024-06-22 04:22:37,451 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:37,451 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:37,760 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:37,760 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:37,760 DEBUG --- stdout --- 2024-06-22 04:22:37,760 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:37,760 DEBUG --- stderr --- 2024-06-22 04:22:37,760 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 04:22:37,760 INFO 2024-06-22 04:22:37,760 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:37,760 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:38,068 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:38,068 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:38,068 DEBUG --- stdout --- 2024-06-22 04:22:38,068 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:38,068 DEBUG --- stderr --- 2024-06-22 04:22:38,068 DEBUG 2024-06-22 04:22:38,069 INFO 2024-06-22 04:22:38,069 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stderr.txt" ]; then echo "/results/orders/login-tree-10/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stderr.txt file found" 2024-06-22 04:22:38,069 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 04:22:38,874 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 04:22:38,874 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:38,874 DEBUG --- stdout --- 2024-06-22 04:22:38,874 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-06-22 04:22:38,875 DEBUG --- stderr --- 2024-06-22 04:22:38,875 DEBUG 2024-06-22 04:22:38,875 INFO 2024-06-22 04:22:38,875 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:38,875 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:39,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:39,193 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:39,194 DEBUG --- stdout --- 2024-06-22 04:22:39,194 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:39,194 DEBUG --- stderr --- 2024-06-22 04:22:39,194 DEBUG 2024-06-22 04:22:39,194 INFO 2024-06-22 04:22:39,194 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/stderr.txt 2024-06-22 04:22:40,013 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 04:22:40,014 DEBUG --- stdout --- 2024-06-22 04:22:40,014 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-06-22 04:22:40,014 DEBUG --- stderr --- 2024-06-22 04:22:40,014 DEBUG 2024-06-22 04:22:40,014 INFO - Download file (size 0k) 2024-06-22 04:22:40,015 INFO 2024-06-22 04:22:40,015 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:40,015 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:40,340 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:40,340 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:40,340 DEBUG --- stdout --- 2024-06-22 04:22:40,340 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:40,340 DEBUG --- stderr --- 2024-06-22 04:22:40,340 DEBUG source : /results/orders/login-tree-10/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-22 04:22:40,340 INFO 2024-06-22 04:22:40,341 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:40,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:40,663 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:40,664 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:40,664 DEBUG --- stdout --- 2024-06-22 04:22:40,664 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:40,664 DEBUG --- stderr --- 2024-06-22 04:22:40,664 DEBUG 2024-06-22 04:22:40,664 INFO 2024-06-22 04:22:40,664 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-22 04:22:40,664 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 04:22:41,398 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:41,399 DEBUG --- stdout --- 2024-06-22 04:22:41,399 DEBUG tar: Removing leading `/' from member names 2024-06-22 04:22:41,399 DEBUG --- stderr --- 2024-06-22 04:22:41,399 DEBUG 2024-06-22 04:22:41,399 INFO 2024-06-22 04:22:41,399 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:41,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:41,716 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:41,716 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:41,716 DEBUG --- stdout --- 2024-06-22 04:22:41,716 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:41,716 DEBUG --- stderr --- 2024-06-22 04:22:41,716 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 04:22:41,717 INFO 2024-06-22 04:22:41,717 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:41,717 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:42,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:42,035 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:42,035 DEBUG --- stdout --- 2024-06-22 04:22:42,035 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:42,035 DEBUG --- stderr --- 2024-06-22 04:22:42,035 DEBUG 2024-06-22 04:22:42,035 INFO 2024-06-22 04:22:42,036 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stdout.txt" ]; then echo "/results/orders/login-tree-10/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stdout.txt file found" 2024-06-22 04:22:42,036 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 04:22:42,852 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 04:22:42,853 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:42,854 DEBUG --- stdout --- 2024-06-22 04:22:42,855 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-06-22 04:22:42,855 DEBUG --- stderr --- 2024-06-22 04:22:42,855 DEBUG 2024-06-22 04:22:42,855 INFO 2024-06-22 04:22:42,855 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:42,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:43,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:43,179 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:43,179 DEBUG --- stdout --- 2024-06-22 04:22:43,179 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:43,179 DEBUG --- stderr --- 2024-06-22 04:22:43,179 DEBUG 2024-06-22 04:22:43,179 INFO 2024-06-22 04:22:43,179 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/stdout.txt 2024-06-22 04:22:44,001 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 04:22:44,001 DEBUG --- stdout --- 2024-06-22 04:22:44,001 DEBUG 964 /results/orders/login-tree-10/stdout.txt 2024-06-22 04:22:44,001 DEBUG --- stderr --- 2024-06-22 04:22:44,001 DEBUG 2024-06-22 04:22:44,001 INFO - Download file (size 964k) 2024-06-22 04:22:44,002 INFO 2024-06-22 04:22:44,002 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:44,002 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:44,315 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:44,315 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:44,315 DEBUG --- stdout --- 2024-06-22 04:22:44,315 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:44,315 DEBUG --- stderr --- 2024-06-22 04:22:44,315 DEBUG source : /results/orders/login-tree-10/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-22 04:22:44,316 INFO 2024-06-22 04:22:44,316 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:44,316 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:44,633 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:44,634 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:44,634 DEBUG --- stdout --- 2024-06-22 04:22:44,634 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:44,634 DEBUG --- stderr --- 2024-06-22 04:22:44,634 DEBUG 2024-06-22 04:22:44,634 INFO 2024-06-22 04:22:44,635 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-22 04:22:44,635 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 04:22:45,758 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:45,758 DEBUG --- stdout --- 2024-06-22 04:22:45,758 DEBUG tar: Removing leading `/' from member names 2024-06-22 04:22:45,758 DEBUG --- stderr --- 2024-06-22 04:22:45,758 DEBUG ________________________________________________________________________________ [2024-06-22 04:22:45] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-06-22 04:22:45,759 INFO 2024-06-22 04:22:45,759 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 04:22:45,759 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:46,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:46,081 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:46,081 DEBUG --- stdout --- 2024-06-22 04:22:46,081 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:46,081 DEBUG --- stderr --- 2024-06-22 04:22:46,081 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-22 04:22:46,081 INFO 2024-06-22 04:22:46,081 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:46,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:46,400 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:46,400 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:46,400 DEBUG --- stdout --- 2024-06-22 04:22:46,400 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:46,400 DEBUG --- stderr --- 2024-06-22 04:22:46,400 DEBUG 2024-06-22 04:22:46,401 INFO 2024-06-22 04:22:46,401 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/cmd.txt" ]; then echo "/results/orders/login-tree-10/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/cmd.txt file found" 2024-06-22 04:22:46,401 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 04:22:47,219 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 04:22:47,220 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:47,220 DEBUG --- stdout --- 2024-06-22 04:22:47,220 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-06-22 04:22:47,220 DEBUG --- stderr --- 2024-06-22 04:22:47,220 DEBUG 2024-06-22 04:22:47,220 INFO 2024-06-22 04:22:47,220 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:47,220 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:47,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:47,542 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:47,542 DEBUG --- stdout --- 2024-06-22 04:22:47,542 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:47,542 DEBUG --- stderr --- 2024-06-22 04:22:47,542 DEBUG 2024-06-22 04:22:47,542 INFO 2024-06-22 04:22:47,543 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-10/cmd.txt 2024-06-22 04:22:48,356 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-22 04:22:48,357 DEBUG --- stdout --- 2024-06-22 04:22:48,358 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-06-22 04:22:48,359 DEBUG --- stderr --- 2024-06-22 04:22:48,359 DEBUG 2024-06-22 04:22:48,359 INFO - Download file (size 4k) 2024-06-22 04:22:48,359 INFO 2024-06-22 04:22:48,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-22 04:22:48,360 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:48,680 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:48,681 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:48,681 DEBUG --- stdout --- 2024-06-22 04:22:48,681 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:48,681 DEBUG --- stderr --- 2024-06-22 04:22:48,681 DEBUG source : /results/orders/login-tree-10/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-22 04:22:48,681 INFO 2024-06-22 04:22:48,681 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:48,681 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 04:22:49,000 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 04:22:49,000 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:49,000 DEBUG --- stdout --- 2024-06-22 04:22:49,000 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 04:22:49,000 DEBUG --- stderr --- 2024-06-22 04:22:49,000 DEBUG 2024-06-22 04:22:49,001 INFO 2024-06-22 04:22:49,001 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-10/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-22 04:22:49,001 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-22 04:22:49,742 INFO [loop_until]: OK (rc = 0) 2024-06-22 04:22:49,742 DEBUG --- stdout --- 2024-06-22 04:22:49,742 DEBUG tar: Removing leading `/' from member names 2024-06-22 04:22:49,742 DEBUG --- stderr --- 2024-06-22 04:22:49,742 DEBUG 2024-06-22 04:22:49,743 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt is small (1 lines), printing all lines: 2024-06-22 04:22:49,743 DEBUG ----- output ----- 2024-06-22 04:22:49,743 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-10 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-22 04:22:49,743 INFO ________________________________________________________________________________ [2024-06-22 04:22:49] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-06-22 04:22:49,743 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt is small (1 lines), printing all lines: 2024-06-22 04:22:49,743 DEBUG ----- output ----- 2024-06-22 04:22:49,743 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-22 04:22:49,743 INFO ________________________________________________________________________________ [2024-06-22 04:22:49] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-22 04:22:49,746 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt is 14151 lines): 2024-06-22 04:22:49,746 DEBUG ----- output ----- 2024-06-22 04:22:49,746 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-22 04:22:49,746 INFO 04:20:11.820 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-22 04:22:49,746 INFO 04:20:16.485 [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 04:22:49,746 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 04:22:49,746 INFO ^ 2024-06-22 04:22:49,746 INFO 04:20:16.488 [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 04:22:49,746 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-22 04:22:49,746 INFO ^ 2024-06-22 04:22:49,746 INFO 04:20:18.197 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-22 04:22:49,746 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-22 04:22:49,746 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 04:22:49,746 INFO 2024-06-22 04:22:49,746 INFO ================================================================================ 2024-06-22 04:22:49,746 INFO 2024-06-22 04:20:25 5s elapsed 2024-06-22 04:22:49,746 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,746 INFO > Global (OK=15 KO=0 ) 2024-06-22 04:22:49,746 INFO > protectLoginInitiate (OK=4 KO=0 ) 2024-06-22 04:22:49,746 INFO > PingOneProtectInitializeCallback (OK=4 KO=0 ) 2024-06-22 04:22:49,746 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-06-22 04:22:49,746 INFO > PingOneProtectEvaluationCallback (OK=3 KO=0 ) 2024-06-22 04:22:49,746 INFO 2024-06-22 04:22:49,746 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,746 INFO [- ] 0% 2024-06-22 04:22:49,746 INFO waiting: 36008 / active: 2 / done: 3 2024-06-22 04:22:49,747 INFO ================================================================================ 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO ================================================================================ 2024-06-22 04:22:49,747 INFO 2024-06-22 04:20:30 10s elapsed 2024-06-22 04:22:49,747 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,747 INFO > Global (OK=63 KO=0 ) 2024-06-22 04:22:49,747 INFO > protectLoginInitiate (OK=16 KO=0 ) 2024-06-22 04:22:49,747 INFO > PingOneProtectInitializeCallback (OK=16 KO=0 ) 2024-06-22 04:22:49,747 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-06-22 04:22:49,747 INFO > PingOneProtectEvaluationCallback (OK=15 KO=0 ) 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,747 INFO [- ] 0% 2024-06-22 04:22:49,747 INFO waiting: 35996 / active: 2 / done: 15 2024-06-22 04:22:49,747 INFO ================================================================================ 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO ================================================================================ 2024-06-22 04:22:49,747 INFO 2024-06-22 04:20:35 15s elapsed 2024-06-22 04:22:49,747 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,747 INFO > Global (OK=146 KO=0 ) 2024-06-22 04:22:49,747 INFO > protectLoginInitiate (OK=37 KO=0 ) 2024-06-22 04:22:49,747 INFO > PingOneProtectInitializeCallback (OK=37 KO=0 ) 2024-06-22 04:22:49,747 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-06-22 04:22:49,747 INFO > PingOneProtectEvaluationCallback (OK=35 KO=0 ) 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,747 INFO [- ] 0% 2024-06-22 04:22:49,747 INFO waiting: 35975 / active: 3 / done: 35 2024-06-22 04:22:49,747 INFO ================================================================================ 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO 2024-06-22 04:22:49,747 INFO ================================================================================ 2024-06-22 04:22:49,747 INFO 2024-06-22 04:20:40 20s elapsed 2024-06-22 04:22:49,747 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,747 INFO > Global (OK=261 KO=0 ) 2024-06-22 04:22:49,747 INFO > protectLoginInitiate (OK=66 KO=0 ) 2024-06-22 04:22:49,747 INFO > PingOneProtectInitializeCallback (OK=66 KO=0 ) 2024-06-22 04:22:49,747 INFO > restLoginUsernamePasswordCallback (OK=65 KO=0 ) 2024-06-22 04:22:49,748 INFO > PingOneProtectEvaluationCallback (OK=64 KO=0 ) 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,748 INFO [- ] 0% 2024-06-22 04:22:49,748 INFO waiting: 35946 / active: 3 / done: 64 2024-06-22 04:22:49,748 INFO ================================================================================ 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO ================================================================================ 2024-06-22 04:22:49,748 INFO 2024-06-22 04:20:45 25s elapsed 2024-06-22 04:22:49,748 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,748 INFO > Global (OK=411 KO=0 ) 2024-06-22 04:22:49,748 INFO > protectLoginInitiate (OK=104 KO=0 ) 2024-06-22 04:22:49,748 INFO > PingOneProtectInitializeCallback (OK=104 KO=0 ) 2024-06-22 04:22:49,748 INFO > restLoginUsernamePasswordCallback (OK=103 KO=0 ) 2024-06-22 04:22:49,748 INFO > PingOneProtectEvaluationCallback (OK=100 KO=0 ) 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,748 INFO [- ] 0% 2024-06-22 04:22:49,748 INFO waiting: 35908 / active: 5 / done: 100 2024-06-22 04:22:49,748 INFO ================================================================================ 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO ================================================================================ 2024-06-22 04:22:49,748 INFO 2024-06-22 04:20:50 30s elapsed 2024-06-22 04:22:49,748 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,748 INFO > Global (OK=595 KO=0 ) 2024-06-22 04:22:49,748 INFO > protectLoginInitiate (OK=150 KO=0 ) 2024-06-22 04:22:49,748 INFO > PingOneProtectInitializeCallback (OK=150 KO=0 ) 2024-06-22 04:22:49,748 INFO > restLoginUsernamePasswordCallback (OK=149 KO=0 ) 2024-06-22 04:22:49,748 INFO > PingOneProtectEvaluationCallback (OK=146 KO=0 ) 2024-06-22 04:22:49,748 INFO 2024-06-22 04:22:49,748 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,748 INFO [- ] 0% 2024-06-22 04:22:49,748 INFO waiting: 35863 / active: 4 / done: 146 2024-06-22 04:22:49,748 INFO ================================================================================ 2024-06-22 04:22:49,748 INFO [...] 2024-06-22 04:22:49,752 INFO 2024-06-22 04:22:49,752 INFO 2024-06-22 04:22:49,752 INFO ================================================================================ 2024-06-22 04:22:49,752 INFO 2024-06-22 05:20:40 3620s elapsed 2024-06-22 04:22:49,752 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,752 INFO > Global (OK=143699 KO=10 ) 2024-06-22 04:22:49,752 INFO > protectLoginInitiate (OK=35926 KO=0 ) 2024-06-22 04:22:49,752 INFO > PingOneProtectInitializeCallback (OK=35923 KO=1 ) 2024-06-22 04:22:49,752 INFO > restLoginUsernamePasswordCallback (OK=35922 KO=2 ) 2024-06-22 04:22:49,752 INFO > PingOneProtectEvaluationCallback (OK=35918 KO=3 ) 2024-06-22 04:22:49,752 INFO > restloginProgressiveProfileCallback (OK=10 KO=4 ) 2024-06-22 04:22:49,752 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 04:22:49,752 INFO > jsonPath($.tokenId).find.exists, found nothing 4 (40.00%) 2024-06-22 04:22:49,752 INFO > status.find.is(200), but actually found 502 3 (30.00%) 2024-06-22 04:22:49,752 INFO > status.find.is(200), but actually found 401 3 (30.00%) 2024-06-22 04:22:49,752 INFO 2024-06-22 04:22:49,752 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,752 INFO [#########################################################################-] 99% 2024-06-22 04:22:49,753 INFO waiting: 85 / active: 7 / done: 35921 2024-06-22 04:22:49,753 INFO ================================================================================ 2024-06-22 04:22:49,753 INFO 2024-06-22 04:22:49,753 INFO 2024-06-22 04:22:49,753 INFO ================================================================================ 2024-06-22 04:22:49,753 INFO 2024-06-22 05:20:45 3625s elapsed 2024-06-22 04:22:49,753 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,753 INFO > Global (OK=143876 KO=10 ) 2024-06-22 04:22:49,753 INFO > protectLoginInitiate (OK=35969 KO=0 ) 2024-06-22 04:22:49,753 INFO > PingOneProtectInitializeCallback (OK=35968 KO=1 ) 2024-06-22 04:22:49,753 INFO > restLoginUsernamePasswordCallback (OK=35966 KO=2 ) 2024-06-22 04:22:49,753 INFO > PingOneProtectEvaluationCallback (OK=35963 KO=3 ) 2024-06-22 04:22:49,753 INFO > restloginProgressiveProfileCallback (OK=10 KO=4 ) 2024-06-22 04:22:49,753 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 04:22:49,753 INFO > jsonPath($.tokenId).find.exists, found nothing 4 (40.00%) 2024-06-22 04:22:49,753 INFO > status.find.is(200), but actually found 502 3 (30.00%) 2024-06-22 04:22:49,753 INFO > status.find.is(200), but actually found 401 3 (30.00%) 2024-06-22 04:22:49,753 INFO 2024-06-22 04:22:49,753 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,753 INFO [#########################################################################-] 99% 2024-06-22 04:22:49,753 INFO waiting: 44 / active: 3 / done: 35966 2024-06-22 04:22:49,753 INFO ================================================================================ 2024-06-22 04:22:49,753 INFO 2024-06-22 04:22:49,753 INFO 2024-06-22 04:22:49,753 INFO ================================================================================ 2024-06-22 04:22:49,753 INFO 2024-06-22 05:20:50 3630s elapsed 2024-06-22 04:22:49,753 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,753 INFO > Global (OK=144049 KO=10 ) 2024-06-22 04:22:49,753 INFO > protectLoginInitiate (OK=36013 KO=0 ) 2024-06-22 04:22:49,753 INFO > PingOneProtectInitializeCallback (OK=36012 KO=1 ) 2024-06-22 04:22:49,753 INFO > restLoginUsernamePasswordCallback (OK=36011 KO=2 ) 2024-06-22 04:22:49,753 INFO > PingOneProtectEvaluationCallback (OK=36003 KO=3 ) 2024-06-22 04:22:49,753 INFO > restloginProgressiveProfileCallback (OK=10 KO=4 ) 2024-06-22 04:22:49,753 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 04:22:49,753 INFO > jsonPath($.tokenId).find.exists, found nothing 4 (40.00%) 2024-06-22 04:22:49,753 INFO > status.find.is(200), but actually found 502 3 (30.00%) 2024-06-22 04:22:49,753 INFO > status.find.is(200), but actually found 401 3 (30.00%) 2024-06-22 04:22:49,753 INFO 2024-06-22 04:22:49,753 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,753 INFO [#########################################################################-] 99% 2024-06-22 04:22:49,753 INFO waiting: 0 / active: 7 / done: 36006 2024-06-22 04:22:49,754 INFO ================================================================================ 2024-06-22 04:22:49,754 INFO 2024-06-22 04:22:49,754 INFO 2024-06-22 04:22:49,754 INFO ================================================================================ 2024-06-22 04:22:49,754 INFO 2024-06-22 05:20:51 3630s elapsed 2024-06-22 04:22:49,754 INFO ---- Requests ------------------------------------------------------------------ 2024-06-22 04:22:49,754 INFO > Global (OK=144056 KO=10 ) 2024-06-22 04:22:49,754 INFO > protectLoginInitiate (OK=36013 KO=0 ) 2024-06-22 04:22:49,754 INFO > PingOneProtectInitializeCallback (OK=36012 KO=1 ) 2024-06-22 04:22:49,754 INFO > restLoginUsernamePasswordCallback (OK=36011 KO=2 ) 2024-06-22 04:22:49,754 INFO > PingOneProtectEvaluationCallback (OK=36010 KO=3 ) 2024-06-22 04:22:49,754 INFO > restloginProgressiveProfileCallback (OK=10 KO=4 ) 2024-06-22 04:22:49,754 INFO ---- Errors -------------------------------------------------------------------- 2024-06-22 04:22:49,754 INFO > jsonPath($.tokenId).find.exists, found nothing 4 (40.00%) 2024-06-22 04:22:49,754 INFO > status.find.is(200), but actually found 502 3 (30.00%) 2024-06-22 04:22:49,754 INFO > status.find.is(200), but actually found 401 3 (30.00%) 2024-06-22 04:22:49,754 INFO 2024-06-22 04:22:49,754 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-22 04:22:49,754 INFO [##########################################################################]100% 2024-06-22 04:22:49,754 INFO waiting: 0 / active: 0 / done: 36013 2024-06-22 04:22:49,754 INFO ================================================================================ 2024-06-22 04:22:49,754 INFO 2024-06-22 04:22:49,754 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-22 04:22:49,754 INFO 2024-06-22 04:22:49,754 INFO ---------------------- 2024-06-22 04:22:49,754 INFO [22/Jun/2024 05:20:51] Create tar file login-tree-10.tar.gz 2024-06-22 04:22:49,754 INFO ---------------------- 2024-06-22 04:22:49,754 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-22 04:22:49,754 INFO RESULT_FOLDER=login-tree-10 2024-06-22 04:22:49,755 INFO SIMULATION_PATH=login-tree-10/loadtest-20240622032020202 2024-06-22 04:22:49,755 INFO PWD=/results/gatling/login-tree-10/loadtest-20240622032020202 2024-06-22 04:22:49,755 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-06-22 04:22:49,755 INFO /results/gatling 2024-06-22 04:22:49,755 INFO 2024-06-22 04:22:49,755 INFO ---------------------- 2024-06-22 04:22:49,755 INFO [22/Jun/2024 05:20:51] Update /results/gatling//latest 2024-06-22 04:22:49,755 INFO ---------------------- 2024-06-22 04:22:49,755 INFO 2024-06-22 04:22:49,755 INFO ---------------------- 2024-06-22 04:22:49,755 INFO [22/Jun/2024 05:20:51] Update /results/gatling//latest.tar.gz 2024-06-22 04:22:49,755 INFO ---------------------- 2024-06-22 04:22:49,755 INFO ________________________________________________________________________________ [2024-06-22 04:22:49] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-22 04:22:49,756 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt is small (0 lines), printing all lines: 2024-06-22 04:22:49,756 DEBUG ----- output ----- 2024-06-22 04:22:49,756 DEBUG 2024-06-22 04:22:49,756 INFO ________________________________________________________________________________ [2024-06-22 04:22:49] login-tree-10 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-22 04:22:49] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-22 04:22:49] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped