--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-23 03:43:06] login-tree-10 pre : N/A ________________________________________________________________________________ 2024-06-23 03:43:06 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 03:43: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-23 03:43:06,342 INFO 2024-06-23 03:43:06,342 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 03:43:06,342 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 03:43:06,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 03:43:06,650 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:06,650 DEBUG --- stdout --- 2024-06-23 03:43:06,650 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 03:43:06,650 DEBUG --- stderr --- 2024-06-23 03:43:06,650 DEBUG 2024-06-23 03:43:06 - INFO: Get overseer pod resources requests memory 2024-06-23 03:43:06,650 INFO 2024-06-23 03:43:06,650 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-23 03:43:06,650 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 03:43:06,977 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:06,977 DEBUG --- stdout --- 2024-06-23 03:43:06,978 DEBUG 4Gi 2024-06-23 03:43:06,978 DEBUG --- stderr --- 2024-06-23 03:43:06,978 DEBUG ________________________________________________________________________________ [2024-06-23 03:43:06] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 03:43:06,978 INFO 2024-06-23 03:43:06,978 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 03:43:06,978 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 03:43:07,286 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 03:43:07,286 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:07,286 DEBUG --- stdout --- 2024-06-23 03:43:07,286 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 03:43:07,287 DEBUG --- stderr --- 2024-06-23 03:43:07,287 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-23 03:43:07] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 03:43:07,287 INFO 2024-06-23 03:43:07,287 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 03:43:07,287 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 03:43:07,596 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 03:43:07,596 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:07,596 DEBUG --- stdout --- 2024-06-23 03:43:07,596 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 03:43:07,596 DEBUG --- stderr --- 2024-06-23 03:43:07,596 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-23 03:43: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-23 03:43:07,598 INFO 2024-06-23 03:43:07,598 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 03:43:07,598 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 03:43:07,877 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 03:43:07,877 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:07,877 DEBUG --- stdout --- 2024-06-23 03:43:07,877 DEBUG WAITING 2024-06-23 03:43:07,877 DEBUG --- stderr --- 2024-06-23 03:43:07,877 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-06-23 03:43:07] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 03:43:07,878 INFO 2024-06-23 03:43:07,878 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 03:43:07,878 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 03:43:08,180 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 03:43:08,180 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:08,180 DEBUG --- stdout --- 2024-06-23 03:43:08,180 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 03:43:08,180 DEBUG --- stderr --- 2024-06-23 03:43:08,180 DEBUG 2024-06-23 03:43:08,181 INFO 2024-06-23 03:43:08,181 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-23 03:43:08,181 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 03:43:09,551 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:09,551 DEBUG --- stdout --- 2024-06-23 03:43:09,551 DEBUG 2024-06-23 03:43:09,551 DEBUG --- stderr --- 2024-06-23 03:43:09,551 DEBUG Order has been successfully forwarded to overseer 2024-06-23 03:43:09 - INFO: Check if Gatling simulation started successfully 2024-06-23 03:43:09,552 INFO 2024-06-23 03:43:09,552 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 03:43:09,552 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 03:43:09,855 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 03:43:09,855 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:09,855 DEBUG --- stdout --- 2024-06-23 03:43:09,855 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 03:43:09,855 DEBUG --- stderr --- 2024-06-23 03:43:09,855 DEBUG 2024-06-23 03:43:09,856 INFO 2024-06-23 03:43:09,856 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-23 03:43:09,856 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 03:43:10,590 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 03:43:16,328 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-23 03:43:22,086 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-23 03:43:22,086 INFO [loop_until]: OK (rc = 0) 2024-06-23 03:43:22,086 DEBUG --- stdout --- 2024-06-23 03:43:22,086 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 04:43:11.435 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 04:43:16.078 [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:43:16.081 [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:43:17.818 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 03:43:22,086 DEBUG --- stderr --- 2024-06-23 03:43:22,086 DEBUG ________________________________________________________________________________ [2024-06-23 03:43: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 01s, 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 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, 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 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-23 04:45:32] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 04:45:32,551 INFO 2024-06-23 04:45:32,551 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:32,551 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:32,879 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:32,879 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:32,879 DEBUG --- stdout --- 2024-06-23 04:45:32,879 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:32,879 DEBUG --- stderr --- 2024-06-23 04:45:32,879 DEBUG 2024-06-23 04:45:32,880 INFO 2024-06-23 04:45:32,880 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-23 04:45:32,880 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 04:45:33,652 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:33,652 DEBUG --- stdout --- 2024-06-23 04:45:33,652 DEBUG 2024-06-23 04:45:33,652 DEBUG --- stderr --- 2024-06-23 04:45:33,652 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 04:45:33] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 04:45: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-23 04:45:33,654 INFO 2024-06-23 04:45:33,654 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:33,654 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:33,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:33,962 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:33,962 DEBUG --- stdout --- 2024-06-23 04:45:33,962 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:33,962 DEBUG --- stderr --- 2024-06-23 04:45:33,962 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 04:45:33,962 INFO 2024-06-23 04:45:33,962 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:33,962 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:34,267 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:34,267 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:34,267 DEBUG --- stdout --- 2024-06-23 04:45:34,267 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:34,267 DEBUG --- stderr --- 2024-06-23 04:45:34,267 DEBUG 2024-06-23 04:45:34,268 INFO 2024-06-23 04:45:34,268 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-23 04:45:34,268 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 04:45:35,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 04:45:35,079 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:35,079 DEBUG --- stdout --- 2024-06-23 04:45:35,079 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-06-23 04:45:35,079 DEBUG --- stderr --- 2024-06-23 04:45:35,079 DEBUG 2024-06-23 04:45:35,080 INFO 2024-06-23 04:45:35,080 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:35,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:35,401 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:35,402 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:35,402 DEBUG --- stdout --- 2024-06-23 04:45:35,402 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:35,402 DEBUG --- stderr --- 2024-06-23 04:45:35,402 DEBUG 2024-06-23 04:45:35,402 INFO 2024-06-23 04:45:35,402 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-23 04:45:36,221 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 04:45:36,222 DEBUG --- stdout --- 2024-06-23 04:45:36,223 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-06-23 04:45:36,223 DEBUG --- stderr --- 2024-06-23 04:45:36,223 DEBUG 2024-06-23 04:45:36,223 INFO - Download file (size 4k) 2024-06-23 04:45:36,224 INFO 2024-06-23 04:45:36,224 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:36,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:36,530 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:36,530 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:36,530 DEBUG --- stdout --- 2024-06-23 04:45:36,531 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:36,531 DEBUG --- stderr --- 2024-06-23 04:45:36,531 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-23 04:45:36,531 INFO 2024-06-23 04:45:36,531 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:36,531 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:36,852 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:36,852 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:36,852 DEBUG --- stdout --- 2024-06-23 04:45:36,852 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:36,852 DEBUG --- stderr --- 2024-06-23 04:45:36,852 DEBUG 2024-06-23 04:45:36,852 INFO 2024-06-23 04:45:36,853 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-23 04:45:36,853 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 04:45:37,704 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:37,705 DEBUG --- stdout --- 2024-06-23 04:45:37,705 DEBUG tar: Removing leading `/' from member names 2024-06-23 04:45:37,705 DEBUG --- stderr --- 2024-06-23 04:45:37,705 DEBUG 2024-06-23 04:45:37,705 INFO 2024-06-23 04:45:37,705 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:37,705 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:38,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:38,027 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:38,027 DEBUG --- stdout --- 2024-06-23 04:45:38,027 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:38,028 DEBUG --- stderr --- 2024-06-23 04:45:38,028 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 04:45:38,028 INFO 2024-06-23 04:45:38,028 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:38,028 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:38,352 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:38,352 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:38,352 DEBUG --- stdout --- 2024-06-23 04:45:38,352 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:38,352 DEBUG --- stderr --- 2024-06-23 04:45:38,352 DEBUG 2024-06-23 04:45:38,353 INFO 2024-06-23 04:45:38,353 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-23 04:45:38,353 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 04:45:39,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 04:45:39,171 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:39,171 DEBUG --- stdout --- 2024-06-23 04:45:39,171 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-06-23 04:45:39,171 DEBUG --- stderr --- 2024-06-23 04:45:39,171 DEBUG 2024-06-23 04:45:39,171 INFO 2024-06-23 04:45:39,171 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:39,171 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:39,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:39,487 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:39,487 DEBUG --- stdout --- 2024-06-23 04:45:39,487 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:39,487 DEBUG --- stderr --- 2024-06-23 04:45:39,487 DEBUG 2024-06-23 04:45:39,487 INFO 2024-06-23 04:45:39,487 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-23 04:45:40,289 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 04:45:40,289 DEBUG --- stdout --- 2024-06-23 04:45:40,289 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-06-23 04:45:40,289 DEBUG --- stderr --- 2024-06-23 04:45:40,289 DEBUG 2024-06-23 04:45:40,289 INFO - Download file (size 0k) 2024-06-23 04:45:40,290 INFO 2024-06-23 04:45:40,290 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:40,290 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:40,606 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:40,607 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:40,607 DEBUG --- stdout --- 2024-06-23 04:45:40,607 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:40,607 DEBUG --- stderr --- 2024-06-23 04:45:40,607 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-23 04:45:40,608 INFO 2024-06-23 04:45:40,608 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:40,608 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:40,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:40,945 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:40,945 DEBUG --- stdout --- 2024-06-23 04:45:40,945 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:40,945 DEBUG --- stderr --- 2024-06-23 04:45:40,946 DEBUG 2024-06-23 04:45:40,946 INFO 2024-06-23 04:45:40,946 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-23 04:45:40,946 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 04:45:41,673 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:41,673 DEBUG --- stdout --- 2024-06-23 04:45:41,673 DEBUG tar: Removing leading `/' from member names 2024-06-23 04:45:41,673 DEBUG --- stderr --- 2024-06-23 04:45:41,673 DEBUG 2024-06-23 04:45:41,673 INFO 2024-06-23 04:45:41,673 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:41,673 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:41,988 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:41,989 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:41,989 DEBUG --- stdout --- 2024-06-23 04:45:41,989 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:41,989 DEBUG --- stderr --- 2024-06-23 04:45:41,989 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 04:45:41,989 INFO 2024-06-23 04:45:41,989 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:41,989 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:42,291 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:42,291 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:42,291 DEBUG --- stdout --- 2024-06-23 04:45:42,292 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:42,292 DEBUG --- stderr --- 2024-06-23 04:45:42,292 DEBUG 2024-06-23 04:45:42,292 INFO 2024-06-23 04:45:42,292 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-23 04:45:42,292 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 04:45:43,090 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 04:45:43,091 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:43,091 DEBUG --- stdout --- 2024-06-23 04:45:43,091 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-06-23 04:45:43,091 DEBUG --- stderr --- 2024-06-23 04:45:43,091 DEBUG 2024-06-23 04:45:43,091 INFO 2024-06-23 04:45:43,091 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:43,091 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:43,406 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:43,407 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:43,407 DEBUG --- stdout --- 2024-06-23 04:45:43,407 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:43,407 DEBUG --- stderr --- 2024-06-23 04:45:43,407 DEBUG 2024-06-23 04:45:43,407 INFO 2024-06-23 04:45:43,407 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-23 04:45:44,224 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 04:45:44,224 DEBUG --- stdout --- 2024-06-23 04:45:44,225 DEBUG 1192 /results/orders/login-tree-10/stdout.txt 2024-06-23 04:45:44,225 DEBUG --- stderr --- 2024-06-23 04:45:44,225 DEBUG 2024-06-23 04:45:44,225 INFO - Download file (size 1192k) 2024-06-23 04:45:44,225 INFO 2024-06-23 04:45:44,225 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:44,225 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:44,543 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:44,543 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:44,544 DEBUG --- stdout --- 2024-06-23 04:45:44,544 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:44,544 DEBUG --- stderr --- 2024-06-23 04:45:44,544 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-23 04:45:44,545 INFO 2024-06-23 04:45:44,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-23 04:45:44,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:44,934 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:44,934 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:44,934 DEBUG --- stdout --- 2024-06-23 04:45:44,934 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:44,934 DEBUG --- stderr --- 2024-06-23 04:45:44,934 DEBUG 2024-06-23 04:45:44,935 INFO 2024-06-23 04:45:44,935 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-23 04:45:44,935 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 04:45:46,073 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:46,073 DEBUG --- stdout --- 2024-06-23 04:45:46,073 DEBUG tar: Removing leading `/' from member names 2024-06-23 04:45:46,073 DEBUG --- stderr --- 2024-06-23 04:45:46,073 DEBUG ________________________________________________________________________________ [2024-06-23 04:45:46] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 04:45:46,074 INFO 2024-06-23 04:45:46,074 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:46,074 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:46,378 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:46,379 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:46,379 DEBUG --- stdout --- 2024-06-23 04:45:46,379 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:46,379 DEBUG --- stderr --- 2024-06-23 04:45:46,379 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 04:45:46,379 INFO 2024-06-23 04:45:46,379 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:46,379 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:46,684 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:46,684 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:46,685 DEBUG --- stdout --- 2024-06-23 04:45:46,685 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:46,685 DEBUG --- stderr --- 2024-06-23 04:45:46,685 DEBUG 2024-06-23 04:45:46,686 INFO 2024-06-23 04:45:46,686 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-23 04:45:46,686 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 04:45:47,491 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 04:45:47,491 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:47,491 DEBUG --- stdout --- 2024-06-23 04:45:47,491 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-06-23 04:45:47,491 DEBUG --- stderr --- 2024-06-23 04:45:47,491 DEBUG 2024-06-23 04:45:47,491 INFO 2024-06-23 04:45:47,491 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:47,491 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:47,816 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:47,816 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:47,816 DEBUG --- stdout --- 2024-06-23 04:45:47,816 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:47,816 DEBUG --- stderr --- 2024-06-23 04:45:47,816 DEBUG 2024-06-23 04:45:47,817 INFO 2024-06-23 04:45:47,817 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-23 04:45:48,635 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 04:45:48,636 DEBUG --- stdout --- 2024-06-23 04:45:48,636 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-06-23 04:45:48,636 DEBUG --- stderr --- 2024-06-23 04:45:48,636 DEBUG 2024-06-23 04:45:48,636 INFO - Download file (size 4k) 2024-06-23 04:45:48,636 INFO 2024-06-23 04:45:48,636 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:48,636 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:48,952 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:48,953 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:48,953 DEBUG --- stdout --- 2024-06-23 04:45:48,953 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:48,953 DEBUG --- stderr --- 2024-06-23 04:45:48,953 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-23 04:45:48,953 INFO 2024-06-23 04:45:48,953 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:48,953 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:49,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:49,258 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:49,258 DEBUG --- stdout --- 2024-06-23 04:45:49,258 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:49,258 DEBUG --- stderr --- 2024-06-23 04:45:49,259 DEBUG 2024-06-23 04:45:49,259 INFO 2024-06-23 04:45:49,259 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-23 04:45:49,259 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 04:45:49,989 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:49,990 DEBUG --- stdout --- 2024-06-23 04:45:49,990 DEBUG tar: Removing leading `/' from member names 2024-06-23 04:45:49,990 DEBUG --- stderr --- 2024-06-23 04:45:49,990 DEBUG 2024-06-23 04:45:49,990 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-23 04:45:49,991 DEBUG ----- output ----- 2024-06-23 04:45:49,991 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-23 04:45:49,991 INFO ________________________________________________________________________________ [2024-06-23 04:45:49] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 04:45:49,991 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-23 04:45:49,991 DEBUG ----- output ----- 2024-06-23 04:45:49,991 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 04:45:49,991 INFO ________________________________________________________________________________ [2024-06-23 04:45:49] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 04:45:49,995 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 18196 lines): 2024-06-23 04:45:49,995 DEBUG ----- output ----- 2024-06-23 04:45:49,995 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 04:45:49,995 INFO 04:43:11.435 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-23 04:45:49,995 INFO 04:43:16.078 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-23 04:45:49,995 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 04:45:49,995 INFO ^ 2024-06-23 04:45:49,995 INFO 04:43:16.081 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-23 04:45:49,995 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 04:45:49,995 INFO ^ 2024-06-23 04:45:49,995 INFO 04:43:17.818 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-23 04:45:49,995 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 04:45:49,995 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 04:45:49,995 INFO 2024-06-23 04:45:49,995 INFO ================================================================================ 2024-06-23 04:45:49,995 INFO 2024-06-23 04:43:25 5s elapsed 2024-06-23 04:45:49,995 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:49,995 INFO > Global (OK=15 KO=0 ) 2024-06-23 04:45:49,995 INFO > protectLoginInitiate (OK=4 KO=0 ) 2024-06-23 04:45:49,995 INFO > PingOneProtectInitializeCallback (OK=4 KO=0 ) 2024-06-23 04:45:49,995 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-06-23 04:45:49,995 INFO > PingOneProtectEvaluationCallback (OK=3 KO=0 ) 2024-06-23 04:45:49,995 INFO 2024-06-23 04:45:49,995 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:49,995 INFO [- ] 0% 2024-06-23 04:45:49,995 INFO waiting: 36034 / active: 2 / done: 3 2024-06-23 04:45:49,995 INFO ================================================================================ 2024-06-23 04:45:49,995 INFO 2024-06-23 04:45:49,995 INFO 2024-06-23 04:45:49,995 INFO ================================================================================ 2024-06-23 04:45:49,996 INFO 2024-06-23 04:43:30 10s elapsed 2024-06-23 04:45:49,996 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:49,996 INFO > Global (OK=62 KO=0 ) 2024-06-23 04:45:49,996 INFO > protectLoginInitiate (OK=16 KO=0 ) 2024-06-23 04:45:49,996 INFO > PingOneProtectInitializeCallback (OK=16 KO=0 ) 2024-06-23 04:45:49,996 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-06-23 04:45:49,996 INFO > PingOneProtectEvaluationCallback (OK=14 KO=0 ) 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:49,996 INFO [- ] 0% 2024-06-23 04:45:49,996 INFO waiting: 36022 / active: 3 / done: 14 2024-06-23 04:45:49,996 INFO ================================================================================ 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO ================================================================================ 2024-06-23 04:45:49,996 INFO 2024-06-23 04:43:35 15s elapsed 2024-06-23 04:45:49,996 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:49,996 INFO > Global (OK=145 KO=0 ) 2024-06-23 04:45:49,996 INFO > protectLoginInitiate (OK=37 KO=0 ) 2024-06-23 04:45:49,996 INFO > PingOneProtectInitializeCallback (OK=37 KO=0 ) 2024-06-23 04:45:49,996 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-06-23 04:45:49,996 INFO > PingOneProtectEvaluationCallback (OK=34 KO=0 ) 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:49,996 INFO [- ] 0% 2024-06-23 04:45:49,996 INFO waiting: 36001 / active: 4 / done: 34 2024-06-23 04:45:49,996 INFO ================================================================================ 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO ================================================================================ 2024-06-23 04:45:49,996 INFO 2024-06-23 04:43:40 20s elapsed 2024-06-23 04:45:49,996 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:49,996 INFO > Global (OK=260 KO=0 ) 2024-06-23 04:45:49,996 INFO > protectLoginInitiate (OK=66 KO=0 ) 2024-06-23 04:45:49,996 INFO > PingOneProtectInitializeCallback (OK=66 KO=0 ) 2024-06-23 04:45:49,996 INFO > restLoginUsernamePasswordCallback (OK=65 KO=0 ) 2024-06-23 04:45:49,996 INFO > PingOneProtectEvaluationCallback (OK=63 KO=0 ) 2024-06-23 04:45:49,996 INFO 2024-06-23 04:45:49,996 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:49,996 INFO [- ] 0% 2024-06-23 04:45:49,997 INFO waiting: 35972 / active: 4 / done: 63 2024-06-23 04:45:49,997 INFO ================================================================================ 2024-06-23 04:45:49,997 INFO 2024-06-23 04:45:49,997 INFO 2024-06-23 04:45:49,997 INFO ================================================================================ 2024-06-23 04:45:49,997 INFO 2024-06-23 04:43:45 25s elapsed 2024-06-23 04:45:49,997 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:49,997 INFO > Global (OK=411 KO=0 ) 2024-06-23 04:45:49,997 INFO > protectLoginInitiate (OK=104 KO=0 ) 2024-06-23 04:45:49,997 INFO > PingOneProtectInitializeCallback (OK=104 KO=0 ) 2024-06-23 04:45:49,997 INFO > restLoginUsernamePasswordCallback (OK=103 KO=0 ) 2024-06-23 04:45:49,997 INFO > PingOneProtectEvaluationCallback (OK=100 KO=0 ) 2024-06-23 04:45:49,997 INFO 2024-06-23 04:45:49,997 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:49,997 INFO [- ] 0% 2024-06-23 04:45:49,997 INFO waiting: 35934 / active: 5 / done: 100 2024-06-23 04:45:49,997 INFO ================================================================================ 2024-06-23 04:45:49,997 INFO 2024-06-23 04:45:49,997 INFO 2024-06-23 04:45:49,997 INFO ================================================================================ 2024-06-23 04:45:49,998 INFO 2024-06-23 04:43:50 30s elapsed 2024-06-23 04:45:49,998 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:49,998 INFO > Global (OK=594 KO=0 ) 2024-06-23 04:45:49,998 INFO > protectLoginInitiate (OK=150 KO=0 ) 2024-06-23 04:45:49,998 INFO > PingOneProtectInitializeCallback (OK=150 KO=0 ) 2024-06-23 04:45:49,998 INFO > restLoginUsernamePasswordCallback (OK=149 KO=0 ) 2024-06-23 04:45:49,998 INFO > PingOneProtectEvaluationCallback (OK=145 KO=0 ) 2024-06-23 04:45:49,998 INFO 2024-06-23 04:45:49,998 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:49,998 INFO [- ] 0% 2024-06-23 04:45:49,998 INFO waiting: 35889 / active: 5 / done: 145 2024-06-23 04:45:49,998 INFO ================================================================================ 2024-06-23 04:45:49,998 INFO [...] 2024-06-23 04:45:50,005 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:50,006 INFO > Global (OK=143928 KO=9 ) 2024-06-23 04:45:50,006 INFO > protectLoginInitiate (OK=35983 KO=1 ) 2024-06-23 04:45:50,006 INFO > PingOneProtectInitializeCallback (OK=35982 KO=0 ) 2024-06-23 04:45:50,006 INFO > restLoginUsernamePasswordCallback (OK=35979 KO=1 ) 2024-06-23 04:45:50,006 INFO > PingOneProtectEvaluationCallback (OK=35973 KO=4 ) 2024-06-23 04:45:50,006 INFO > restloginProgressiveProfileCallback (OK=11 KO=3 ) 2024-06-23 04:45:50,006 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 04:45:50,006 INFO > status.find.is(200), but actually found 502 3 (23.08%) 2024-06-23 04:45:50,006 INFO > status.find.is(200), but actually found 401 3 (23.08%) 2024-06-23 04:45:50,006 INFO > jsonPath($.tokenId).find.exists, found nothing 3 (23.08%) 2024-06-23 04:45:50,006 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,006 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,006 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,006 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,006 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,006 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,006 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 7.69%) 2024-06-23 04:45:50,006 INFO No attribute named 'callbacks' is defined 2024-06-23 04:45:50,006 INFO 2024-06-23 04:45:50,006 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:50,006 INFO [#########################################################################-] 99% 2024-06-23 04:45:50,006 INFO waiting: 55 / active: 6 / done: 35978 2024-06-23 04:45:50,006 INFO ================================================================================ 2024-06-23 04:45:50,006 INFO 2024-06-23 04:45:50,006 INFO 2024-06-23 04:45:50,006 INFO ================================================================================ 2024-06-23 04:45:50,006 INFO 2024-06-23 05:43:50 3630s elapsed 2024-06-23 04:45:50,006 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:50,007 INFO > Global (OK=144151 KO=9 ) 2024-06-23 04:45:50,007 INFO > protectLoginInitiate (OK=36038 KO=1 ) 2024-06-23 04:45:50,007 INFO > PingOneProtectInitializeCallback (OK=36037 KO=0 ) 2024-06-23 04:45:50,007 INFO > restLoginUsernamePasswordCallback (OK=36035 KO=1 ) 2024-06-23 04:45:50,007 INFO > PingOneProtectEvaluationCallback (OK=36030 KO=4 ) 2024-06-23 04:45:50,007 INFO > restloginProgressiveProfileCallback (OK=11 KO=3 ) 2024-06-23 04:45:50,007 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 04:45:50,007 INFO > status.find.is(200), but actually found 502 3 (23.08%) 2024-06-23 04:45:50,007 INFO > status.find.is(200), but actually found 401 3 (23.08%) 2024-06-23 04:45:50,007 INFO > jsonPath($.tokenId).find.exists, found nothing 3 (23.08%) 2024-06-23 04:45:50,007 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,007 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,007 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,007 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,007 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,007 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,007 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 7.69%) 2024-06-23 04:45:50,007 INFO No attribute named 'callbacks' is defined 2024-06-23 04:45:50,007 INFO 2024-06-23 04:45:50,007 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:50,007 INFO [#########################################################################-] 99% 2024-06-23 04:45:50,007 INFO waiting: 0 / active: 4 / done: 36035 2024-06-23 04:45:50,007 INFO ================================================================================ 2024-06-23 04:45:50,007 INFO 2024-06-23 04:45:50,007 INFO 2024-06-23 04:45:50,007 INFO ================================================================================ 2024-06-23 04:45:50,007 INFO 2024-06-23 05:43:50 3630s elapsed 2024-06-23 04:45:50,007 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 04:45:50,007 INFO > Global (OK=144158 KO=9 ) 2024-06-23 04:45:50,007 INFO > protectLoginInitiate (OK=36038 KO=1 ) 2024-06-23 04:45:50,007 INFO > PingOneProtectInitializeCallback (OK=36038 KO=0 ) 2024-06-23 04:45:50,007 INFO > restLoginUsernamePasswordCallback (OK=36037 KO=1 ) 2024-06-23 04:45:50,007 INFO > PingOneProtectEvaluationCallback (OK=36034 KO=4 ) 2024-06-23 04:45:50,007 INFO > restloginProgressiveProfileCallback (OK=11 KO=3 ) 2024-06-23 04:45:50,007 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 04:45:50,007 INFO > status.find.is(200), but actually found 502 3 (23.08%) 2024-06-23 04:45:50,007 INFO > status.find.is(200), but actually found 401 3 (23.08%) 2024-06-23 04:45:50,007 INFO > jsonPath($.tokenId).find.exists, found nothing 3 (23.08%) 2024-06-23 04:45:50,007 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,007 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,008 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,008 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,008 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 ( 7.69%) 2024-06-23 04:45:50,008 INFO attribute named 'callbacks' is defined 2024-06-23 04:45:50,008 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 ( 7.69%) 2024-06-23 04:45:50,008 INFO No attribute named 'callbacks' is defined 2024-06-23 04:45:50,008 INFO 2024-06-23 04:45:50,008 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 04:45:50,008 INFO [##########################################################################]100% 2024-06-23 04:45:50,008 INFO waiting: 0 / active: 0 / done: 36039 2024-06-23 04:45:50,008 INFO ================================================================================ 2024-06-23 04:45:50,008 INFO 2024-06-23 04:45:50,008 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 04:45:50,008 INFO 2024-06-23 04:45:50,008 INFO ---------------------- 2024-06-23 04:45:50,008 INFO [23/Jun/2024 05:43:50] Create tar file login-tree-10.tar.gz 2024-06-23 04:45:50,008 INFO ---------------------- 2024-06-23 04:45:50,008 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 04:45:50,008 INFO RESULT_FOLDER=login-tree-10 2024-06-23 04:45:50,008 INFO SIMULATION_PATH=login-tree-10/loadtest-20240623034319840 2024-06-23 04:45:50,008 INFO PWD=/results/gatling/login-tree-10/loadtest-20240623034319840 2024-06-23 04:45:50,008 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-06-23 04:45:50,008 INFO /results/gatling 2024-06-23 04:45:50,008 INFO 2024-06-23 04:45:50,008 INFO ---------------------- 2024-06-23 04:45:50,008 INFO [23/Jun/2024 05:43:50] Update /results/gatling//latest 2024-06-23 04:45:50,008 INFO ---------------------- 2024-06-23 04:45:50,008 INFO 2024-06-23 04:45:50,008 INFO ---------------------- 2024-06-23 04:45:50,008 INFO [23/Jun/2024 05:43:50] Update /results/gatling//latest.tar.gz 2024-06-23 04:45:50,008 INFO ---------------------- 2024-06-23 04:45:50,008 INFO ________________________________________________________________________________ [2024-06-23 04:45:50] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 04:45:50,009 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-23 04:45:50,009 DEBUG ----- output ----- 2024-06-23 04:45:50,009 DEBUG 2024-06-23 04:45:50,009 INFO ________________________________________________________________________________ [2024-06-23 04:45:50] 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-23 04:45:50] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 04:45:50] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped