--Task-- name: login-tree-100 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-10'] preceding_task: login-tree-10 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 100} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 05:38:46] login-tree-100 pre : N/A ________________________________________________________________________________ [2024-05-25 05:38:46] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 05:38:46] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-100/stdout.txt Pod log : /results/orders/login-tree-100 2024-05-25 05:38:46,737 INFO 2024-05-25 05:38:46,738 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-25 05:38:46,738 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:47,046 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:47,046 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:47,046 DEBUG --- stdout --- 2024-05-25 05:38:47,046 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:47,046 DEBUG --- stderr --- 2024-05-25 05:38:47,046 DEBUG ________________________________________________________________________________ [2024-05-25 05:38:47] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 05:38:47,047 INFO 2024-05-25 05:38:47,047 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-25 05:38:47,047 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:47,352 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:47,352 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:47,352 DEBUG --- stdout --- 2024-05-25 05:38:47,352 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:47,352 DEBUG --- stderr --- 2024-05-25 05:38:47,352 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-100 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 05:38:47] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 05:38:47,353 INFO 2024-05-25 05:38:47,353 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-25 05:38:47,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:47,655 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:47,655 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:47,655 DEBUG --- stdout --- 2024-05-25 05:38:47,655 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:47,655 DEBUG --- stderr --- 2024-05-25 05:38:47,655 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json { "name": "login-tree-100", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-3580\" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-100", "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=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 05:38:47] login-tree-100 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-05-25 05:38:47,657 INFO 2024-05-25 05:38:47,657 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 05:38:47,657 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 05:38:47,899 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 05:38:47,900 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:47,900 DEBUG --- stdout --- 2024-05-25 05:38:47,900 DEBUG WAITING 2024-05-25 05:38:47,900 DEBUG --- stderr --- 2024-05-25 05:38:47,900 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-05-25 05:38:47] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 05:38:47,901 INFO 2024-05-25 05:38:47,901 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-25 05:38:47,901 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:48,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:48,211 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:48,212 DEBUG --- stdout --- 2024-05-25 05:38:48,212 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:48,212 DEBUG --- stderr --- 2024-05-25 05:38:48,212 DEBUG 2024-05-25 05:38:48,212 INFO 2024-05-25 05:38:48,212 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 05:38:48,212 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 05:38:49,581 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:49,581 DEBUG --- stdout --- 2024-05-25 05:38:49,581 DEBUG 2024-05-25 05:38:49,581 DEBUG --- stderr --- 2024-05-25 05:38:49,581 DEBUG Order has been successfully forwarded to overseer [2024-05-25 05:38:49] - INFO: Check if Gatling simulation started successfully 2024-05-25 05:38:49,582 INFO 2024-05-25 05:38:49,582 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-25 05:38:49,582 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:49,929 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:49,929 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:49,929 DEBUG --- stdout --- 2024-05-25 05:38:49,930 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:49,930 DEBUG --- stderr --- 2024-05-25 05:38:49,930 DEBUG 2024-05-25 05:38:49,930 INFO 2024-05-25 05:38:49,930 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-100/stdout.txt | grep "started..." 2024-05-25 05:38:49,930 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 05:38:50,771 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 05:38:56,524 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-05-25 05:39:02,271 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-05-25 05:39:02,272 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:39:02,272 DEBUG --- stdout --- 2024-05-25 05:39:02,273 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 06:38:52.061 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 06:38:55.848 [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"); ^ 06:38:55.851 [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"); ^ 06:38:57.433 [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-25 05:39:02,273 DEBUG --- stderr --- 2024-05-25 05:39:02,273 DEBUG ________________________________________________________________________________ [2024-05-25 05:39:02] login-tree-100 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 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 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-05-25 06:41:12] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 06:41:12,602 INFO 2024-05-25 06:41:12,602 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-25 06:41:12,602 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:12,930 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:12,930 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:12,930 DEBUG --- stdout --- 2024-05-25 06:41:12,930 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:12,930 DEBUG --- stderr --- 2024-05-25 06:41:12,930 DEBUG 2024-05-25 06:41:12,931 INFO 2024-05-25 06:41:12,931 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-25 06:41:12,931 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 06:41:13,724 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:13,724 DEBUG --- stdout --- 2024-05-25 06:41:13,725 DEBUG 2024-05-25 06:41:13,725 DEBUG --- stderr --- 2024-05-25 06:41:13,725 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 06:41:13] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 06:41:13] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json 2024-05-25 06:41:13,726 INFO 2024-05-25 06:41:13,726 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-25 06:41:13,726 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:14,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:14,029 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:14,029 DEBUG --- stdout --- 2024-05-25 06:41:14,029 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:14,029 DEBUG --- stderr --- 2024-05-25 06:41:14,029 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 06:41:14,030 INFO 2024-05-25 06:41:14,030 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-25 06:41:14,030 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:14,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:14,334 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:14,334 DEBUG --- stdout --- 2024-05-25 06:41:14,334 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:14,334 DEBUG --- stderr --- 2024-05-25 06:41:14,334 DEBUG 2024-05-25 06:41:14,335 INFO 2024-05-25 06:41:14,335 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-100/rc.txt" ]; then echo "/results/orders/login-tree-100/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/rc.txt file found" 2024-05-25 06:41:14,336 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 06:41:15,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 06:41:15,191 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:15,191 DEBUG --- stdout --- 2024-05-25 06:41:15,191 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-05-25 06:41:15,191 DEBUG --- stderr --- 2024-05-25 06:41:15,191 DEBUG 2024-05-25 06:41:15,191 INFO 2024-05-25 06:41:15,191 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-25 06:41:15,191 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:15,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:15,504 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:15,504 DEBUG --- stdout --- 2024-05-25 06:41:15,504 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:15,504 DEBUG --- stderr --- 2024-05-25 06:41:15,504 DEBUG 2024-05-25 06:41:15,504 INFO 2024-05-25 06:41:15,504 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-100/rc.txt 2024-05-25 06:41:16,310 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 06:41:16,311 DEBUG --- stdout --- 2024-05-25 06:41:16,311 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-05-25 06:41:16,311 DEBUG --- stderr --- 2024-05-25 06:41:16,311 DEBUG 2024-05-25 06:41:16,311 INFO - Download file (size 4k) 2024-05-25 06:41:16,311 INFO 2024-05-25 06:41:16,311 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-25 06:41:16,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:16,619 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:16,619 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:16,619 DEBUG --- stdout --- 2024-05-25 06:41:16,619 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:16,619 DEBUG --- stderr --- 2024-05-25 06:41:16,619 DEBUG source : /results/orders/login-tree-100/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-05-25 06:41:16,620 INFO 2024-05-25 06:41:16,620 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-25 06:41:16,620 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:16,929 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:16,929 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:16,929 DEBUG --- stdout --- 2024-05-25 06:41:16,929 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:16,929 DEBUG --- stderr --- 2024-05-25 06:41:16,929 DEBUG 2024-05-25 06:41:16,929 INFO 2024-05-25 06:41:16,929 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-05-25 06:41:16,929 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 06:41:17,650 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:17,650 DEBUG --- stdout --- 2024-05-25 06:41:17,650 DEBUG tar: Removing leading `/' from member names 2024-05-25 06:41:17,650 DEBUG --- stderr --- 2024-05-25 06:41:17,650 DEBUG 2024-05-25 06:41:17,651 INFO 2024-05-25 06:41:17,651 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-25 06:41:17,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:17,990 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:17,990 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:17,991 DEBUG --- stdout --- 2024-05-25 06:41:17,991 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:17,991 DEBUG --- stderr --- 2024-05-25 06:41:17,991 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 06:41:17,991 INFO 2024-05-25 06:41:17,991 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-25 06:41:17,991 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:18,306 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:18,307 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:18,307 DEBUG --- stdout --- 2024-05-25 06:41:18,307 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:18,307 DEBUG --- stderr --- 2024-05-25 06:41:18,307 DEBUG 2024-05-25 06:41:18,307 INFO 2024-05-25 06:41:18,307 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-100/stderr.txt" ]; then echo "/results/orders/login-tree-100/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stderr.txt file found" 2024-05-25 06:41:18,307 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 06:41:19,111 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 06:41:19,113 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:19,113 DEBUG --- stdout --- 2024-05-25 06:41:19,113 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-05-25 06:41:19,113 DEBUG --- stderr --- 2024-05-25 06:41:19,113 DEBUG 2024-05-25 06:41:19,113 INFO 2024-05-25 06:41:19,114 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-25 06:41:19,114 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:19,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:19,429 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:19,430 DEBUG --- stdout --- 2024-05-25 06:41:19,430 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:19,430 DEBUG --- stderr --- 2024-05-25 06:41:19,430 DEBUG 2024-05-25 06:41:19,430 INFO 2024-05-25 06:41:19,430 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-100/stderr.txt 2024-05-25 06:41:20,236 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 06:41:20,236 DEBUG --- stdout --- 2024-05-25 06:41:20,236 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-05-25 06:41:20,236 DEBUG --- stderr --- 2024-05-25 06:41:20,236 DEBUG 2024-05-25 06:41:20,236 INFO - Download file (size 0k) 2024-05-25 06:41:20,237 INFO 2024-05-25 06:41:20,237 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-25 06:41:20,237 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:20,547 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:20,547 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:20,547 DEBUG --- stdout --- 2024-05-25 06:41:20,547 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:20,547 DEBUG --- stderr --- 2024-05-25 06:41:20,547 DEBUG source : /results/orders/login-tree-100/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-05-25 06:41:20,548 INFO 2024-05-25 06:41:20,548 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-25 06:41:20,548 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:20,852 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:20,853 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:20,853 DEBUG --- stdout --- 2024-05-25 06:41:20,853 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:20,853 DEBUG --- stderr --- 2024-05-25 06:41:20,853 DEBUG 2024-05-25 06:41:20,853 INFO 2024-05-25 06:41:20,853 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-05-25 06:41:20,853 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 06:41:21,631 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:21,631 DEBUG --- stdout --- 2024-05-25 06:41:21,631 DEBUG tar: Removing leading `/' from member names 2024-05-25 06:41:21,631 DEBUG --- stderr --- 2024-05-25 06:41:21,631 DEBUG 2024-05-25 06:41:21,632 INFO 2024-05-25 06:41:21,632 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-25 06:41:21,632 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:21,946 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:21,946 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:21,946 DEBUG --- stdout --- 2024-05-25 06:41:21,946 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:21,946 DEBUG --- stderr --- 2024-05-25 06:41:21,946 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 06:41:21,947 INFO 2024-05-25 06:41:21,947 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-25 06:41:21,947 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:22,248 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:22,248 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:22,248 DEBUG --- stdout --- 2024-05-25 06:41:22,249 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:22,249 DEBUG --- stderr --- 2024-05-25 06:41:22,249 DEBUG 2024-05-25 06:41:22,249 INFO 2024-05-25 06:41:22,249 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-100/stdout.txt" ]; then echo "/results/orders/login-tree-100/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stdout.txt file found" 2024-05-25 06:41:22,249 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 06:41:23,063 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 06:41:23,063 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:23,063 DEBUG --- stdout --- 2024-05-25 06:41:23,063 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-05-25 06:41:23,063 DEBUG --- stderr --- 2024-05-25 06:41:23,063 DEBUG 2024-05-25 06:41:23,064 INFO 2024-05-25 06:41:23,064 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-25 06:41:23,064 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:23,378 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:23,378 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:23,378 DEBUG --- stdout --- 2024-05-25 06:41:23,378 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:23,379 DEBUG --- stderr --- 2024-05-25 06:41:23,379 DEBUG 2024-05-25 06:41:23,379 INFO 2024-05-25 06:41:23,379 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-100/stdout.txt 2024-05-25 06:41:24,193 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 06:41:24,195 DEBUG --- stdout --- 2024-05-25 06:41:24,195 DEBUG 684 /results/orders/login-tree-100/stdout.txt 2024-05-25 06:41:24,195 DEBUG --- stderr --- 2024-05-25 06:41:24,195 DEBUG 2024-05-25 06:41:24,195 INFO - Download file (size 684k) 2024-05-25 06:41:24,196 INFO 2024-05-25 06:41:24,196 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-25 06:41:24,196 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:24,499 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:24,499 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:24,499 DEBUG --- stdout --- 2024-05-25 06:41:24,499 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:24,499 DEBUG --- stderr --- 2024-05-25 06:41:24,499 DEBUG source : /results/orders/login-tree-100/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-05-25 06:41:24,500 INFO 2024-05-25 06:41:24,500 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-25 06:41:24,500 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:24,815 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:24,816 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:24,816 DEBUG --- stdout --- 2024-05-25 06:41:24,816 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:24,816 DEBUG --- stderr --- 2024-05-25 06:41:24,816 DEBUG 2024-05-25 06:41:24,816 INFO 2024-05-25 06:41:24,816 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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-05-25 06:41:24,816 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 06:41:25,878 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:25,878 DEBUG --- stdout --- 2024-05-25 06:41:25,878 DEBUG tar: Removing leading `/' from member names 2024-05-25 06:41:25,878 DEBUG --- stderr --- 2024-05-25 06:41:25,878 DEBUG ________________________________________________________________________________ [2024-05-25 06:41:25] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 06:41:25,879 INFO 2024-05-25 06:41:25,879 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-25 06:41:25,879 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:26,194 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:26,194 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:26,194 DEBUG --- stdout --- 2024-05-25 06:41:26,195 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:26,195 DEBUG --- stderr --- 2024-05-25 06:41:26,195 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 06:41:26,195 INFO 2024-05-25 06:41:26,195 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-25 06:41:26,195 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:26,502 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:26,502 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:26,503 DEBUG --- stdout --- 2024-05-25 06:41:26,503 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:26,503 DEBUG --- stderr --- 2024-05-25 06:41:26,503 DEBUG 2024-05-25 06:41:26,503 INFO 2024-05-25 06:41:26,503 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-100/cmd.txt" ]; then echo "/results/orders/login-tree-100/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/cmd.txt file found" 2024-05-25 06:41:26,503 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 06:41:27,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 06:41:27,311 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:27,311 DEBUG --- stdout --- 2024-05-25 06:41:27,311 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-05-25 06:41:27,311 DEBUG --- stderr --- 2024-05-25 06:41:27,311 DEBUG 2024-05-25 06:41:27,311 INFO 2024-05-25 06:41:27,311 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-25 06:41:27,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:27,617 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:27,617 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:27,617 DEBUG --- stdout --- 2024-05-25 06:41:27,618 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:27,618 DEBUG --- stderr --- 2024-05-25 06:41:27,618 DEBUG 2024-05-25 06:41:27,618 INFO 2024-05-25 06:41:27,618 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-100/cmd.txt 2024-05-25 06:41:28,421 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 06:41:28,421 DEBUG --- stdout --- 2024-05-25 06:41:28,421 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-05-25 06:41:28,421 DEBUG --- stderr --- 2024-05-25 06:41:28,421 DEBUG 2024-05-25 06:41:28,421 INFO - Download file (size 4k) 2024-05-25 06:41:28,421 INFO 2024-05-25 06:41:28,421 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-25 06:41:28,421 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:28,737 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:28,737 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:28,737 DEBUG --- stdout --- 2024-05-25 06:41:28,737 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:28,737 DEBUG --- stderr --- 2024-05-25 06:41:28,737 DEBUG source : /results/orders/login-tree-100/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-05-25 06:41:28,737 INFO 2024-05-25 06:41:28,737 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-25 06:41:28,738 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:29,046 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:29,046 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:29,046 DEBUG --- stdout --- 2024-05-25 06:41:29,046 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:29,046 DEBUG --- stderr --- 2024-05-25 06:41:29,046 DEBUG 2024-05-25 06:41:29,047 INFO 2024-05-25 06:41:29,047 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-05-25 06:41:29,047 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 06:41:29,768 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:29,768 DEBUG --- stdout --- 2024-05-25 06:41:29,769 DEBUG tar: Removing leading `/' from member names 2024-05-25 06:41:29,769 DEBUG --- stderr --- 2024-05-25 06:41:29,769 DEBUG 2024-05-25 06:41:29,769 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt is small (1 lines), printing all lines: 2024-05-25 06:41:29,769 DEBUG ----- output ----- 2024-05-25 06:41:29,769 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-100 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 06:41:29,769 INFO ________________________________________________________________________________ [2024-05-25 06:41:29] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 06:41:29,770 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt is small (1 lines), printing all lines: 2024-05-25 06:41:29,770 DEBUG ----- output ----- 2024-05-25 06:41:29,770 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 06:41:29,770 INFO ________________________________________________________________________________ [2024-05-25 06:41:29] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 06:41:29,772 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt is 10989 lines): 2024-05-25 06:41:29,772 DEBUG ----- output ----- 2024-05-25 06:41:29,772 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 06:41:29,772 INFO 06:38:52.061 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-05-25 06:41:29,772 INFO 06:38:55.848 [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-25 06:41:29,772 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-25 06:41:29,772 INFO ^ 2024-05-25 06:41:29,772 INFO 06:38:55.851 [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-25 06:41:29,772 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-25 06:41:29,772 INFO ^ 2024-05-25 06:41:29,772 INFO 06:38:57.433 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-05-25 06:41:29,772 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 06:41:29,772 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 06:41:29,772 INFO 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:39:04 5s elapsed 2024-05-25 06:41:29,773 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,773 INFO > Global (OK=82 KO=0 ) 2024-05-25 06:41:29,773 INFO > restLoginInitiate (OK=41 KO=0 ) 2024-05-25 06:41:29,773 INFO > restLoginUsernamePasswordCallback (OK=41 KO=0 ) 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,773 INFO [- ] 0% 2024-05-25 06:41:29,773 INFO waiting: 361590 / active: 1 / done: 41 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:39:09 10s elapsed 2024-05-25 06:41:29,773 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,773 INFO > Global (OK=331 KO=0 ) 2024-05-25 06:41:29,773 INFO > restLoginInitiate (OK=166 KO=0 ) 2024-05-25 06:41:29,773 INFO > restLoginUsernamePasswordCallback (OK=165 KO=0 ) 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,773 INFO [- ] 0% 2024-05-25 06:41:29,773 INFO waiting: 361465 / active: 2 / done: 165 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:39:14 15s elapsed 2024-05-25 06:41:29,773 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,773 INFO > Global (OK=749 KO=0 ) 2024-05-25 06:41:29,773 INFO > restLoginInitiate (OK=375 KO=0 ) 2024-05-25 06:41:29,773 INFO > restLoginUsernamePasswordCallback (OK=374 KO=0 ) 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,773 INFO [- ] 0% 2024-05-25 06:41:29,773 INFO waiting: 361256 / active: 2 / done: 374 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO 2024-05-25 06:41:29,773 INFO ================================================================================ 2024-05-25 06:41:29,773 INFO 2024-05-25 06:39:19 20s elapsed 2024-05-25 06:41:29,774 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,774 INFO > Global (OK=1331 KO=0 ) 2024-05-25 06:41:29,774 INFO > restLoginInitiate (OK=666 KO=0 ) 2024-05-25 06:41:29,774 INFO > restLoginUsernamePasswordCallback (OK=665 KO=0 ) 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,774 INFO [- ] 0% 2024-05-25 06:41:29,774 INFO waiting: 360965 / active: 2 / done: 665 2024-05-25 06:41:29,774 INFO ================================================================================ 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO ================================================================================ 2024-05-25 06:41:29,774 INFO 2024-05-25 06:39:24 25s elapsed 2024-05-25 06:41:29,774 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,774 INFO > Global (OK=2080 KO=0 ) 2024-05-25 06:41:29,774 INFO > restLoginInitiate (OK=1041 KO=0 ) 2024-05-25 06:41:29,774 INFO > restLoginUsernamePasswordCallback (OK=1039 KO=0 ) 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,774 INFO [- ] 0% 2024-05-25 06:41:29,774 INFO waiting: 360589 / active: 4 / done: 1039 2024-05-25 06:41:29,774 INFO ================================================================================ 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO ================================================================================ 2024-05-25 06:41:29,774 INFO 2024-05-25 06:39:29 30s elapsed 2024-05-25 06:41:29,774 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,774 INFO > Global (OK=2997 KO=0 ) 2024-05-25 06:41:29,774 INFO > restLoginInitiate (OK=1499 KO=0 ) 2024-05-25 06:41:29,774 INFO > restLoginUsernamePasswordCallback (OK=1498 KO=0 ) 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,774 INFO [- ] 0% 2024-05-25 06:41:29,774 INFO waiting: 360131 / active: 3 / done: 1498 2024-05-25 06:41:29,774 INFO ================================================================================ 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO 2024-05-25 06:41:29,774 INFO ================================================================================ 2024-05-25 06:41:29,774 INFO 2024-05-25 06:39:34 35s elapsed 2024-05-25 06:41:29,775 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,775 INFO > Global (OK=3937 KO=0 ) 2024-05-25 06:41:29,775 INFO > restLoginInitiate (OK=1971 KO=0 ) 2024-05-25 06:41:29,775 INFO > restLoginUsernamePasswordCallback (OK=1966 KO=0 ) 2024-05-25 06:41:29,775 INFO 2024-05-25 06:41:29,775 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,775 INFO [- ] 0% 2024-05-25 06:41:29,775 INFO waiting: 359660 / active: 6 / done: 1966 2024-05-25 06:41:29,775 INFO [...] 2024-05-25 06:41:29,778 INFO 2024-05-25 06:41:29,778 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,778 INFO [#########################################################################-] 99% 2024-05-25 06:41:29,778 INFO waiting: 1998 / active: 4 / done: 359630 2024-05-25 06:41:29,778 INFO ================================================================================ 2024-05-25 06:41:29,778 INFO 2024-05-25 06:41:29,778 INFO 2024-05-25 06:41:29,778 INFO ================================================================================ 2024-05-25 06:41:29,778 INFO 2024-05-25 07:39:14 3615s elapsed 2024-05-25 06:41:29,778 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,778 INFO > Global (OK=720239 KO=8 ) 2024-05-25 06:41:29,779 INFO > restLoginInitiate (OK=360122 KO=6 ) 2024-05-25 06:41:29,779 INFO > restLoginUsernamePasswordCallback (OK=360117 KO=2 ) 2024-05-25 06:41:29,779 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 06:41:29,779 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-05-25 06:41:29,779 INFO 2024-05-25 06:41:29,779 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,779 INFO [#########################################################################-] 99% 2024-05-25 06:41:29,779 INFO waiting: 1503 / active: 4 / done: 360125 2024-05-25 06:41:29,779 INFO ================================================================================ 2024-05-25 06:41:29,779 INFO 2024-05-25 06:41:29,779 INFO 2024-05-25 06:41:29,779 INFO ================================================================================ 2024-05-25 06:41:29,779 INFO 2024-05-25 07:39:19 3620s elapsed 2024-05-25 06:41:29,779 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,779 INFO > Global (OK=721137 KO=8 ) 2024-05-25 06:41:29,779 INFO > restLoginInitiate (OK=360570 KO=6 ) 2024-05-25 06:41:29,779 INFO > restLoginUsernamePasswordCallback (OK=360567 KO=2 ) 2024-05-25 06:41:29,779 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 06:41:29,779 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-05-25 06:41:29,779 INFO 2024-05-25 06:41:29,779 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,779 INFO [#########################################################################-] 99% 2024-05-25 06:41:29,779 INFO waiting: 1056 / active: 1 / done: 360575 2024-05-25 06:41:29,779 INFO ================================================================================ 2024-05-25 06:41:29,779 INFO 2024-05-25 06:41:29,779 INFO 2024-05-25 06:41:29,779 INFO ================================================================================ 2024-05-25 06:41:29,779 INFO 2024-05-25 07:39:24 3625s elapsed 2024-05-25 06:41:29,779 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,779 INFO > Global (OK=722172 KO=8 ) 2024-05-25 06:41:29,780 INFO > restLoginInitiate (OK=361088 KO=6 ) 2024-05-25 06:41:29,780 INFO > restLoginUsernamePasswordCallback (OK=361084 KO=2 ) 2024-05-25 06:41:29,780 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 06:41:29,780 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-05-25 06:41:29,780 INFO 2024-05-25 06:41:29,780 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,780 INFO [#########################################################################-] 99% 2024-05-25 06:41:29,780 INFO waiting: 535 / active: 5 / done: 361092 2024-05-25 06:41:29,780 INFO ================================================================================ 2024-05-25 06:41:29,780 INFO 2024-05-25 06:41:29,780 INFO 2024-05-25 06:41:29,780 INFO ================================================================================ 2024-05-25 06:41:29,780 INFO 2024-05-25 07:39:29 3630s elapsed 2024-05-25 06:41:29,780 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,780 INFO > Global (OK=723248 KO=8 ) 2024-05-25 06:41:29,780 INFO > restLoginInitiate (OK=361626 KO=6 ) 2024-05-25 06:41:29,780 INFO > restLoginUsernamePasswordCallback (OK=361622 KO=2 ) 2024-05-25 06:41:29,780 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 06:41:29,780 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-05-25 06:41:29,780 INFO 2024-05-25 06:41:29,780 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,780 INFO [#########################################################################-] 99% 2024-05-25 06:41:29,780 INFO waiting: 0 / active: 2 / done: 361630 2024-05-25 06:41:29,780 INFO ================================================================================ 2024-05-25 06:41:29,780 INFO 2024-05-25 06:41:29,780 INFO 2024-05-25 06:41:29,780 INFO ================================================================================ 2024-05-25 06:41:29,780 INFO 2024-05-25 07:39:29 3630s elapsed 2024-05-25 06:41:29,780 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 06:41:29,780 INFO > Global (OK=723250 KO=8 ) 2024-05-25 06:41:29,780 INFO > restLoginInitiate (OK=361626 KO=6 ) 2024-05-25 06:41:29,780 INFO > restLoginUsernamePasswordCallback (OK=361624 KO=2 ) 2024-05-25 06:41:29,780 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 06:41:29,780 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-05-25 06:41:29,781 INFO 2024-05-25 06:41:29,781 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 06:41:29,781 INFO [##########################################################################]100% 2024-05-25 06:41:29,781 INFO waiting: 0 / active: 0 / done: 361632 2024-05-25 06:41:29,781 INFO ================================================================================ 2024-05-25 06:41:29,781 INFO 2024-05-25 06:41:29,781 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 06:41:29,781 INFO 2024-05-25 06:41:29,781 INFO ---------------------- 2024-05-25 06:41:29,781 INFO [25/May/2024 07:39:30] Create tar file login-tree-100.tar.gz 2024-05-25 06:41:29,781 INFO ---------------------- 2024-05-25 06:41:29,781 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 06:41:29,781 INFO RESULT_FOLDER=login-tree-100 2024-05-25 06:41:29,781 INFO SIMULATION_PATH=login-tree-100/loadtest-20240525053859460 2024-05-25 06:41:29,781 INFO PWD=/results/gatling/login-tree-100/loadtest-20240525053859460 2024-05-25 06:41:29,781 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-05-25 06:41:29,781 INFO /results/gatling 2024-05-25 06:41:29,781 INFO 2024-05-25 06:41:29,781 INFO ---------------------- 2024-05-25 06:41:29,781 INFO [25/May/2024 07:39:30] Update /results/gatling//latest 2024-05-25 06:41:29,781 INFO ---------------------- 2024-05-25 06:41:29,781 INFO 2024-05-25 06:41:29,781 INFO ---------------------- 2024-05-25 06:41:29,781 INFO [25/May/2024 07:39:30] Update /results/gatling//latest.tar.gz 2024-05-25 06:41:29,781 INFO ---------------------- 2024-05-25 06:41:29,781 INFO ________________________________________________________________________________ [2024-05-25 06:41:29] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 06:41:29,782 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt is small (0 lines), printing all lines: 2024-05-25 06:41:29,782 DEBUG ----- output ----- 2024-05-25 06:41:29,782 DEBUG 2024-05-25 06:41:29,782 INFO ________________________________________________________________________________ [2024-05-25 06:41:29] login-tree-100 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-05-25 06:41:29] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 06:41:29] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped