--Task-- name: login-tree-10 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: [] preceding_task: None options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 10, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-12 23:49:48] login-tree-10 pre : N/A ________________________________________________________________________________ 2024-06-12 23:49:48 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-12 23:49:48 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-10/stdout.txt Pod log : /results/orders/login-tree-10 2024-06-12 23:49:48,949 INFO 2024-06-12 23:49:48,949 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 23:49:48,950 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 23:49:49,274 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 23:49:49,274 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:49,274 DEBUG --- stdout --- 2024-06-12 23:49:49,274 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 23:49:49,274 DEBUG --- stderr --- 2024-06-12 23:49:49,274 DEBUG 2024-06-12 23:49:49 - INFO: Get overseer pod resources requests memory 2024-06-12 23:49:49,275 INFO 2024-06-12 23:49:49,275 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-6c7d65494f-sln6z --output jsonpath={.spec.containers[].resources.requests.memory} 2024-06-12 23:49:49,275 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 23:49:49,600 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:49,600 DEBUG --- stdout --- 2024-06-12 23:49:49,600 DEBUG 4Gi 2024-06-12 23:49:49,600 DEBUG --- stderr --- 2024-06-12 23:49:49,600 DEBUG ________________________________________________________________________________ [2024-06-12 23:49:49] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-12 23:49:49,601 INFO 2024-06-12 23:49:49,601 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 23:49:49,601 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 23:49:49,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 23:49:49,897 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:49,897 DEBUG --- stdout --- 2024-06-12 23:49:49,897 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 23:49:49,897 DEBUG --- stderr --- 2024-06-12 23:49:49,897 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-12 23:49:49] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-12 23:49:49,898 INFO 2024-06-12 23:49:49,898 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 23:49:49,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 23:49:50,201 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 23:49:50,201 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:50,201 DEBUG --- stdout --- 2024-06-12 23:49:50,201 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 23:49:50,201 DEBUG --- stderr --- 2024-06-12 23:49:50,202 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json { "name": "login-tree-10", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.loginProtect@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-10", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -Dlogin_tree=Login_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-12 23:49:50] login-tree-10 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-12 23:49:50,203 INFO 2024-06-12 23:49:50,203 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-12 23:49:50,203 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-12 23:49:50,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 23:49:50,487 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:50,487 DEBUG --- stdout --- 2024-06-12 23:49:50,487 DEBUG WAITING 2024-06-12 23:49:50,487 DEBUG --- stderr --- 2024-06-12 23:49:50,487 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 26 0 --:--:-- --:--:-- --:--:-- 26 ________________________________________________________________________________ [2024-06-12 23:49:50] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-12 23:49:50,487 INFO 2024-06-12 23:49:50,487 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 23:49:50,487 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 23:49:50,779 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 23:49:50,779 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:50,779 DEBUG --- stdout --- 2024-06-12 23:49:50,779 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 23:49:50,779 DEBUG --- stderr --- 2024-06-12 23:49:50,779 DEBUG 2024-06-12 23:49:50,780 INFO 2024-06-12 23:49:50,780 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-6c7d65494f-sln6z:/results/orders/order.json 2024-06-12 23:49:50,780 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 23:49:52,145 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:52,145 DEBUG --- stdout --- 2024-06-12 23:49:52,145 DEBUG 2024-06-12 23:49:52,145 DEBUG --- stderr --- 2024-06-12 23:49:52,145 DEBUG Order has been successfully forwarded to overseer 2024-06-12 23:49:52 - INFO: Check if Gatling simulation started successfully 2024-06-12 23:49:52,146 INFO 2024-06-12 23:49:52,146 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 23:49:52,146 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 23:49:52,451 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 23:49:52,451 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:49:52,453 DEBUG --- stdout --- 2024-06-12 23:49:52,453 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 23:49:52,453 DEBUG --- stderr --- 2024-06-12 23:49:52,453 DEBUG 2024-06-12 23:49:52,453 INFO 2024-06-12 23:49:52,453 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-10/stdout.txt | grep "started..." 2024-06-12 23:49:52,453 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-12 23:49:53,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-12 23:49:58,916 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-12 23:50:04,681 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-12 23:50:04,681 INFO [loop_until]: OK (rc = 0) 2024-06-12 23:50:04,681 DEBUG --- stdout --- 2024-06-12 23:50:04,681 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 00:49:54.437 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 00:49:58.209 [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"); ^ 00:49:58.212 [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"); ^ 00:49:59.789 [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-06-12 23:50:04,681 DEBUG --- stderr --- 2024-06-12 23:50:04,681 DEBUG ________________________________________________________________________________ [2024-06-12 23:50:04] login-tree-10 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, 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 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-13 00:52:15] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-13 00:52:15,002 INFO 2024-06-13 00:52:15,003 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:15,003 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:15,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:15,381 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:15,381 DEBUG --- stdout --- 2024-06-13 00:52:15,381 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:15,381 DEBUG --- stderr --- 2024-06-13 00:52:15,381 DEBUG 2024-06-13 00:52:15,381 INFO 2024-06-13 00:52:15,381 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-13 00:52:15,381 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 00:52:16,175 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:16,176 DEBUG --- stdout --- 2024-06-13 00:52:16,176 DEBUG 2024-06-13 00:52:16,176 DEBUG --- stderr --- 2024-06-13 00:52:16,176 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-13 00:52:16] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-13 00:52:16 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json 2024-06-13 00:52:16,177 INFO 2024-06-13 00:52:16,177 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:16,177 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:16,490 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:16,491 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:16,491 DEBUG --- stdout --- 2024-06-13 00:52:16,491 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:16,491 DEBUG --- stderr --- 2024-06-13 00:52:16,491 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 00:52:16,492 INFO 2024-06-13 00:52:16,492 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:16,492 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:16,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:16,801 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:16,801 DEBUG --- stdout --- 2024-06-13 00:52:16,801 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:16,801 DEBUG --- stderr --- 2024-06-13 00:52:16,801 DEBUG 2024-06-13 00:52:16,802 INFO 2024-06-13 00:52:16,802 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/rc.txt" ]; then echo "/results/orders/login-tree-10/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/rc.txt file found" 2024-06-13 00:52:16,802 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 00:52:17,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 00:52:17,614 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:17,614 DEBUG --- stdout --- 2024-06-13 00:52:17,614 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-06-13 00:52:17,614 DEBUG --- stderr --- 2024-06-13 00:52:17,614 DEBUG 2024-06-13 00:52:17,615 INFO 2024-06-13 00:52:17,615 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:17,615 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:17,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:17,915 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:17,915 DEBUG --- stdout --- 2024-06-13 00:52:17,915 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:17,915 DEBUG --- stderr --- 2024-06-13 00:52:17,915 DEBUG 2024-06-13 00:52:17,915 INFO 2024-06-13 00:52:17,916 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/rc.txt 2024-06-13 00:52:18,717 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 00:52:18,717 DEBUG --- stdout --- 2024-06-13 00:52:18,717 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-06-13 00:52:18,717 DEBUG --- stderr --- 2024-06-13 00:52:18,717 DEBUG 2024-06-13 00:52:18,717 INFO - Download file (size 4k) 2024-06-13 00:52:18,718 INFO 2024-06-13 00:52:18,718 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:18,719 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:19,020 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:19,021 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:19,021 DEBUG --- stdout --- 2024-06-13 00:52:19,021 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:19,021 DEBUG --- stderr --- 2024-06-13 00:52:19,021 DEBUG source : /results/orders/login-tree-10/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-13 00:52:19,021 INFO 2024-06-13 00:52:19,021 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:19,021 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:19,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:19,319 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:19,319 DEBUG --- stdout --- 2024-06-13 00:52:19,319 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:19,319 DEBUG --- stderr --- 2024-06-13 00:52:19,319 DEBUG 2024-06-13 00:52:19,319 INFO 2024-06-13 00:52:19,319 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-13 00:52:19,319 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 00:52:20,046 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:20,046 DEBUG --- stdout --- 2024-06-13 00:52:20,046 DEBUG tar: Removing leading `/' from member names 2024-06-13 00:52:20,047 DEBUG --- stderr --- 2024-06-13 00:52:20,047 DEBUG 2024-06-13 00:52:20,047 INFO 2024-06-13 00:52:20,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-06-13 00:52:20,047 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:20,359 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:20,359 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:20,359 DEBUG --- stdout --- 2024-06-13 00:52:20,359 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:20,359 DEBUG --- stderr --- 2024-06-13 00:52:20,359 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 00:52:20,360 INFO 2024-06-13 00:52:20,360 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:20,360 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:20,665 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:20,666 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:20,666 DEBUG --- stdout --- 2024-06-13 00:52:20,666 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:20,666 DEBUG --- stderr --- 2024-06-13 00:52:20,666 DEBUG 2024-06-13 00:52:20,667 INFO 2024-06-13 00:52:20,667 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stderr.txt" ]; then echo "/results/orders/login-tree-10/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stderr.txt file found" 2024-06-13 00:52:20,667 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 00:52:21,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 00:52:21,484 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:21,484 DEBUG --- stdout --- 2024-06-13 00:52:21,484 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-06-13 00:52:21,484 DEBUG --- stderr --- 2024-06-13 00:52:21,484 DEBUG 2024-06-13 00:52:21,485 INFO 2024-06-13 00:52:21,485 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:21,485 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:21,797 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:21,797 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:21,797 DEBUG --- stdout --- 2024-06-13 00:52:21,798 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:21,798 DEBUG --- stderr --- 2024-06-13 00:52:21,798 DEBUG 2024-06-13 00:52:21,798 INFO 2024-06-13 00:52:21,798 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/stderr.txt 2024-06-13 00:52:22,701 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 00:52:22,701 DEBUG --- stdout --- 2024-06-13 00:52:22,701 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-06-13 00:52:22,701 DEBUG --- stderr --- 2024-06-13 00:52:22,701 DEBUG 2024-06-13 00:52:22,701 INFO - Download file (size 0k) 2024-06-13 00:52:22,701 INFO 2024-06-13 00:52:22,701 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:22,701 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:23,012 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:23,012 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:23,012 DEBUG --- stdout --- 2024-06-13 00:52:23,012 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:23,012 DEBUG --- stderr --- 2024-06-13 00:52:23,012 DEBUG source : /results/orders/login-tree-10/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-13 00:52:23,012 INFO 2024-06-13 00:52:23,013 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:23,013 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:23,324 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:23,324 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:23,324 DEBUG --- stdout --- 2024-06-13 00:52:23,324 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:23,324 DEBUG --- stderr --- 2024-06-13 00:52:23,324 DEBUG 2024-06-13 00:52:23,325 INFO 2024-06-13 00:52:23,325 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-13 00:52:23,325 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 00:52:24,070 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:24,070 DEBUG --- stdout --- 2024-06-13 00:52:24,070 DEBUG tar: Removing leading `/' from member names 2024-06-13 00:52:24,070 DEBUG --- stderr --- 2024-06-13 00:52:24,070 DEBUG 2024-06-13 00:52:24,071 INFO 2024-06-13 00:52:24,071 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:24,071 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:24,375 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:24,375 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:24,375 DEBUG --- stdout --- 2024-06-13 00:52:24,375 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:24,375 DEBUG --- stderr --- 2024-06-13 00:52:24,375 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 00:52:24,375 INFO 2024-06-13 00:52:24,375 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:24,375 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:24,671 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:24,671 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:24,672 DEBUG --- stdout --- 2024-06-13 00:52:24,672 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:24,672 DEBUG --- stderr --- 2024-06-13 00:52:24,672 DEBUG 2024-06-13 00:52:24,672 INFO 2024-06-13 00:52:24,672 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stdout.txt" ]; then echo "/results/orders/login-tree-10/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stdout.txt file found" 2024-06-13 00:52:24,672 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 00:52:25,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 00:52:25,477 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:25,477 DEBUG --- stdout --- 2024-06-13 00:52:25,478 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-06-13 00:52:25,478 DEBUG --- stderr --- 2024-06-13 00:52:25,478 DEBUG 2024-06-13 00:52:25,478 INFO 2024-06-13 00:52:25,478 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:25,478 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:25,775 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:25,775 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:25,776 DEBUG --- stdout --- 2024-06-13 00:52:25,776 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:25,776 DEBUG --- stderr --- 2024-06-13 00:52:25,776 DEBUG 2024-06-13 00:52:25,776 INFO 2024-06-13 00:52:25,776 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/stdout.txt 2024-06-13 00:52:26,574 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 00:52:26,574 DEBUG --- stdout --- 2024-06-13 00:52:26,574 DEBUG 1084 /results/orders/login-tree-10/stdout.txt 2024-06-13 00:52:26,574 DEBUG --- stderr --- 2024-06-13 00:52:26,574 DEBUG 2024-06-13 00:52:26,574 INFO - Download file (size 1084k) 2024-06-13 00:52:26,575 INFO 2024-06-13 00:52:26,575 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:26,575 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:26,887 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:26,887 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:26,887 DEBUG --- stdout --- 2024-06-13 00:52:26,887 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:26,888 DEBUG --- stderr --- 2024-06-13 00:52:26,888 DEBUG source : /results/orders/login-tree-10/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-13 00:52:26,888 INFO 2024-06-13 00:52:26,888 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:26,888 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:27,196 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:27,196 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:27,196 DEBUG --- stdout --- 2024-06-13 00:52:27,196 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:27,197 DEBUG --- stderr --- 2024-06-13 00:52:27,197 DEBUG 2024-06-13 00:52:27,197 INFO 2024-06-13 00:52:27,197 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-13 00:52:27,197 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 00:52:28,325 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:28,326 DEBUG --- stdout --- 2024-06-13 00:52:28,326 DEBUG tar: Removing leading `/' from member names 2024-06-13 00:52:28,326 DEBUG --- stderr --- 2024-06-13 00:52:28,326 DEBUG ________________________________________________________________________________ [2024-06-13 00:52:28] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-06-13 00:52:28,327 INFO 2024-06-13 00:52:28,327 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:28,327 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:28,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:28,660 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:28,660 DEBUG --- stdout --- 2024-06-13 00:52:28,660 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:28,660 DEBUG --- stderr --- 2024-06-13 00:52:28,660 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 00:52:28,660 INFO 2024-06-13 00:52:28,660 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:28,660 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:28,981 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:28,981 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:28,981 DEBUG --- stdout --- 2024-06-13 00:52:28,981 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:28,981 DEBUG --- stderr --- 2024-06-13 00:52:28,981 DEBUG 2024-06-13 00:52:28,982 INFO 2024-06-13 00:52:28,982 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/cmd.txt" ]; then echo "/results/orders/login-tree-10/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/cmd.txt file found" 2024-06-13 00:52:28,982 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 00:52:29,791 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 00:52:29,791 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:29,791 DEBUG --- stdout --- 2024-06-13 00:52:29,791 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-06-13 00:52:29,791 DEBUG --- stderr --- 2024-06-13 00:52:29,791 DEBUG 2024-06-13 00:52:29,792 INFO 2024-06-13 00:52:29,792 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:29,792 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:30,105 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:30,106 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:30,106 DEBUG --- stdout --- 2024-06-13 00:52:30,106 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:30,106 DEBUG --- stderr --- 2024-06-13 00:52:30,106 DEBUG 2024-06-13 00:52:30,106 INFO 2024-06-13 00:52:30,106 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/cmd.txt 2024-06-13 00:52:30,922 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 00:52:30,923 DEBUG --- stdout --- 2024-06-13 00:52:30,924 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-06-13 00:52:30,924 DEBUG --- stderr --- 2024-06-13 00:52:30,924 DEBUG 2024-06-13 00:52:30,924 INFO - Download file (size 4k) 2024-06-13 00:52:30,925 INFO 2024-06-13 00:52:30,925 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:30,925 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:31,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:31,238 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:31,238 DEBUG --- stdout --- 2024-06-13 00:52:31,239 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:31,239 DEBUG --- stderr --- 2024-06-13 00:52:31,239 DEBUG source : /results/orders/login-tree-10/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-13 00:52:31,239 INFO 2024-06-13 00:52:31,239 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 00:52:31,239 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 00:52:31,547 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 00:52:31,548 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:31,548 DEBUG --- stdout --- 2024-06-13 00:52:31,548 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 00:52:31,548 DEBUG --- stderr --- 2024-06-13 00:52:31,548 DEBUG 2024-06-13 00:52:31,549 INFO 2024-06-13 00:52:31,549 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-13 00:52:31,549 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 00:52:32,281 INFO [loop_until]: OK (rc = 0) 2024-06-13 00:52:32,281 DEBUG --- stdout --- 2024-06-13 00:52:32,281 DEBUG tar: Removing leading `/' from member names 2024-06-13 00:52:32,281 DEBUG --- stderr --- 2024-06-13 00:52:32,281 DEBUG 2024-06-13 00:52:32,282 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt is small (1 lines), printing all lines: 2024-06-13 00:52:32,282 DEBUG ----- output ----- 2024-06-13 00:52:32,282 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-13 00:52:32,282 INFO ________________________________________________________________________________ [2024-06-13 00:52:32] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-06-13 00:52:32,282 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt is small (1 lines), printing all lines: 2024-06-13 00:52:32,283 DEBUG ----- output ----- 2024-06-13 00:52:32,283 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 00:52:32,283 INFO ________________________________________________________________________________ [2024-06-13 00:52:32] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-13 00:52:32,285 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 17252 lines): 2024-06-13 00:52:32,286 DEBUG ----- output ----- 2024-06-13 00:52:32,286 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-13 00:52:32,286 INFO 00:49:54.437 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-13 00:52:32,286 INFO 00:49:58.209 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-13 00:52:32,286 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-13 00:52:32,286 INFO ^ 2024-06-13 00:52:32,286 INFO 00:49:58.212 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-13 00:52:32,286 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-13 00:52:32,286 INFO ^ 2024-06-13 00:52:32,286 INFO 00:49:59.789 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-13 00:52:32,286 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-13 00:52:32,286 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 00:52:32,286 INFO 2024-06-13 00:52:32,286 INFO ================================================================================ 2024-06-13 00:52:32,286 INFO 2024-06-13 00:50:07 5s elapsed 2024-06-13 00:52:32,286 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,286 INFO > Global (OK=10 KO=0 ) 2024-06-13 00:52:32,286 INFO > protectLoginInitiate (OK=4 KO=0 ) 2024-06-13 00:52:32,286 INFO > PingOneProtectInitializeCallback (OK=3 KO=0 ) 2024-06-13 00:52:32,286 INFO > restLoginUsernamePasswordCallback (OK=3 KO=0 ) 2024-06-13 00:52:32,286 INFO 2024-06-13 00:52:32,286 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,286 INFO [- ] 0% 2024-06-13 00:52:32,286 INFO waiting: 36069 / active: 5 / done: 0 2024-06-13 00:52:32,286 INFO ================================================================================ 2024-06-13 00:52:32,286 INFO 2024-06-13 00:52:32,286 INFO 2024-06-13 00:52:32,286 INFO ================================================================================ 2024-06-13 00:52:32,286 INFO 2024-06-13 00:50:12 10s elapsed 2024-06-13 00:52:32,286 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,286 INFO > Global (OK=60 KO=0 ) 2024-06-13 00:52:32,287 INFO > protectLoginInitiate (OK=16 KO=0 ) 2024-06-13 00:52:32,287 INFO > PingOneProtectInitializeCallback (OK=16 KO=0 ) 2024-06-13 00:52:32,287 INFO > restLoginUsernamePasswordCallback (OK=15 KO=0 ) 2024-06-13 00:52:32,287 INFO > PingOneProtectEvaluationCallback (OK=13 KO=0 ) 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,287 INFO [- ] 0% 2024-06-13 00:52:32,287 INFO waiting: 36057 / active: 4 / done: 13 2024-06-13 00:52:32,287 INFO ================================================================================ 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO ================================================================================ 2024-06-13 00:52:32,287 INFO 2024-06-13 00:50:17 15s elapsed 2024-06-13 00:52:32,287 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,287 INFO > Global (OK=142 KO=0 ) 2024-06-13 00:52:32,287 INFO > protectLoginInitiate (OK=37 KO=0 ) 2024-06-13 00:52:32,287 INFO > PingOneProtectInitializeCallback (OK=36 KO=0 ) 2024-06-13 00:52:32,287 INFO > restLoginUsernamePasswordCallback (OK=36 KO=0 ) 2024-06-13 00:52:32,287 INFO > PingOneProtectEvaluationCallback (OK=33 KO=0 ) 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,287 INFO [- ] 0% 2024-06-13 00:52:32,287 INFO waiting: 36036 / active: 5 / done: 33 2024-06-13 00:52:32,287 INFO ================================================================================ 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO ================================================================================ 2024-06-13 00:52:32,287 INFO 2024-06-13 00:50:22 20s elapsed 2024-06-13 00:52:32,287 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,287 INFO > Global (OK=253 KO=0 ) 2024-06-13 00:52:32,287 INFO > protectLoginInitiate (OK=66 KO=0 ) 2024-06-13 00:52:32,287 INFO > PingOneProtectInitializeCallback (OK=64 KO=0 ) 2024-06-13 00:52:32,287 INFO > restLoginUsernamePasswordCallback (OK=63 KO=0 ) 2024-06-13 00:52:32,287 INFO > PingOneProtectEvaluationCallback (OK=60 KO=0 ) 2024-06-13 00:52:32,287 INFO 2024-06-13 00:52:32,287 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,287 INFO [- ] 0% 2024-06-13 00:52:32,287 INFO waiting: 36007 / active: 7 / done: 60 2024-06-13 00:52:32,288 INFO ================================================================================ 2024-06-13 00:52:32,288 INFO 2024-06-13 00:52:32,288 INFO 2024-06-13 00:52:32,288 INFO ================================================================================ 2024-06-13 00:52:32,288 INFO 2024-06-13 00:50:27 25s elapsed 2024-06-13 00:52:32,288 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,288 INFO > Global (OK=402 KO=0 ) 2024-06-13 00:52:32,288 INFO > protectLoginInitiate (OK=104 KO=0 ) 2024-06-13 00:52:32,288 INFO > PingOneProtectInitializeCallback (OK=101 KO=0 ) 2024-06-13 00:52:32,288 INFO > restLoginUsernamePasswordCallback (OK=100 KO=0 ) 2024-06-13 00:52:32,288 INFO > PingOneProtectEvaluationCallback (OK=97 KO=0 ) 2024-06-13 00:52:32,288 INFO 2024-06-13 00:52:32,288 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,288 INFO [- ] 0% 2024-06-13 00:52:32,288 INFO waiting: 35969 / active: 8 / done: 97 2024-06-13 00:52:32,288 INFO ================================================================================ 2024-06-13 00:52:32,288 INFO 2024-06-13 00:52:32,288 INFO 2024-06-13 00:52:32,288 INFO ================================================================================ 2024-06-13 00:52:32,288 INFO 2024-06-13 00:50:32 30s elapsed 2024-06-13 00:52:32,288 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,288 INFO > Global (OK=584 KO=0 ) 2024-06-13 00:52:32,288 INFO > protectLoginInitiate (OK=149 KO=0 ) 2024-06-13 00:52:32,288 INFO > PingOneProtectInitializeCallback (OK=148 KO=0 ) 2024-06-13 00:52:32,288 INFO > restLoginUsernamePasswordCallback (OK=146 KO=0 ) 2024-06-13 00:52:32,288 INFO > PingOneProtectEvaluationCallback (OK=141 KO=0 ) 2024-06-13 00:52:32,288 INFO 2024-06-13 00:52:32,289 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,289 INFO [- ] 0% 2024-06-13 00:52:32,289 INFO waiting: 35924 / active: 9 / done: 141 2024-06-13 00:52:32,289 INFO ================================================================================ 2024-06-13 00:52:32,289 INFO 2024-06-13 00:52:32,289 INFO [...] 2024-06-13 00:52:32,293 INFO waiting: 93 / active: 11 / done: 35970 2024-06-13 00:52:32,293 INFO ================================================================================ 2024-06-13 00:52:32,293 INFO 2024-06-13 00:52:32,293 INFO 2024-06-13 00:52:32,293 INFO ================================================================================ 2024-06-13 00:52:32,293 INFO 2024-06-13 01:50:27 3625s elapsed 2024-06-13 00:52:32,293 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,293 INFO > Global (OK=144106 KO=1 ) 2024-06-13 00:52:32,293 INFO > protectLoginInitiate (OK=36027 KO=1 ) 2024-06-13 00:52:32,293 INFO > PingOneProtectInitializeCallback (OK=36027 KO=0 ) 2024-06-13 00:52:32,293 INFO > restLoginUsernamePasswordCallback (OK=36025 KO=0 ) 2024-06-13 00:52:32,293 INFO > PingOneProtectEvaluationCallback (OK=36019 KO=0 ) 2024-06-13 00:52:32,293 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-13 00:52:32,293 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 00:52:32,293 INFO > status.find.is(200), but actually found 502 1 (20.00%) 2024-06-13 00:52:32,293 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,294 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,294 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,294 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 (20.00%) 2024-06-13 00:52:32,294 INFO No attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO 2024-06-13 00:52:32,294 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,294 INFO [#########################################################################-] 99% 2024-06-13 00:52:32,294 INFO waiting: 45 / active: 9 / done: 36020 2024-06-13 00:52:32,294 INFO ================================================================================ 2024-06-13 00:52:32,294 INFO 2024-06-13 00:52:32,294 INFO 2024-06-13 00:52:32,294 INFO ================================================================================ 2024-06-13 00:52:32,294 INFO 2024-06-13 01:50:32 3630s elapsed 2024-06-13 00:52:32,294 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,294 INFO > Global (OK=144284 KO=1 ) 2024-06-13 00:52:32,294 INFO > protectLoginInitiate (OK=36071 KO=1 ) 2024-06-13 00:52:32,294 INFO > PingOneProtectInitializeCallback (OK=36070 KO=0 ) 2024-06-13 00:52:32,294 INFO > restLoginUsernamePasswordCallback (OK=36070 KO=0 ) 2024-06-13 00:52:32,294 INFO > PingOneProtectEvaluationCallback (OK=36065 KO=0 ) 2024-06-13 00:52:32,294 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-13 00:52:32,294 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 00:52:32,294 INFO > status.find.is(200), but actually found 502 1 (20.00%) 2024-06-13 00:52:32,294 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,294 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,294 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,294 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 (20.00%) 2024-06-13 00:52:32,294 INFO No attribute named 'callbacks' is defined 2024-06-13 00:52:32,294 INFO 2024-06-13 00:52:32,294 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,294 INFO [#########################################################################-] 99% 2024-06-13 00:52:32,294 INFO waiting: 0 / active: 8 / done: 36066 2024-06-13 00:52:32,295 INFO ================================================================================ 2024-06-13 00:52:32,295 INFO 2024-06-13 00:52:32,295 INFO 2024-06-13 00:52:32,295 INFO ================================================================================ 2024-06-13 00:52:32,295 INFO 2024-06-13 01:50:33 3630s elapsed 2024-06-13 00:52:32,295 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 00:52:32,295 INFO > Global (OK=144300 KO=1 ) 2024-06-13 00:52:32,295 INFO > protectLoginInitiate (OK=36073 KO=1 ) 2024-06-13 00:52:32,295 INFO > PingOneProtectInitializeCallback (OK=36073 KO=0 ) 2024-06-13 00:52:32,295 INFO > restLoginUsernamePasswordCallback (OK=36073 KO=0 ) 2024-06-13 00:52:32,295 INFO > PingOneProtectEvaluationCallback (OK=36073 KO=0 ) 2024-06-13 00:52:32,295 INFO > restloginProgressiveProfileCallback (OK=8 KO=0 ) 2024-06-13 00:52:32,295 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 00:52:32,295 INFO > status.find.is(200), but actually found 502 1 (20.00%) 2024-06-13 00:52:32,295 INFO > PingOneProtectInitializeCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,295 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,295 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,295 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,295 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 1 (20.00%) 2024-06-13 00:52:32,295 INFO attribute named 'callbacks' is defined 2024-06-13 00:52:32,295 INFO > restloginProgressiveProfileCallback: Failed to build request: 1 (20.00%) 2024-06-13 00:52:32,295 INFO No attribute named 'callbacks' is defined 2024-06-13 00:52:32,295 INFO 2024-06-13 00:52:32,295 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 00:52:32,295 INFO [##########################################################################]100% 2024-06-13 00:52:32,295 INFO waiting: 0 / active: 0 / done: 36074 2024-06-13 00:52:32,295 INFO ================================================================================ 2024-06-13 00:52:32,295 INFO 2024-06-13 00:52:32,295 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-13 00:52:32,295 INFO 2024-06-13 00:52:32,295 INFO ---------------------- 2024-06-13 00:52:32,295 INFO [13/Jun/2024 01:50:33] Create tar file login-tree-10.tar.gz 2024-06-13 00:52:32,295 INFO ---------------------- 2024-06-13 00:52:32,295 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-13 00:52:32,295 INFO RESULT_FOLDER=login-tree-10 2024-06-13 00:52:32,295 INFO SIMULATION_PATH=login-tree-10/loadtest-20240612235001866 2024-06-13 00:52:32,295 INFO PWD=/results/gatling/login-tree-10/loadtest-20240612235001866 2024-06-13 00:52:32,295 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-06-13 00:52:32,295 INFO /results/gatling 2024-06-13 00:52:32,295 INFO 2024-06-13 00:52:32,295 INFO ---------------------- 2024-06-13 00:52:32,296 INFO [13/Jun/2024 01:50:33] Update /results/gatling//latest 2024-06-13 00:52:32,296 INFO ---------------------- 2024-06-13 00:52:32,296 INFO 2024-06-13 00:52:32,296 INFO ---------------------- 2024-06-13 00:52:32,296 INFO [13/Jun/2024 01:50:33] Update /results/gatling//latest.tar.gz 2024-06-13 00:52:32,296 INFO ---------------------- 2024-06-13 00:52:32,296 INFO ________________________________________________________________________________ [2024-06-13 00:52:32] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-13 00:52:32,296 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt is small (0 lines), printing all lines: 2024-06-13 00:52:32,296 DEBUG ----- output ----- 2024-06-13 00:52:32,296 DEBUG 2024-06-13 00:52:32,296 INFO ________________________________________________________________________________ [2024-06-13 00:52:32] login-tree-10 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-13 00:52:32] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-13 00:52:32] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped