--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: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 100, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-23 04:45:50] login-tree-100 pre : N/A ________________________________________________________________________________ 2024-06-23 04:45:50 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 04:45:50 - 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-06-23 04:45:50,860 INFO 2024-06-23 04:45:50,861 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:50,861 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:51,179 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:51,179 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:51,179 DEBUG --- stdout --- 2024-06-23 04:45:51,179 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:51,179 DEBUG --- stderr --- 2024-06-23 04:45:51,179 DEBUG ________________________________________________________________________________ [2024-06-23 04:45:51] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 04:45:51,180 INFO 2024-06-23 04:45:51,180 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:51,180 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:51,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:51,483 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:51,483 DEBUG --- stdout --- 2024-06-23 04:45:51,484 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:51,484 DEBUG --- stderr --- 2024-06-23 04:45:51,484 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 04:45:51] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 04:45:51,484 INFO 2024-06-23 04:45:51,484 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:51,484 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:51,791 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:51,791 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:51,792 DEBUG --- stdout --- 2024-06-23 04:45:51,793 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:51,793 DEBUG --- stderr --- 2024-06-23 04:45:51,793 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.loginProtect@ema-perf-p1\" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-100", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-23 04:45:51] 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-06-23 04:45:51,795 INFO 2024-06-23 04:45:51,795 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 04:45:51,795 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 04:45:52,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 04:45:52,041 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:52,041 DEBUG --- stdout --- 2024-06-23 04:45:52,041 DEBUG WAITING 2024-06-23 04:45:52,041 DEBUG --- stderr --- 2024-06-23 04:45:52,041 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-23 04:45:52] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 04:45:52,042 INFO 2024-06-23 04:45:52,042 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:52,042 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:52,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:52,376 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:52,377 DEBUG --- stdout --- 2024-06-23 04:45:52,377 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:52,377 DEBUG --- stderr --- 2024-06-23 04:45:52,377 DEBUG 2024-06-23 04:45:52,377 INFO 2024-06-23 04:45:52,377 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-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 04:45:52,377 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 04:45:53,763 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:53,763 DEBUG --- stdout --- 2024-06-23 04:45:53,764 DEBUG 2024-06-23 04:45:53,764 DEBUG --- stderr --- 2024-06-23 04:45:53,764 DEBUG Order has been successfully forwarded to overseer 2024-06-23 04:45:53 - INFO: Check if Gatling simulation started successfully 2024-06-23 04:45:53,764 INFO 2024-06-23 04:45:53,764 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 04:45:53,764 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 04:45:54,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 04:45:54,137 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:45:54,137 DEBUG --- stdout --- 2024-06-23 04:45:54,137 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 04:45:54,138 DEBUG --- stderr --- 2024-06-23 04:45:54,138 DEBUG 2024-06-23 04:45:54,138 INFO 2024-06-23 04:45:54,138 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-100/stdout.txt | grep "started..." 2024-06-23 04:45:54,138 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 04:45:54,898 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 04:46:00,643 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-23 04:46:06,410 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-23 04:46:06,410 INFO [loop_until]: OK (rc = 0) 2024-06-23 04:46:06,410 DEBUG --- stdout --- 2024-06-23 04:46:06,410 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 05:45:56.308 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 05:46:01.393 [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"); ^ 05:46:01.396 [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"); ^ 05:46:03.123 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 04:46:06,410 DEBUG --- stderr --- 2024-06-23 04:46:06,410 DEBUG ________________________________________________________________________________ [2024-06-23 04:46:06] 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 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-23 05:48:16] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 05:48:16,417 INFO 2024-06-23 05:48:16,417 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:16,417 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:16,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:16,752 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:16,752 DEBUG --- stdout --- 2024-06-23 05:48:16,752 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:16,752 DEBUG --- stderr --- 2024-06-23 05:48:16,753 DEBUG 2024-06-23 05:48:16,753 INFO 2024-06-23 05:48:16,753 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-23 05:48:16,753 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 05:48:17,540 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:17,540 DEBUG --- stdout --- 2024-06-23 05:48:17,545 DEBUG 2024-06-23 05:48:17,546 DEBUG --- stderr --- 2024-06-23 05:48:17,546 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 05:48:17] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 05:48:17 - 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-06-23 05:48:17,549 INFO 2024-06-23 05:48:17,549 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:17,549 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:17,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:17,915 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:17,915 DEBUG --- stdout --- 2024-06-23 05:48:17,916 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:17,916 DEBUG --- stderr --- 2024-06-23 05:48:17,916 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 05:48:17,916 INFO 2024-06-23 05:48:17,916 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:17,916 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:18,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:18,242 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:18,242 DEBUG --- stdout --- 2024-06-23 05:48:18,242 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:18,242 DEBUG --- stderr --- 2024-06-23 05:48:18,242 DEBUG 2024-06-23 05:48:18,243 INFO 2024-06-23 05:48:18,243 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-06-23 05:48:18,243 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 05:48:19,062 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 05:48:19,062 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:19,062 DEBUG --- stdout --- 2024-06-23 05:48:19,062 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-06-23 05:48:19,062 DEBUG --- stderr --- 2024-06-23 05:48:19,062 DEBUG 2024-06-23 05:48:19,063 INFO 2024-06-23 05:48:19,063 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:19,063 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:19,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:19,380 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:19,380 DEBUG --- stdout --- 2024-06-23 05:48:19,380 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:19,380 DEBUG --- stderr --- 2024-06-23 05:48:19,380 DEBUG 2024-06-23 05:48:19,380 INFO 2024-06-23 05:48:19,380 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-100/rc.txt 2024-06-23 05:48:20,192 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 05:48:20,192 DEBUG --- stdout --- 2024-06-23 05:48:20,193 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-06-23 05:48:20,194 DEBUG --- stderr --- 2024-06-23 05:48:20,194 DEBUG 2024-06-23 05:48:20,194 INFO - Download file (size 4k) 2024-06-23 05:48:20,194 INFO 2024-06-23 05:48:20,194 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:20,194 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:20,520 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:20,521 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:20,521 DEBUG --- stdout --- 2024-06-23 05:48:20,521 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:20,521 DEBUG --- stderr --- 2024-06-23 05:48:20,521 DEBUG source : /results/orders/login-tree-100/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-06-23 05:48:20,521 INFO 2024-06-23 05:48:20,521 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:20,521 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:20,840 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:20,840 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:20,840 DEBUG --- stdout --- 2024-06-23 05:48:20,841 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:20,841 DEBUG --- stderr --- 2024-06-23 05:48:20,841 DEBUG 2024-06-23 05:48:20,841 INFO 2024-06-23 05:48:20,841 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-06-23 05:48:20,841 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 05:48:21,638 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:21,639 DEBUG --- stdout --- 2024-06-23 05:48:21,639 DEBUG tar: Removing leading `/' from member names 2024-06-23 05:48:21,639 DEBUG --- stderr --- 2024-06-23 05:48:21,639 DEBUG 2024-06-23 05:48:21,639 INFO 2024-06-23 05:48:21,639 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:21,639 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:21,960 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:21,961 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:21,961 DEBUG --- stdout --- 2024-06-23 05:48:21,961 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:21,961 DEBUG --- stderr --- 2024-06-23 05:48:21,961 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 05:48:21,961 INFO 2024-06-23 05:48:21,961 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:21,961 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:22,268 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:22,268 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:22,269 DEBUG --- stdout --- 2024-06-23 05:48:22,269 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:22,269 DEBUG --- stderr --- 2024-06-23 05:48:22,269 DEBUG 2024-06-23 05:48:22,269 INFO 2024-06-23 05:48:22,269 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-06-23 05:48:22,269 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 05:48:23,103 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 05:48:23,103 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:23,103 DEBUG --- stdout --- 2024-06-23 05:48:23,103 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-06-23 05:48:23,103 DEBUG --- stderr --- 2024-06-23 05:48:23,103 DEBUG 2024-06-23 05:48:23,104 INFO 2024-06-23 05:48:23,104 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:23,104 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:23,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:23,427 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:23,427 DEBUG --- stdout --- 2024-06-23 05:48:23,427 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:23,427 DEBUG --- stderr --- 2024-06-23 05:48:23,427 DEBUG 2024-06-23 05:48:23,427 INFO 2024-06-23 05:48:23,427 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-100/stderr.txt 2024-06-23 05:48:24,247 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 05:48:24,247 DEBUG --- stdout --- 2024-06-23 05:48:24,247 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-06-23 05:48:24,247 DEBUG --- stderr --- 2024-06-23 05:48:24,247 DEBUG 2024-06-23 05:48:24,247 INFO - Download file (size 0k) 2024-06-23 05:48:24,248 INFO 2024-06-23 05:48:24,248 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:24,248 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:24,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:24,626 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:24,626 DEBUG --- stdout --- 2024-06-23 05:48:24,627 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:24,627 DEBUG --- stderr --- 2024-06-23 05:48:24,627 DEBUG source : /results/orders/login-tree-100/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-06-23 05:48:24,627 INFO 2024-06-23 05:48:24,627 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:24,628 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:24,957 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:24,957 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:24,958 DEBUG --- stdout --- 2024-06-23 05:48:24,958 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:24,958 DEBUG --- stderr --- 2024-06-23 05:48:24,958 DEBUG 2024-06-23 05:48:24,958 INFO 2024-06-23 05:48:24,958 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-06-23 05:48:24,958 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 05:48:25,695 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:25,696 DEBUG --- stdout --- 2024-06-23 05:48:25,696 DEBUG tar: Removing leading `/' from member names 2024-06-23 05:48:25,696 DEBUG --- stderr --- 2024-06-23 05:48:25,696 DEBUG 2024-06-23 05:48:25,696 INFO 2024-06-23 05:48:25,696 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:25,697 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:26,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:26,027 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:26,027 DEBUG --- stdout --- 2024-06-23 05:48:26,027 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:26,028 DEBUG --- stderr --- 2024-06-23 05:48:26,028 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 05:48:26,028 INFO 2024-06-23 05:48:26,028 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:26,028 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:26,338 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:26,339 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:26,339 DEBUG --- stdout --- 2024-06-23 05:48:26,339 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:26,339 DEBUG --- stderr --- 2024-06-23 05:48:26,339 DEBUG 2024-06-23 05:48:26,339 INFO 2024-06-23 05:48:26,339 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-06-23 05:48:26,339 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 05:48:27,144 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 05:48:27,145 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:27,145 DEBUG --- stdout --- 2024-06-23 05:48:27,145 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-06-23 05:48:27,145 DEBUG --- stderr --- 2024-06-23 05:48:27,145 DEBUG 2024-06-23 05:48:27,145 INFO 2024-06-23 05:48:27,145 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:27,145 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:27,467 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:27,467 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:27,468 DEBUG --- stdout --- 2024-06-23 05:48:27,468 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:27,468 DEBUG --- stderr --- 2024-06-23 05:48:27,468 DEBUG 2024-06-23 05:48:27,468 INFO 2024-06-23 05:48:27,468 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-100/stdout.txt 2024-06-23 05:48:28,286 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 05:48:28,286 DEBUG --- stdout --- 2024-06-23 05:48:28,286 DEBUG 3140 /results/orders/login-tree-100/stdout.txt 2024-06-23 05:48:28,286 DEBUG --- stderr --- 2024-06-23 05:48:28,286 DEBUG 2024-06-23 05:48:28,286 INFO - Download file (size 3140k) 2024-06-23 05:48:28,287 INFO 2024-06-23 05:48:28,287 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:28,287 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:28,600 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:28,600 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:28,600 DEBUG --- stdout --- 2024-06-23 05:48:28,600 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:28,600 DEBUG --- stderr --- 2024-06-23 05:48:28,600 DEBUG source : /results/orders/login-tree-100/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-06-23 05:48:28,600 INFO 2024-06-23 05:48:28,600 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:28,600 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:28,920 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:28,920 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:28,920 DEBUG --- stdout --- 2024-06-23 05:48:28,920 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:28,920 DEBUG --- stderr --- 2024-06-23 05:48:28,920 DEBUG 2024-06-23 05:48:28,920 INFO 2024-06-23 05:48:28,921 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-06-23 05:48:28,921 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 05:48:30,125 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:30,125 DEBUG --- stdout --- 2024-06-23 05:48:30,125 DEBUG tar: Removing leading `/' from member names 2024-06-23 05:48:30,125 DEBUG --- stderr --- 2024-06-23 05:48:30,125 DEBUG ________________________________________________________________________________ [2024-06-23 05:48:30] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 05:48:30,126 INFO 2024-06-23 05:48:30,126 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:30,126 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:30,442 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:30,442 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:30,442 DEBUG --- stdout --- 2024-06-23 05:48:30,442 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:30,442 DEBUG --- stderr --- 2024-06-23 05:48:30,443 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 05:48:30,443 INFO 2024-06-23 05:48:30,443 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:30,443 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:30,764 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:30,764 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:30,765 DEBUG --- stdout --- 2024-06-23 05:48:30,765 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:30,765 DEBUG --- stderr --- 2024-06-23 05:48:30,765 DEBUG 2024-06-23 05:48:30,766 INFO 2024-06-23 05:48:30,766 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-06-23 05:48:30,766 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 05:48:31,576 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 05:48:31,576 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:31,576 DEBUG --- stdout --- 2024-06-23 05:48:31,577 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-06-23 05:48:31,577 DEBUG --- stderr --- 2024-06-23 05:48:31,577 DEBUG 2024-06-23 05:48:31,577 INFO 2024-06-23 05:48:31,577 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:31,577 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:31,889 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:31,890 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:31,890 DEBUG --- stdout --- 2024-06-23 05:48:31,890 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:31,890 DEBUG --- stderr --- 2024-06-23 05:48:31,890 DEBUG 2024-06-23 05:48:31,890 INFO 2024-06-23 05:48:31,890 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-100/cmd.txt 2024-06-23 05:48:32,691 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 05:48:32,692 DEBUG --- stdout --- 2024-06-23 05:48:32,692 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-06-23 05:48:32,692 DEBUG --- stderr --- 2024-06-23 05:48:32,692 DEBUG 2024-06-23 05:48:32,692 INFO - Download file (size 4k) 2024-06-23 05:48:32,692 INFO 2024-06-23 05:48:32,692 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:32,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:32,995 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:32,995 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:32,995 DEBUG --- stdout --- 2024-06-23 05:48:32,996 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:32,996 DEBUG --- stderr --- 2024-06-23 05:48:32,996 DEBUG source : /results/orders/login-tree-100/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-06-23 05:48:32,996 INFO 2024-06-23 05:48:32,996 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 05:48:32,996 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 05:48:33,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 05:48:33,303 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:33,303 DEBUG --- stdout --- 2024-06-23 05:48:33,303 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 05:48:33,303 DEBUG --- stderr --- 2024-06-23 05:48:33,303 DEBUG 2024-06-23 05:48:33,303 INFO 2024-06-23 05:48:33,303 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-06-23 05:48:33,303 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 05:48:34,041 INFO [loop_until]: OK (rc = 0) 2024-06-23 05:48:34,041 DEBUG --- stdout --- 2024-06-23 05:48:34,041 DEBUG tar: Removing leading `/' from member names 2024-06-23 05:48:34,041 DEBUG --- stderr --- 2024-06-23 05:48:34,041 DEBUG 2024-06-23 05:48:34,042 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-06-23 05:48:34,042 DEBUG ----- output ----- 2024-06-23 05:48:34,042 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 05:48:34,042 INFO ________________________________________________________________________________ [2024-06-23 05:48:34] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 05:48:34,042 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-06-23 05:48:34,042 DEBUG ----- output ----- 2024-06-23 05:48:34,042 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 05:48:34,042 INFO ________________________________________________________________________________ [2024-06-23 05:48:34] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 05:48:34,049 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 34279 lines): 2024-06-23 05:48:34,050 DEBUG ----- output ----- 2024-06-23 05:48:34,050 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 05:48:34,050 INFO 05:45:56.308 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-23 05:48:34,050 INFO 05:46:01.393 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-23 05:48:34,050 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 05:48:34,050 INFO ^ 2024-06-23 05:48:34,050 INFO 05:46:01.396 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-06-23 05:48:34,050 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-23 05:48:34,050 INFO ^ 2024-06-23 05:48:34,050 INFO 05:46:03.123 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-23 05:48:34,050 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 05:48:34,050 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 05:48:34,050 INFO 2024-06-23 05:48:34,050 INFO ================================================================================ 2024-06-23 05:48:34,050 INFO 2024-06-23 05:46:10 5s elapsed 2024-06-23 05:48:34,050 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,050 INFO > Global (OK=154 KO=0 ) 2024-06-23 05:48:34,050 INFO > protectLoginInitiate (OK=41 KO=0 ) 2024-06-23 05:48:34,050 INFO > PingOneProtectInitializeCallback (OK=40 KO=0 ) 2024-06-23 05:48:34,050 INFO > restLoginUsernamePasswordCallback (OK=39 KO=0 ) 2024-06-23 05:48:34,050 INFO > PingOneProtectEvaluationCallback (OK=34 KO=0 ) 2024-06-23 05:48:34,050 INFO 2024-06-23 05:48:34,050 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,050 INFO [- ] 0% 2024-06-23 05:48:34,050 INFO waiting: 361585 / active: 8 / done: 34 2024-06-23 05:48:34,051 INFO ================================================================================ 2024-06-23 05:48:34,051 INFO 2024-06-23 05:48:34,051 INFO 2024-06-23 05:48:34,051 INFO ================================================================================ 2024-06-23 05:48:34,051 INFO 2024-06-23 05:46:15 10s elapsed 2024-06-23 05:48:34,051 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,051 INFO > Global (OK=644 KO=0 ) 2024-06-23 05:48:34,051 INFO > protectLoginInitiate (OK=165 KO=0 ) 2024-06-23 05:48:34,051 INFO > PingOneProtectInitializeCallback (OK=164 KO=0 ) 2024-06-23 05:48:34,051 INFO > restLoginUsernamePasswordCallback (OK=162 KO=0 ) 2024-06-23 05:48:34,051 INFO > PingOneProtectEvaluationCallback (OK=153 KO=0 ) 2024-06-23 05:48:34,051 INFO 2024-06-23 05:48:34,051 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,051 INFO [- ] 0% 2024-06-23 05:48:34,051 INFO waiting: 361460 / active: 14 / done: 153 2024-06-23 05:48:34,051 INFO ================================================================================ 2024-06-23 05:48:34,051 INFO 2024-06-23 05:48:34,051 INFO 2024-06-23 05:48:34,051 INFO ================================================================================ 2024-06-23 05:48:34,051 INFO 2024-06-23 05:46:20 15s elapsed 2024-06-23 05:48:34,051 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,051 INFO > Global (OK=1470 KO=0 ) 2024-06-23 05:48:34,051 INFO > protectLoginInitiate (OK=374 KO=0 ) 2024-06-23 05:48:34,051 INFO > PingOneProtectInitializeCallback (OK=371 KO=0 ) 2024-06-23 05:48:34,051 INFO > restLoginUsernamePasswordCallback (OK=368 KO=0 ) 2024-06-23 05:48:34,051 INFO > PingOneProtectEvaluationCallback (OK=356 KO=0 ) 2024-06-23 05:48:34,051 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 05:48:34,051 INFO 2024-06-23 05:48:34,051 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,051 INFO [- ] 0% 2024-06-23 05:48:34,051 INFO waiting: 361251 / active: 20 / done: 356 2024-06-23 05:48:34,051 INFO ================================================================================ 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO ================================================================================ 2024-06-23 05:48:34,052 INFO 2024-06-23 05:46:25 20s elapsed 2024-06-23 05:48:34,052 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,052 INFO > Global (OK=2620 KO=0 ) 2024-06-23 05:48:34,052 INFO > protectLoginInitiate (OK=664 KO=0 ) 2024-06-23 05:48:34,052 INFO > PingOneProtectInitializeCallback (OK=661 KO=0 ) 2024-06-23 05:48:34,052 INFO > restLoginUsernamePasswordCallback (OK=657 KO=0 ) 2024-06-23 05:48:34,052 INFO > PingOneProtectEvaluationCallback (OK=637 KO=0 ) 2024-06-23 05:48:34,052 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,052 INFO [- ] 0% 2024-06-23 05:48:34,052 INFO waiting: 360960 / active: 30 / done: 637 2024-06-23 05:48:34,052 INFO ================================================================================ 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO ================================================================================ 2024-06-23 05:48:34,052 INFO 2024-06-23 05:46:30 25s elapsed 2024-06-23 05:48:34,052 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,052 INFO > Global (OK=4112 KO=0 ) 2024-06-23 05:48:34,052 INFO > protectLoginInitiate (OK=1039 KO=0 ) 2024-06-23 05:48:34,052 INFO > PingOneProtectInitializeCallback (OK=1035 KO=0 ) 2024-06-23 05:48:34,052 INFO > restLoginUsernamePasswordCallback (OK=1029 KO=0 ) 2024-06-23 05:48:34,052 INFO > PingOneProtectEvaluationCallback (OK=1008 KO=0 ) 2024-06-23 05:48:34,052 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,052 INFO [- ] 0% 2024-06-23 05:48:34,052 INFO waiting: 360584 / active: 35 / done: 1008 2024-06-23 05:48:34,052 INFO ================================================================================ 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,052 INFO 2024-06-23 05:48:34,053 INFO ================================================================================ 2024-06-23 05:48:34,053 INFO 2024-06-23 05:46:35 30s elapsed 2024-06-23 05:48:34,053 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,053 INFO > Global (OK=5934 KO=0 ) 2024-06-23 05:48:34,053 INFO > protectLoginInitiate (OK=1497 KO=0 ) 2024-06-23 05:48:34,053 INFO > PingOneProtectInitializeCallback (OK=1492 KO=0 ) 2024-06-23 05:48:34,053 INFO > restLoginUsernamePasswordCallback (OK=1486 KO=0 ) 2024-06-23 05:48:34,053 INFO > PingOneProtectEvaluationCallback (OK=1458 KO=0 ) 2024-06-23 05:48:34,053 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-23 05:48:34,053 INFO 2024-06-23 05:48:34,053 INFO [...] 2024-06-23 05:48:34,064 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,064 INFO > Global (OK=1452053 KO=326 ) 2024-06-23 05:48:34,064 INFO > protectLoginInitiate (OK=361134 KO=2 ) 2024-06-23 05:48:34,064 INFO > PingOneProtectInitializeCallback (OK=361124 KO=2 ) 2024-06-23 05:48:34,064 INFO > restLoginUsernamePasswordCallback (OK=361117 KO=6 ) 2024-06-23 05:48:34,064 INFO > PingOneProtectEvaluationCallback (OK=360939 KO=160 ) 2024-06-23 05:48:34,064 INFO > restloginProgressiveProfileCallback (OK=7739 KO=156 ) 2024-06-23 05:48:34,064 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 05:48:34,064 INFO > jsonPath($.tokenId).find.exists, found nothing 156 (46.71%) 2024-06-23 05:48:34,064 INFO > status.find.is(200), but actually found 401 155 (46.41%) 2024-06-23 05:48:34,064 INFO > status.find.is(200), but actually found 502 15 ( 4.49%) 2024-06-23 05:48:34,064 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,064 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,064 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,064 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,064 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,064 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,064 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 0.60%) 2024-06-23 05:48:34,064 INFO No attribute named 'callbacks' is defined 2024-06-23 05:48:34,064 INFO 2024-06-23 05:48:34,064 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,064 INFO [#########################################################################-] 99% 2024-06-23 05:48:34,065 INFO waiting: 484 / active: 43 / done: 361100 2024-06-23 05:48:34,065 INFO ================================================================================ 2024-06-23 05:48:34,065 INFO 2024-06-23 05:48:34,065 INFO 2024-06-23 05:48:34,065 INFO ================================================================================ 2024-06-23 05:48:34,065 INFO 2024-06-23 06:46:35 3630s elapsed 2024-06-23 05:48:34,065 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,065 INFO > Global (OK=1454013 KO=326 ) 2024-06-23 05:48:34,065 INFO > protectLoginInitiate (OK=361622 KO=2 ) 2024-06-23 05:48:34,065 INFO > PingOneProtectInitializeCallback (OK=361616 KO=2 ) 2024-06-23 05:48:34,065 INFO > restLoginUsernamePasswordCallback (OK=361602 KO=6 ) 2024-06-23 05:48:34,065 INFO > PingOneProtectEvaluationCallback (OK=361418 KO=160 ) 2024-06-23 05:48:34,065 INFO > restloginProgressiveProfileCallback (OK=7755 KO=156 ) 2024-06-23 05:48:34,065 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 05:48:34,065 INFO > jsonPath($.tokenId).find.exists, found nothing 156 (46.71%) 2024-06-23 05:48:34,065 INFO > status.find.is(200), but actually found 401 155 (46.41%) 2024-06-23 05:48:34,065 INFO > status.find.is(200), but actually found 502 15 ( 4.49%) 2024-06-23 05:48:34,065 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,065 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,065 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,065 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,065 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,065 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,065 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 0.60%) 2024-06-23 05:48:34,065 INFO No attribute named 'callbacks' is defined 2024-06-23 05:48:34,065 INFO 2024-06-23 05:48:34,065 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,065 INFO [#########################################################################-] 99% 2024-06-23 05:48:34,065 INFO waiting: 0 / active: 47 / done: 361580 2024-06-23 05:48:34,065 INFO ================================================================================ 2024-06-23 05:48:34,065 INFO 2024-06-23 05:48:34,065 INFO 2024-06-23 05:48:34,065 INFO ================================================================================ 2024-06-23 05:48:34,065 INFO 2024-06-23 06:46:36 3630s elapsed 2024-06-23 05:48:34,065 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 05:48:34,065 INFO > Global (OK=1454087 KO=326 ) 2024-06-23 05:48:34,065 INFO > protectLoginInitiate (OK=361625 KO=2 ) 2024-06-23 05:48:34,065 INFO > PingOneProtectInitializeCallback (OK=361623 KO=2 ) 2024-06-23 05:48:34,065 INFO > restLoginUsernamePasswordCallback (OK=361619 KO=6 ) 2024-06-23 05:48:34,065 INFO > PingOneProtectEvaluationCallback (OK=361465 KO=160 ) 2024-06-23 05:48:34,065 INFO > restloginProgressiveProfileCallback (OK=7755 KO=156 ) 2024-06-23 05:48:34,066 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 05:48:34,066 INFO > jsonPath($.tokenId).find.exists, found nothing 156 (46.71%) 2024-06-23 05:48:34,066 INFO > status.find.is(200), but actually found 401 155 (46.41%) 2024-06-23 05:48:34,066 INFO > status.find.is(200), but actually found 502 15 ( 4.49%) 2024-06-23 05:48:34,066 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,066 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,066 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,066 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,066 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 0.60%) 2024-06-23 05:48:34,066 INFO attribute named 'callbacks' is defined 2024-06-23 05:48:34,066 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 0.60%) 2024-06-23 05:48:34,066 INFO No attribute named 'callbacks' is defined 2024-06-23 05:48:34,066 INFO 2024-06-23 05:48:34,066 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 05:48:34,066 INFO [##########################################################################]100% 2024-06-23 05:48:34,066 INFO waiting: 0 / active: 0 / done: 361627 2024-06-23 05:48:34,066 INFO ================================================================================ 2024-06-23 05:48:34,066 INFO 2024-06-23 05:48:34,066 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 05:48:34,066 INFO 2024-06-23 05:48:34,066 INFO ---------------------- 2024-06-23 05:48:34,066 INFO [23/Jun/2024 06:46:36] Create tar file login-tree-100.tar.gz 2024-06-23 05:48:34,066 INFO ---------------------- 2024-06-23 05:48:34,066 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 05:48:34,066 INFO RESULT_FOLDER=login-tree-100 2024-06-23 05:48:34,066 INFO SIMULATION_PATH=login-tree-100/loadtest-20240623044605369 2024-06-23 05:48:34,066 INFO PWD=/results/gatling/login-tree-100/loadtest-20240623044605369 2024-06-23 05:48:34,066 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-06-23 05:48:34,066 INFO /results/gatling 2024-06-23 05:48:34,066 INFO 2024-06-23 05:48:34,066 INFO ---------------------- 2024-06-23 05:48:34,066 INFO [23/Jun/2024 06:46:36] Update /results/gatling//latest 2024-06-23 05:48:34,066 INFO ---------------------- 2024-06-23 05:48:34,066 INFO 2024-06-23 05:48:34,066 INFO ---------------------- 2024-06-23 05:48:34,066 INFO [23/Jun/2024 06:46:36] Update /results/gatling//latest.tar.gz 2024-06-23 05:48:34,066 INFO ---------------------- 2024-06-23 05:48:34,067 INFO ________________________________________________________________________________ [2024-06-23 05:48:34] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 05:48:34,067 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-06-23 05:48:34,067 DEBUG ----- output ----- 2024-06-23 05:48:34,067 DEBUG 2024-06-23 05:48:34,067 INFO ________________________________________________________________________________ [2024-06-23 05:48:34] 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-06-23 05:48:34] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 05:48:34] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped