--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: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 10} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-24 19:53:42] login-tree-10 pre : N/A ________________________________________________________________________________ [2024-05-24 19:53:43] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-24 19:53:43] - 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-05-24 19:53:43,004 INFO 2024-05-24 19:53:43,004 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-05-24 19:53:43,004 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 19:53:43,313 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 19:53:43,313 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:43,313 DEBUG --- stdout --- 2024-05-24 19:53:43,313 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 19:53:43,313 DEBUG --- stderr --- 2024-05-24 19:53:43,313 DEBUG [2024-05-24 19:53:43] - INFO: Get overseer pod resources requests memory 2024-05-24 19:53:43,313 INFO 2024-05-24 19:53:43,313 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-dfd5985cb-fzzf7 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-05-24 19:53:43,313 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 19:53:43,639 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:43,639 DEBUG --- stdout --- 2024-05-24 19:53:43,639 DEBUG 4Gi 2024-05-24 19:53:43,639 DEBUG --- stderr --- 2024-05-24 19:53:43,639 DEBUG ________________________________________________________________________________ [2024-05-24 19:53:43] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-24 19:53:43,640 INFO 2024-05-24 19:53:43,640 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-05-24 19:53:43,640 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 19:53:43,949 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 19:53:43,949 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:43,949 DEBUG --- stdout --- 2024-05-24 19:53:43,950 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 19:53:43,950 DEBUG --- stderr --- 2024-05-24 19:53:43,950 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-3580" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-24 19:53:43] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-24 19:53:43,951 INFO 2024-05-24 19:53:43,951 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-05-24 19:53:43,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 19:53:44,322 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 19:53:44,322 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:44,322 DEBUG --- stdout --- 2024-05-24 19:53:44,323 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 19:53:44,324 DEBUG --- stderr --- 2024-05-24 19:53:44,324 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.login@ema-perf-3580\" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-10", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-3580.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=9537f3c44bdb077 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-24 19:53:44] 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-05-24 19:53:44,327 INFO 2024-05-24 19:53:44,327 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-24 19:53:44,327 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 19:53:44,649 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 19:53:44,649 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:44,649 DEBUG --- stdout --- 2024-05-24 19:53:44,649 DEBUG WAITING 2024-05-24 19:53:44,649 DEBUG --- stderr --- 2024-05-24 19:53:44,650 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 24 0 --:--:-- --:--:-- --:--:-- 24 ________________________________________________________________________________ [2024-05-24 19:53:44] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-24 19:53:44,650 INFO 2024-05-24 19:53:44,650 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-05-24 19:53:44,650 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 19:53:44,959 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 19:53:44,961 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:44,961 DEBUG --- stdout --- 2024-05-24 19:53:44,961 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 19:53:44,961 DEBUG --- stderr --- 2024-05-24 19:53:44,961 DEBUG 2024-05-24 19:53:44,962 INFO 2024-05-24 19:53:44,962 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-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-24 19:53:44,962 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 19:53:46,339 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:46,339 DEBUG --- stdout --- 2024-05-24 19:53:46,339 DEBUG 2024-05-24 19:53:46,339 DEBUG --- stderr --- 2024-05-24 19:53:46,339 DEBUG Order has been successfully forwarded to overseer [2024-05-24 19:53:46] - INFO: Check if Gatling simulation started successfully 2024-05-24 19:53:46,340 INFO 2024-05-24 19:53:46,340 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-05-24 19:53:46,340 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 19:53:46,654 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 19:53:46,654 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:46,654 DEBUG --- stdout --- 2024-05-24 19:53:46,654 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 19:53:46,654 DEBUG --- stderr --- 2024-05-24 19:53:46,654 DEBUG 2024-05-24 19:53:46,655 INFO 2024-05-24 19:53:46,655 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- head -n 25 /results/orders/login-tree-10/stdout.txt | grep "started..." 2024-05-24 19:53:46,655 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 19:53:47,386 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-24 19:53:53,244 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-05-24 19:53:58,995 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-05-24 19:53:58,996 INFO [loop_until]: OK (rc = 0) 2024-05-24 19:53:58,996 DEBUG --- stdout --- 2024-05-24 19:53:58,996 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 20:53:48.623 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 20:53:52.510 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 20:53:52.513 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 20:53:54.050 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 19:53:58,996 DEBUG --- stderr --- 2024-05-24 19:53:58,996 DEBUG ________________________________________________________________________________ [2024-05-24 19:53:58] 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 05s, 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 07s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 08s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 09s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 10s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-05-24 20:56:09] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-24 20:56:09,783 INFO 2024-05-24 20:56:09,783 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-05-24 20:56:09,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:10,126 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:10,126 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:10,127 DEBUG --- stdout --- 2024-05-24 20:56:10,127 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:10,127 DEBUG --- stderr --- 2024-05-24 20:56:10,127 DEBUG 2024-05-24 20:56:10,127 INFO 2024-05-24 20:56:10,127 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- rm -f /results/orders/order.json 2024-05-24 20:56:10,127 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 20:56:10,911 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:10,911 DEBUG --- stdout --- 2024-05-24 20:56:10,911 DEBUG 2024-05-24 20:56:10,911 DEBUG --- stderr --- 2024-05-24 20:56:10,911 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-24 20:56:10] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-24 20:56:10] - 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-05-24 20:56:10,912 INFO 2024-05-24 20:56:10,913 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-05-24 20:56:10,913 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:11,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:11,238 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:11,238 DEBUG --- stdout --- 2024-05-24 20:56:11,238 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:11,238 DEBUG --- stderr --- 2024-05-24 20:56:11,238 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 20:56:11,238 INFO 2024-05-24 20:56:11,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-05-24 20:56:11,239 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:11,552 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:11,552 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:11,552 DEBUG --- stdout --- 2024-05-24 20:56:11,552 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:11,552 DEBUG --- stderr --- 2024-05-24 20:56:11,552 DEBUG 2024-05-24 20:56:11,553 INFO 2024-05-24 20:56:11,553 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 20:56:11,553 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 20:56:12,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 20:56:12,365 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:12,365 DEBUG --- stdout --- 2024-05-24 20:56:12,365 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-05-24 20:56:12,365 DEBUG --- stderr --- 2024-05-24 20:56:12,365 DEBUG 2024-05-24 20:56:12,366 INFO 2024-05-24 20:56:12,366 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-05-24 20:56:12,366 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:12,688 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:12,688 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:12,688 DEBUG --- stdout --- 2024-05-24 20:56:12,688 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:12,688 DEBUG --- stderr --- 2024-05-24 20:56:12,688 DEBUG 2024-05-24 20:56:12,689 INFO 2024-05-24 20:56:12,689 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-10/rc.txt 2024-05-24 20:56:13,497 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 20:56:13,498 DEBUG --- stdout --- 2024-05-24 20:56:13,498 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-05-24 20:56:13,498 DEBUG --- stderr --- 2024-05-24 20:56:13,498 DEBUG 2024-05-24 20:56:13,498 INFO - Download file (size 4k) 2024-05-24 20:56:13,499 INFO 2024-05-24 20:56:13,499 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-05-24 20:56:13,499 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:13,813 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:13,814 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:13,814 DEBUG --- stdout --- 2024-05-24 20:56:13,814 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:13,814 DEBUG --- stderr --- 2024-05-24 20:56:13,814 DEBUG source : /results/orders/login-tree-10/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-05-24 20:56:13,814 INFO 2024-05-24 20:56:13,814 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-05-24 20:56:13,814 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:14,126 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:14,127 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:14,127 DEBUG --- stdout --- 2024-05-24 20:56:14,127 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:14,127 DEBUG --- stderr --- 2024-05-24 20:56:14,127 DEBUG 2024-05-24 20:56:14,127 INFO 2024-05-24 20:56:14,127 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/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-05-24 20:56:14,127 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 20:56:14,910 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:14,910 DEBUG --- stdout --- 2024-05-24 20:56:14,910 DEBUG tar: Removing leading `/' from member names 2024-05-24 20:56:14,910 DEBUG --- stderr --- 2024-05-24 20:56:14,910 DEBUG 2024-05-24 20:56:14,910 INFO 2024-05-24 20:56:14,911 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-05-24 20:56:14,911 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:15,217 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:15,217 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:15,218 DEBUG --- stdout --- 2024-05-24 20:56:15,218 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:15,218 DEBUG --- stderr --- 2024-05-24 20:56:15,218 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 20:56:15,218 INFO 2024-05-24 20:56:15,218 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 20:56:15,218 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:15,529 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:15,529 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:15,529 DEBUG --- stdout --- 2024-05-24 20:56:15,529 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:15,529 DEBUG --- stderr --- 2024-05-24 20:56:15,529 DEBUG 2024-05-24 20:56:15,530 INFO 2024-05-24 20:56:15,530 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 20:56:15,530 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 20:56:16,340 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 20:56:16,340 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:16,340 DEBUG --- stdout --- 2024-05-24 20:56:16,340 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-05-24 20:56:16,340 DEBUG --- stderr --- 2024-05-24 20:56:16,340 DEBUG 2024-05-24 20:56:16,341 INFO 2024-05-24 20:56:16,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-05-24 20:56:16,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:16,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:16,650 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:16,650 DEBUG --- stdout --- 2024-05-24 20:56:16,650 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:16,650 DEBUG --- stderr --- 2024-05-24 20:56:16,650 DEBUG 2024-05-24 20:56:16,650 INFO 2024-05-24 20:56:16,651 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-10/stderr.txt 2024-05-24 20:56:17,511 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 20:56:17,511 DEBUG --- stdout --- 2024-05-24 20:56:17,513 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-05-24 20:56:17,513 DEBUG --- stderr --- 2024-05-24 20:56:17,513 DEBUG 2024-05-24 20:56:17,513 INFO - Download file (size 0k) 2024-05-24 20:56:17,513 INFO 2024-05-24 20:56:17,514 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-05-24 20:56:17,514 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:17,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:17,826 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:17,826 DEBUG --- stdout --- 2024-05-24 20:56:17,826 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:17,826 DEBUG --- stderr --- 2024-05-24 20:56:17,826 DEBUG source : /results/orders/login-tree-10/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-05-24 20:56:17,826 INFO 2024-05-24 20:56:17,826 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-05-24 20:56:17,826 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:18,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:18,137 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:18,137 DEBUG --- stdout --- 2024-05-24 20:56:18,138 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:18,138 DEBUG --- stderr --- 2024-05-24 20:56:18,138 DEBUG 2024-05-24 20:56:18,138 INFO 2024-05-24 20:56:18,138 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/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-05-24 20:56:18,138 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 20:56:18,872 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:18,872 DEBUG --- stdout --- 2024-05-24 20:56:18,872 DEBUG tar: Removing leading `/' from member names 2024-05-24 20:56:18,872 DEBUG --- stderr --- 2024-05-24 20:56:18,872 DEBUG 2024-05-24 20:56:18,872 INFO 2024-05-24 20:56:18,872 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-05-24 20:56:18,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:19,182 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:19,182 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:19,182 DEBUG --- stdout --- 2024-05-24 20:56:19,182 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:19,182 DEBUG --- stderr --- 2024-05-24 20:56:19,182 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 20:56:19,183 INFO 2024-05-24 20:56:19,183 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-05-24 20:56:19,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:19,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:19,493 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:19,493 DEBUG --- stdout --- 2024-05-24 20:56:19,493 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:19,493 DEBUG --- stderr --- 2024-05-24 20:56:19,493 DEBUG 2024-05-24 20:56:19,494 INFO 2024-05-24 20:56:19,494 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 20:56:19,494 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 20:56:20,300 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 20:56:20,300 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:20,300 DEBUG --- stdout --- 2024-05-24 20:56:20,300 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-05-24 20:56:20,300 DEBUG --- stderr --- 2024-05-24 20:56:20,300 DEBUG 2024-05-24 20:56:20,300 INFO 2024-05-24 20:56:20,300 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-05-24 20:56:20,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:20,607 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:20,607 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:20,607 DEBUG --- stdout --- 2024-05-24 20:56:20,607 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:20,607 DEBUG --- stderr --- 2024-05-24 20:56:20,608 DEBUG 2024-05-24 20:56:20,608 INFO 2024-05-24 20:56:20,608 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-10/stdout.txt 2024-05-24 20:56:21,411 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 20:56:21,411 DEBUG --- stdout --- 2024-05-24 20:56:21,411 DEBUG 564 /results/orders/login-tree-10/stdout.txt 2024-05-24 20:56:21,411 DEBUG --- stderr --- 2024-05-24 20:56:21,411 DEBUG 2024-05-24 20:56:21,411 INFO - Download file (size 564k) 2024-05-24 20:56:21,412 INFO 2024-05-24 20:56:21,412 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-05-24 20:56:21,412 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:21,722 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:21,722 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:21,722 DEBUG --- stdout --- 2024-05-24 20:56:21,722 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:21,722 DEBUG --- stderr --- 2024-05-24 20:56:21,722 DEBUG source : /results/orders/login-tree-10/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-05-24 20:56:21,723 INFO 2024-05-24 20:56:21,723 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-05-24 20:56:21,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:22,025 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:22,026 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:22,026 DEBUG --- stdout --- 2024-05-24 20:56:22,026 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:22,026 DEBUG --- stderr --- 2024-05-24 20:56:22,026 DEBUG 2024-05-24 20:56:22,026 INFO 2024-05-24 20:56:22,026 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/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-05-24 20:56:22,026 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 20:56:23,087 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:23,087 DEBUG --- stdout --- 2024-05-24 20:56:23,087 DEBUG tar: Removing leading `/' from member names 2024-05-24 20:56:23,087 DEBUG --- stderr --- 2024-05-24 20:56:23,087 DEBUG ________________________________________________________________________________ [2024-05-24 20:56:23] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-05-24 20:56:23,088 INFO 2024-05-24 20:56:23,088 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-05-24 20:56:23,088 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:23,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:23,409 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:23,409 DEBUG --- stdout --- 2024-05-24 20:56:23,410 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:23,410 DEBUG --- stderr --- 2024-05-24 20:56:23,410 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 20:56:23,410 INFO 2024-05-24 20:56:23,410 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-05-24 20:56:23,410 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:23,756 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:23,756 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:23,756 DEBUG --- stdout --- 2024-05-24 20:56:23,756 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:23,757 DEBUG --- stderr --- 2024-05-24 20:56:23,757 DEBUG 2024-05-24 20:56:23,757 INFO 2024-05-24 20:56:23,757 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --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-05-24 20:56:23,757 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 20:56:24,568 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 20:56:24,569 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:24,569 DEBUG --- stdout --- 2024-05-24 20:56:24,569 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-05-24 20:56:24,569 DEBUG --- stderr --- 2024-05-24 20:56:24,569 DEBUG 2024-05-24 20:56:24,569 INFO 2024-05-24 20:56:24,569 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-05-24 20:56:24,569 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:24,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:24,879 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:24,879 DEBUG --- stdout --- 2024-05-24 20:56:24,879 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:24,879 DEBUG --- stderr --- 2024-05-24 20:56:24,879 DEBUG 2024-05-24 20:56:24,879 INFO 2024-05-24 20:56:24,879 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-10/cmd.txt 2024-05-24 20:56:25,690 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 20:56:25,691 DEBUG --- stdout --- 2024-05-24 20:56:25,691 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-05-24 20:56:25,691 DEBUG --- stderr --- 2024-05-24 20:56:25,691 DEBUG 2024-05-24 20:56:25,691 INFO - Download file (size 4k) 2024-05-24 20:56:25,691 INFO 2024-05-24 20:56:25,691 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-05-24 20:56:25,691 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:26,000 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:26,000 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:26,000 DEBUG --- stdout --- 2024-05-24 20:56:26,000 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:26,000 DEBUG --- stderr --- 2024-05-24 20:56:26,000 DEBUG source : /results/orders/login-tree-10/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-05-24 20:56:26,001 INFO 2024-05-24 20:56:26,001 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-05-24 20:56:26,001 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:26,306 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:26,306 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:26,306 DEBUG --- stdout --- 2024-05-24 20:56:26,306 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:26,306 DEBUG --- stderr --- 2024-05-24 20:56:26,306 DEBUG 2024-05-24 20:56:26,307 INFO 2024-05-24 20:56:26,307 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/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-05-24 20:56:26,307 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 20:56:27,042 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:27,042 DEBUG --- stdout --- 2024-05-24 20:56:27,042 DEBUG tar: Removing leading `/' from member names 2024-05-24 20:56:27,042 DEBUG --- stderr --- 2024-05-24 20:56:27,043 DEBUG 2024-05-24 20:56:27,043 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-05-24 20:56:27,043 DEBUG ----- output ----- 2024-05-24 20:56:27,043 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-3580" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-24 20:56:27,043 INFO ________________________________________________________________________________ [2024-05-24 20:56:27] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-05-24 20:56:27,043 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-05-24 20:56:27,043 DEBUG ----- output ----- 2024-05-24 20:56:27,044 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-24 20:56:27,044 INFO ________________________________________________________________________________ [2024-05-24 20:56:27] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-24 20:56:27,045 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 9481 lines): 2024-05-24 20:56:27,045 DEBUG ----- output ----- 2024-05-24 20:56:27,045 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-24 20:56:27,045 INFO 20:53:48.623 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-05-24 20:56:27,045 INFO 20:53:52.510 [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-05-24 20:56:27,045 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-24 20:56:27,045 INFO ^ 2024-05-24 20:56:27,045 INFO 20:53:52.513 [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-05-24 20:56:27,045 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-24 20:56:27,045 INFO ^ 2024-05-24 20:56:27,046 INFO 20:53:54.050 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-05-24 20:56:27,046 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-24 20:56:27,046 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO ================================================================================ 2024-05-24 20:56:27,046 INFO 2024-05-24 20:54:01 5s elapsed 2024-05-24 20:56:27,046 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,046 INFO > Global (OK=8 KO=0 ) 2024-05-24 20:56:27,046 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-05-24 20:56:27,046 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,046 INFO [- ] 0% 2024-05-24 20:56:27,046 INFO waiting: 35958 / active: 1 / done: 4 2024-05-24 20:56:27,046 INFO ================================================================================ 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO ================================================================================ 2024-05-24 20:56:27,046 INFO 2024-05-24 20:54:06 10s elapsed 2024-05-24 20:56:27,046 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,046 INFO > Global (OK=32 KO=0 ) 2024-05-24 20:56:27,046 INFO > restLoginInitiate (OK=16 KO=0 ) 2024-05-24 20:56:27,046 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,046 INFO [- ] 0% 2024-05-24 20:56:27,046 INFO waiting: 35946 / active: 1 / done: 16 2024-05-24 20:56:27,046 INFO ================================================================================ 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO ================================================================================ 2024-05-24 20:56:27,046 INFO 2024-05-24 20:54:11 15s elapsed 2024-05-24 20:56:27,046 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,046 INFO > Global (OK=74 KO=0 ) 2024-05-24 20:56:27,046 INFO > restLoginInitiate (OK=37 KO=0 ) 2024-05-24 20:56:27,046 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-05-24 20:56:27,046 INFO 2024-05-24 20:56:27,046 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,047 INFO [- ] 0% 2024-05-24 20:56:27,047 INFO waiting: 35925 / active: 1 / done: 37 2024-05-24 20:56:27,047 INFO ================================================================================ 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO ================================================================================ 2024-05-24 20:56:27,047 INFO 2024-05-24 20:54:16 20s elapsed 2024-05-24 20:56:27,047 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,047 INFO > Global (OK=132 KO=0 ) 2024-05-24 20:56:27,047 INFO > restLoginInitiate (OK=66 KO=0 ) 2024-05-24 20:56:27,047 INFO > restLoginUsernamePasswordCallback (OK=66 KO=0 ) 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,047 INFO [- ] 0% 2024-05-24 20:56:27,047 INFO waiting: 35896 / active: 1 / done: 66 2024-05-24 20:56:27,047 INFO ================================================================================ 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO ================================================================================ 2024-05-24 20:56:27,047 INFO 2024-05-24 20:54:21 25s elapsed 2024-05-24 20:56:27,047 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,047 INFO > Global (OK=208 KO=0 ) 2024-05-24 20:56:27,047 INFO > restLoginInitiate (OK=104 KO=0 ) 2024-05-24 20:56:27,047 INFO > restLoginUsernamePasswordCallback (OK=104 KO=0 ) 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,047 INFO [- ] 0% 2024-05-24 20:56:27,047 INFO waiting: 35858 / active: 1 / done: 104 2024-05-24 20:56:27,047 INFO ================================================================================ 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO ================================================================================ 2024-05-24 20:56:27,047 INFO 2024-05-24 20:54:26 30s elapsed 2024-05-24 20:56:27,047 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,047 INFO > Global (OK=300 KO=0 ) 2024-05-24 20:56:27,047 INFO > restLoginInitiate (OK=150 KO=0 ) 2024-05-24 20:56:27,047 INFO > restLoginUsernamePasswordCallback (OK=150 KO=0 ) 2024-05-24 20:56:27,047 INFO 2024-05-24 20:56:27,047 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,048 INFO [ ] 0% 2024-05-24 20:56:27,048 INFO waiting: 35813 / active: 0 / done: 150 2024-05-24 20:56:27,048 INFO ================================================================================ 2024-05-24 20:56:27,048 INFO 2024-05-24 20:56:27,048 INFO 2024-05-24 20:56:27,048 INFO ================================================================================ 2024-05-24 20:56:27,048 INFO 2024-05-24 20:54:31 35s elapsed 2024-05-24 20:56:27,048 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,048 INFO > Global (OK=427 KO=0 ) 2024-05-24 20:56:27,048 INFO > restLoginInitiate (OK=214 KO=0 ) 2024-05-24 20:56:27,048 INFO > restLoginUsernamePasswordCallback (OK=213 KO=0 ) 2024-05-24 20:56:27,048 INFO 2024-05-24 20:56:27,048 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,048 INFO [- ] 0% 2024-05-24 20:56:27,048 INFO waiting: 35749 / active: 1 / done: 213 2024-05-24 20:56:27,048 INFO [...] 2024-05-24 20:56:27,050 INFO waiting: 250 / active: 0 / done: 35713 2024-05-24 20:56:27,050 INFO ================================================================================ 2024-05-24 20:56:27,050 INFO 2024-05-24 20:56:27,050 INFO 2024-05-24 20:56:27,050 INFO ================================================================================ 2024-05-24 20:56:27,050 INFO 2024-05-24 21:54:06 3610s elapsed 2024-05-24 20:56:27,051 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,051 INFO > Global (OK=71532 KO=0 ) 2024-05-24 20:56:27,051 INFO > restLoginInitiate (OK=35766 KO=0 ) 2024-05-24 20:56:27,051 INFO > restLoginUsernamePasswordCallback (OK=35766 KO=0 ) 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,051 INFO [######################################################################### ] 99% 2024-05-24 20:56:27,051 INFO waiting: 197 / active: 0 / done: 35766 2024-05-24 20:56:27,051 INFO ================================================================================ 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO ================================================================================ 2024-05-24 20:56:27,051 INFO 2024-05-24 21:54:11 3615s elapsed 2024-05-24 20:56:27,051 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,051 INFO > Global (OK=71628 KO=0 ) 2024-05-24 20:56:27,051 INFO > restLoginInitiate (OK=35814 KO=0 ) 2024-05-24 20:56:27,051 INFO > restLoginUsernamePasswordCallback (OK=35814 KO=0 ) 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,051 INFO [######################################################################### ] 99% 2024-05-24 20:56:27,051 INFO waiting: 149 / active: 0 / done: 35814 2024-05-24 20:56:27,051 INFO ================================================================================ 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO ================================================================================ 2024-05-24 20:56:27,051 INFO 2024-05-24 21:54:16 3620s elapsed 2024-05-24 20:56:27,051 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,051 INFO > Global (OK=71714 KO=0 ) 2024-05-24 20:56:27,051 INFO > restLoginInitiate (OK=35857 KO=0 ) 2024-05-24 20:56:27,051 INFO > restLoginUsernamePasswordCallback (OK=35857 KO=0 ) 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,051 INFO [######################################################################### ] 99% 2024-05-24 20:56:27,051 INFO waiting: 106 / active: 0 / done: 35857 2024-05-24 20:56:27,051 INFO ================================================================================ 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO 2024-05-24 20:56:27,051 INFO ================================================================================ 2024-05-24 20:56:27,051 INFO 2024-05-24 21:54:21 3625s elapsed 2024-05-24 20:56:27,051 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,052 INFO > Global (OK=71823 KO=0 ) 2024-05-24 20:56:27,052 INFO > restLoginInitiate (OK=35912 KO=0 ) 2024-05-24 20:56:27,052 INFO > restLoginUsernamePasswordCallback (OK=35911 KO=0 ) 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,052 INFO [#########################################################################-] 99% 2024-05-24 20:56:27,052 INFO waiting: 51 / active: 1 / done: 35911 2024-05-24 20:56:27,052 INFO ================================================================================ 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO ================================================================================ 2024-05-24 20:56:27,052 INFO 2024-05-24 21:54:26 3630s elapsed 2024-05-24 20:56:27,052 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,052 INFO > Global (OK=71925 KO=0 ) 2024-05-24 20:56:27,052 INFO > restLoginInitiate (OK=35963 KO=0 ) 2024-05-24 20:56:27,052 INFO > restLoginUsernamePasswordCallback (OK=35962 KO=0 ) 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,052 INFO [#########################################################################-] 99% 2024-05-24 20:56:27,052 INFO waiting: 0 / active: 1 / done: 35962 2024-05-24 20:56:27,052 INFO ================================================================================ 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO ================================================================================ 2024-05-24 20:56:27,052 INFO 2024-05-24 21:54:26 3630s elapsed 2024-05-24 20:56:27,052 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 20:56:27,052 INFO > Global (OK=71926 KO=0 ) 2024-05-24 20:56:27,052 INFO > restLoginInitiate (OK=35963 KO=0 ) 2024-05-24 20:56:27,052 INFO > restLoginUsernamePasswordCallback (OK=35963 KO=0 ) 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 20:56:27,052 INFO [##########################################################################]100% 2024-05-24 20:56:27,052 INFO waiting: 0 / active: 0 / done: 35963 2024-05-24 20:56:27,052 INFO ================================================================================ 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-24 20:56:27,052 INFO 2024-05-24 20:56:27,052 INFO ---------------------- 2024-05-24 20:56:27,052 INFO [24/May/2024 21:54:26] Create tar file login-tree-10.tar.gz 2024-05-24 20:56:27,052 INFO ---------------------- 2024-05-24 20:56:27,052 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-24 20:56:27,053 INFO RESULT_FOLDER=login-tree-10 2024-05-24 20:56:27,053 INFO SIMULATION_PATH=login-tree-10/loadtest-20240524195356076 2024-05-24 20:56:27,053 INFO PWD=/results/gatling/login-tree-10/loadtest-20240524195356076 2024-05-24 20:56:27,053 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-05-24 20:56:27,053 INFO /results/gatling 2024-05-24 20:56:27,053 INFO 2024-05-24 20:56:27,053 INFO ---------------------- 2024-05-24 20:56:27,053 INFO [24/May/2024 21:54:26] Update /results/gatling//latest 2024-05-24 20:56:27,053 INFO ---------------------- 2024-05-24 20:56:27,053 INFO 2024-05-24 20:56:27,053 INFO ---------------------- 2024-05-24 20:56:27,053 INFO [24/May/2024 21:54:26] Update /results/gatling//latest.tar.gz 2024-05-24 20:56:27,053 INFO ---------------------- 2024-05-24 20:56:27,053 INFO ________________________________________________________________________________ [2024-05-24 20:56:27] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-24 20:56:27,053 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-05-24 20:56:27,053 DEBUG ----- output ----- 2024-05-24 20:56:27,053 DEBUG 2024-05-24 20:56:27,053 INFO ________________________________________________________________________________ [2024-05-24 20:56:27] 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-05-24 20:56:27] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-24 20:56:27] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped