--Task-- name: login-tree-330 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-300'] preceding_task: login-tree-300 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 330, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-24 03:36:02] login-tree-330 pre : N/A ________________________________________________________________________________ 2024-06-24 03:36:02 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-24 03:36:02 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-330/stdout.txt Pod log : /results/orders/login-tree-330 2024-06-24 03:36:02,356 INFO 2024-06-24 03:36:02,356 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-24 03:36:02,356 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 03:36:02,679 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 03:36:02,680 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:02,680 DEBUG --- stdout --- 2024-06-24 03:36:02,680 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 03:36:02,680 DEBUG --- stderr --- 2024-06-24 03:36:02,680 DEBUG ________________________________________________________________________________ [2024-06-24 03:36:02] login-tree-330 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-24 03:36:02,681 INFO 2024-06-24 03:36:02,681 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-24 03:36:02,681 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 03:36:03,003 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 03:36:03,003 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:03,003 DEBUG --- stdout --- 2024-06-24 03:36:03,004 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 03:36:03,004 DEBUG --- stderr --- 2024-06-24 03:36:03,004 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1" --results-folder /results/gatling/login-tree-330 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-24 03:36:03] login-tree-330 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-24 03:36:03,004 INFO 2024-06-24 03:36:03,004 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 03:36:03,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 03:36:03,320 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 03:36:03,320 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:03,320 DEBUG --- stdout --- 2024-06-24 03:36:03,321 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 03:36:03,321 DEBUG --- stderr --- 2024-06-24 03:36:03,321 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/order.json { "name": "login-tree-330", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-p1\" --results-folder /results/gatling/login-tree-330 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-330", "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 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=330 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-24 03:36:03] login-tree-330 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-24 03:36:03,323 INFO 2024-06-24 03:36:03,323 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-24 03:36:03,323 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-24 03:36:03,577 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 03:36:03,577 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:03,577 DEBUG --- stdout --- 2024-06-24 03:36:03,577 DEBUG WAITING 2024-06-24 03:36:03,577 DEBUG --- stderr --- 2024-06-24 03:36:03,577 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-24 03:36:03] login-tree-330 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-24 03:36:03,578 INFO 2024-06-24 03:36:03,578 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-24 03:36:03,578 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 03:36:03,894 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 03:36:03,894 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:03,894 DEBUG --- stdout --- 2024-06-24 03:36:03,894 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 03:36:03,894 DEBUG --- stderr --- 2024-06-24 03:36:03,894 DEBUG 2024-06-24 03:36:03,895 INFO 2024-06-24 03:36:03,895 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/07_login-tree-330/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-24 03:36:03,895 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 03:36:05,244 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:05,244 DEBUG --- stdout --- 2024-06-24 03:36:05,244 DEBUG 2024-06-24 03:36:05,244 DEBUG --- stderr --- 2024-06-24 03:36:05,244 DEBUG Order has been successfully forwarded to overseer 2024-06-24 03:36:05 - INFO: Check if Gatling simulation started successfully 2024-06-24 03:36:05,245 INFO 2024-06-24 03:36:05,245 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-24 03:36:05,245 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 03:36:05,547 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 03:36:05,547 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:05,547 DEBUG --- stdout --- 2024-06-24 03:36:05,547 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 03:36:05,547 DEBUG --- stderr --- 2024-06-24 03:36:05,547 DEBUG 2024-06-24 03:36:05,548 INFO 2024-06-24 03:36:05,548 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-330/stdout.txt | grep "started..." 2024-06-24 03:36:05,548 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-24 03:36:06,276 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-24 03:36:12,019 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-06-24 03:36:17,766 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-06-24 03:36:17,766 INFO [loop_until]: OK (rc = 0) 2024-06-24 03:36:17,766 DEBUG --- stdout --- 2024-06-24 03:36:17,766 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 04:36:07.350 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 04:36:12.550 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 04:36:12.553 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 04:36:14.273 [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-24 03:36:17,766 DEBUG --- stderr --- 2024-06-24 03:36:17,766 DEBUG ________________________________________________________________________________ [2024-06-24 03:36:17] login-tree-330 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-24 04:38:28] login-tree-330 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-24 04:38:28,099 INFO 2024-06-24 04:38:28,100 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-24 04:38:28,100 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:28,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:28,428 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:28,428 DEBUG --- stdout --- 2024-06-24 04:38:28,428 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:28,428 DEBUG --- stderr --- 2024-06-24 04:38:28,428 DEBUG 2024-06-24 04:38:28,428 INFO 2024-06-24 04:38:28,428 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-24 04:38:28,428 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 04:38:29,226 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:29,226 DEBUG --- stdout --- 2024-06-24 04:38:29,228 DEBUG 2024-06-24 04:38:29,228 DEBUG --- stderr --- 2024-06-24 04:38:29,228 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-24 04:38:29] login-tree-330 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-24 04:38:29 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/order.json 2024-06-24 04:38:29,230 INFO 2024-06-24 04:38:29,230 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-24 04:38:29,230 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:29,551 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:29,551 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:29,551 DEBUG --- stdout --- 2024-06-24 04:38:29,551 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:29,551 DEBUG --- stderr --- 2024-06-24 04:38:29,551 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 04:38:29,552 INFO 2024-06-24 04:38:29,552 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 04:38:29,552 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:29,877 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:29,878 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:29,878 DEBUG --- stdout --- 2024-06-24 04:38:29,878 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:29,878 DEBUG --- stderr --- 2024-06-24 04:38:29,878 DEBUG 2024-06-24 04:38:29,879 INFO 2024-06-24 04:38:29,879 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-330/rc.txt" ]; then echo "/results/orders/login-tree-330/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-330/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/rc.txt file found" 2024-06-24 04:38:29,879 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 04:38:30,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 04:38:30,705 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:30,705 DEBUG --- stdout --- 2024-06-24 04:38:30,705 DEBUG /results/orders/login-tree-330/rc.txt file found 2024-06-24 04:38:30,705 DEBUG --- stderr --- 2024-06-24 04:38:30,705 DEBUG 2024-06-24 04:38:30,705 INFO 2024-06-24 04:38:30,705 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 04:38:30,705 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:31,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:31,027 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:31,028 DEBUG --- stdout --- 2024-06-24 04:38:31,028 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:31,028 DEBUG --- stderr --- 2024-06-24 04:38:31,028 DEBUG 2024-06-24 04:38:31,028 INFO 2024-06-24 04:38:31,028 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-330/rc.txt 2024-06-24 04:38:31,853 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 04:38:31,854 DEBUG --- stdout --- 2024-06-24 04:38:31,854 DEBUG 4 /results/orders/login-tree-330/rc.txt 2024-06-24 04:38:31,854 DEBUG --- stderr --- 2024-06-24 04:38:31,854 DEBUG 2024-06-24 04:38:31,854 INFO - Download file (size 4k) 2024-06-24 04:38:31,855 INFO 2024-06-24 04:38:31,855 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-24 04:38:31,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:32,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:32,172 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:32,172 DEBUG --- stdout --- 2024-06-24 04:38:32,172 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:32,172 DEBUG --- stderr --- 2024-06-24 04:38:32,172 DEBUG source : /results/orders/login-tree-330/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/rc.txt 2024-06-24 04:38:32,172 INFO 2024-06-24 04:38:32,172 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-24 04:38:32,173 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:32,486 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:32,486 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:32,486 DEBUG --- stdout --- 2024-06-24 04:38:32,486 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:32,486 DEBUG --- stderr --- 2024-06-24 04:38:32,486 DEBUG 2024-06-24 04:38:32,487 INFO 2024-06-24 04:38:32,487 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-330/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/rc.txt 2024-06-24 04:38:32,487 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 04:38:33,215 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:33,215 DEBUG --- stdout --- 2024-06-24 04:38:33,215 DEBUG tar: Removing leading `/' from member names 2024-06-24 04:38:33,215 DEBUG --- stderr --- 2024-06-24 04:38:33,215 DEBUG 2024-06-24 04:38:33,216 INFO 2024-06-24 04:38:33,216 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-24 04:38:33,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:33,522 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:33,522 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:33,523 DEBUG --- stdout --- 2024-06-24 04:38:33,523 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:33,523 DEBUG --- stderr --- 2024-06-24 04:38:33,523 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 04:38:33,523 INFO 2024-06-24 04:38:33,523 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-24 04:38:33,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:33,834 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:33,834 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:33,834 DEBUG --- stdout --- 2024-06-24 04:38:33,834 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:33,834 DEBUG --- stderr --- 2024-06-24 04:38:33,834 DEBUG 2024-06-24 04:38:33,835 INFO 2024-06-24 04:38:33,835 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-330/stderr.txt" ]; then echo "/results/orders/login-tree-330/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-330/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/stderr.txt file found" 2024-06-24 04:38:33,835 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 04:38:34,659 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 04:38:34,659 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:34,659 DEBUG --- stdout --- 2024-06-24 04:38:34,659 DEBUG /results/orders/login-tree-330/stderr.txt file found 2024-06-24 04:38:34,659 DEBUG --- stderr --- 2024-06-24 04:38:34,659 DEBUG 2024-06-24 04:38:34,660 INFO 2024-06-24 04:38:34,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-24 04:38:34,660 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:34,993 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:34,994 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:34,994 DEBUG --- stdout --- 2024-06-24 04:38:34,994 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:34,994 DEBUG --- stderr --- 2024-06-24 04:38:34,994 DEBUG 2024-06-24 04:38:34,994 INFO 2024-06-24 04:38:34,995 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-330/stderr.txt 2024-06-24 04:38:35,835 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 04:38:35,835 DEBUG --- stdout --- 2024-06-24 04:38:35,835 DEBUG 0 /results/orders/login-tree-330/stderr.txt 2024-06-24 04:38:35,835 DEBUG --- stderr --- 2024-06-24 04:38:35,835 DEBUG 2024-06-24 04:38:35,835 INFO - Download file (size 0k) 2024-06-24 04:38:35,836 INFO 2024-06-24 04:38:35,836 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-24 04:38:35,836 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:36,160 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:36,160 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:36,160 DEBUG --- stdout --- 2024-06-24 04:38:36,160 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:36,160 DEBUG --- stderr --- 2024-06-24 04:38:36,160 DEBUG source : /results/orders/login-tree-330/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stderr.txt 2024-06-24 04:38:36,161 INFO 2024-06-24 04:38:36,161 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-24 04:38:36,161 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:36,469 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:36,470 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:36,470 DEBUG --- stdout --- 2024-06-24 04:38:36,470 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:36,470 DEBUG --- stderr --- 2024-06-24 04:38:36,470 DEBUG 2024-06-24 04:38:36,471 INFO 2024-06-24 04:38:36,471 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-330/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stderr.txt 2024-06-24 04:38:36,471 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 04:38:37,197 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:37,198 DEBUG --- stdout --- 2024-06-24 04:38:37,198 DEBUG tar: Removing leading `/' from member names 2024-06-24 04:38:37,198 DEBUG --- stderr --- 2024-06-24 04:38:37,198 DEBUG 2024-06-24 04:38:37,198 INFO 2024-06-24 04:38:37,198 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-24 04:38:37,198 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:37,532 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:37,533 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:37,533 DEBUG --- stdout --- 2024-06-24 04:38:37,533 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:37,533 DEBUG --- stderr --- 2024-06-24 04:38:37,533 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 04:38:37,533 INFO 2024-06-24 04:38:37,533 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-24 04:38:37,533 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:37,842 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:37,843 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:37,843 DEBUG --- stdout --- 2024-06-24 04:38:37,843 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:37,843 DEBUG --- stderr --- 2024-06-24 04:38:37,843 DEBUG 2024-06-24 04:38:37,844 INFO 2024-06-24 04:38:37,844 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-330/stdout.txt" ]; then echo "/results/orders/login-tree-330/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-330/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/stdout.txt file found" 2024-06-24 04:38:37,844 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 04:38:38,668 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 04:38:38,669 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:38,669 DEBUG --- stdout --- 2024-06-24 04:38:38,669 DEBUG /results/orders/login-tree-330/stdout.txt file found 2024-06-24 04:38:38,669 DEBUG --- stderr --- 2024-06-24 04:38:38,669 DEBUG 2024-06-24 04:38:38,669 INFO 2024-06-24 04:38:38,669 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-24 04:38:38,669 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:38,988 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:38,988 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:38,988 DEBUG --- stdout --- 2024-06-24 04:38:38,988 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:38,988 DEBUG --- stderr --- 2024-06-24 04:38:38,988 DEBUG 2024-06-24 04:38:38,988 INFO 2024-06-24 04:38:38,988 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-330/stdout.txt 2024-06-24 04:38:39,812 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 04:38:39,812 DEBUG --- stdout --- 2024-06-24 04:38:39,813 DEBUG 804 /results/orders/login-tree-330/stdout.txt 2024-06-24 04:38:39,813 DEBUG --- stderr --- 2024-06-24 04:38:39,813 DEBUG 2024-06-24 04:38:39,813 INFO - Download file (size 804k) 2024-06-24 04:38:39,813 INFO 2024-06-24 04:38:39,813 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-24 04:38:39,813 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:40,140 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:40,141 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:40,141 DEBUG --- stdout --- 2024-06-24 04:38:40,141 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:40,141 DEBUG --- stderr --- 2024-06-24 04:38:40,141 DEBUG source : /results/orders/login-tree-330/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stdout.txt 2024-06-24 04:38:40,141 INFO 2024-06-24 04:38:40,141 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-24 04:38:40,141 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:40,457 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:40,457 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:40,457 DEBUG --- stdout --- 2024-06-24 04:38:40,457 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:40,457 DEBUG --- stderr --- 2024-06-24 04:38:40,457 DEBUG 2024-06-24 04:38:40,458 INFO 2024-06-24 04:38:40,458 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-330/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stdout.txt 2024-06-24 04:38:40,458 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 04:38:41,542 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:41,542 DEBUG --- stdout --- 2024-06-24 04:38:41,542 DEBUG tar: Removing leading `/' from member names 2024-06-24 04:38:41,542 DEBUG --- stderr --- 2024-06-24 04:38:41,542 DEBUG ________________________________________________________________________________ [2024-06-24 04:38:41] login-tree-330 show_cmd : Display order command ________________________________________________________________________________ 2024-06-24 04:38:41,543 INFO 2024-06-24 04:38:41,543 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-24 04:38:41,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:41,862 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:41,862 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:41,862 DEBUG --- stdout --- 2024-06-24 04:38:41,862 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:41,862 DEBUG --- stderr --- 2024-06-24 04:38:41,862 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 04:38:41,862 INFO 2024-06-24 04:38:41,863 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-24 04:38:41,863 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:42,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:42,165 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:42,165 DEBUG --- stdout --- 2024-06-24 04:38:42,165 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:42,165 DEBUG --- stderr --- 2024-06-24 04:38:42,166 DEBUG 2024-06-24 04:38:42,166 INFO 2024-06-24 04:38:42,166 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-330/cmd.txt" ]; then echo "/results/orders/login-tree-330/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-330/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/cmd.txt file found" 2024-06-24 04:38:42,166 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 04:38:42,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 04:38:42,966 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:42,966 DEBUG --- stdout --- 2024-06-24 04:38:42,966 DEBUG /results/orders/login-tree-330/cmd.txt file found 2024-06-24 04:38:42,966 DEBUG --- stderr --- 2024-06-24 04:38:42,966 DEBUG 2024-06-24 04:38:42,967 INFO 2024-06-24 04:38:42,967 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-24 04:38:42,967 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:43,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:43,277 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:43,277 DEBUG --- stdout --- 2024-06-24 04:38:43,277 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:43,277 DEBUG --- stderr --- 2024-06-24 04:38:43,277 DEBUG 2024-06-24 04:38:43,278 INFO 2024-06-24 04:38:43,278 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-330/cmd.txt 2024-06-24 04:38:44,099 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 04:38:44,100 DEBUG --- stdout --- 2024-06-24 04:38:44,100 DEBUG 4 /results/orders/login-tree-330/cmd.txt 2024-06-24 04:38:44,100 DEBUG --- stderr --- 2024-06-24 04:38:44,100 DEBUG 2024-06-24 04:38:44,100 INFO - Download file (size 4k) 2024-06-24 04:38:44,100 INFO 2024-06-24 04:38:44,100 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-24 04:38:44,100 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:44,422 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:44,422 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:44,422 DEBUG --- stdout --- 2024-06-24 04:38:44,422 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:44,422 DEBUG --- stderr --- 2024-06-24 04:38:44,422 DEBUG source : /results/orders/login-tree-330/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/cmd.txt 2024-06-24 04:38:44,423 INFO 2024-06-24 04:38:44,423 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-24 04:38:44,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 04:38:44,740 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 04:38:44,740 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:44,740 DEBUG --- stdout --- 2024-06-24 04:38:44,740 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 04:38:44,740 DEBUG --- stderr --- 2024-06-24 04:38:44,740 DEBUG 2024-06-24 04:38:44,741 INFO 2024-06-24 04:38:44,741 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-330/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/cmd.txt 2024-06-24 04:38:44,741 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 04:38:45,485 INFO [loop_until]: OK (rc = 0) 2024-06-24 04:38:45,486 DEBUG --- stdout --- 2024-06-24 04:38:45,486 DEBUG tar: Removing leading `/' from member names 2024-06-24 04:38:45,486 DEBUG --- stderr --- 2024-06-24 04:38:45,486 DEBUG 2024-06-24 04:38:45,486 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/cmd.txt is small (1 lines), printing all lines: 2024-06-24 04:38:45,486 DEBUG ----- output ----- 2024-06-24 04:38:45,487 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1" --results-folder /results/gatling/login-tree-330 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-24 04:38:45,487 INFO ________________________________________________________________________________ [2024-06-24 04:38:45] login-tree-330 show_rc : Display rc order ________________________________________________________________________________ 2024-06-24 04:38:45,487 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/rc.txt is small (1 lines), printing all lines: 2024-06-24 04:38:45,487 DEBUG ----- output ----- 2024-06-24 04:38:45,487 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-24 04:38:45,487 INFO ________________________________________________________________________________ [2024-06-24 04:38:45] login-tree-330 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-24 04:38:45,489 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/07_login-tree-330/stdout.txt is 12146 lines): 2024-06-24 04:38:45,489 DEBUG ----- output ----- 2024-06-24 04:38:45,489 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-24 04:38:45,489 INFO 04:36:07.350 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-06-24 04:38:45,490 INFO 04:36:12.550 [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-24 04:38:45,490 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-24 04:38:45,490 INFO ^ 2024-06-24 04:38:45,490 INFO 04:36:12.553 [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-24 04:38:45,490 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-06-24 04:38:45,490 INFO ^ 2024-06-24 04:38:45,490 INFO 04:36:14.273 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-06-24 04:38:45,490 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-24 04:38:45,490 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-24 04:38:45,490 INFO 2024-06-24 04:38:45,490 INFO ================================================================================ 2024-06-24 04:38:45,490 INFO 2024-06-24 04:36:22 5s elapsed 2024-06-24 04:38:45,490 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,490 INFO > Global (OK=280 KO=0 ) 2024-06-24 04:38:45,490 INFO > restLoginInitiate (OK=135 KO=0 ) 2024-06-24 04:38:45,490 INFO > restLoginUsernamePasswordCallback (OK=129 KO=0 ) 2024-06-24 04:38:45,490 INFO > restloginProgressiveProfileCallback (OK=16 KO=0 ) 2024-06-24 04:38:45,490 INFO 2024-06-24 04:38:45,490 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,490 INFO [- ] 0% 2024-06-24 04:38:45,490 INFO waiting: 1193228 / active: 11 / done: 127 2024-06-24 04:38:45,490 INFO ================================================================================ 2024-06-24 04:38:45,490 INFO 2024-06-24 04:38:45,490 INFO 2024-06-24 04:38:45,490 INFO ================================================================================ 2024-06-24 04:38:45,490 INFO 2024-06-24 04:36:27 10s elapsed 2024-06-24 04:38:45,490 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,490 INFO > Global (OK=1161 KO=0 ) 2024-06-24 04:38:45,490 INFO > restLoginInitiate (OK=546 KO=0 ) 2024-06-24 04:38:45,490 INFO > restLoginUsernamePasswordCallback (OK=534 KO=0 ) 2024-06-24 04:38:45,490 INFO > restloginProgressiveProfileCallback (OK=81 KO=0 ) 2024-06-24 04:38:45,490 INFO 2024-06-24 04:38:45,490 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,490 INFO [- ] 0% 2024-06-24 04:38:45,490 INFO waiting: 1192814 / active: 23 / done: 529 2024-06-24 04:38:45,490 INFO ================================================================================ 2024-06-24 04:38:45,490 INFO 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO ================================================================================ 2024-06-24 04:38:45,491 INFO 2024-06-24 04:36:32 15s elapsed 2024-06-24 04:38:45,491 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,491 INFO > Global (OK=2623 KO=0 ) 2024-06-24 04:38:45,491 INFO > restLoginInitiate (OK=1231 KO=0 ) 2024-06-24 04:38:45,491 INFO > restLoginUsernamePasswordCallback (OK=1212 KO=0 ) 2024-06-24 04:38:45,491 INFO > restloginProgressiveProfileCallback (OK=180 KO=0 ) 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,491 INFO [- ] 0% 2024-06-24 04:38:45,491 INFO waiting: 1192126 / active: 34 / done: 1206 2024-06-24 04:38:45,491 INFO ================================================================================ 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO ================================================================================ 2024-06-24 04:38:45,491 INFO 2024-06-24 04:36:37 20s elapsed 2024-06-24 04:38:45,491 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,491 INFO > Global (OK=4679 KO=0 ) 2024-06-24 04:38:45,491 INFO > restLoginInitiate (OK=2191 KO=0 ) 2024-06-24 04:38:45,491 INFO > restLoginUsernamePasswordCallback (OK=2164 KO=0 ) 2024-06-24 04:38:45,491 INFO > restloginProgressiveProfileCallback (OK=324 KO=0 ) 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,491 INFO [- ] 0% 2024-06-24 04:38:45,491 INFO waiting: 1191163 / active: 44 / done: 2159 2024-06-24 04:38:45,491 INFO ================================================================================ 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO ================================================================================ 2024-06-24 04:38:45,491 INFO 2024-06-24 04:36:42 25s elapsed 2024-06-24 04:38:45,491 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,491 INFO > Global (OK=7362 KO=0 ) 2024-06-24 04:38:45,491 INFO > restLoginInitiate (OK=3427 KO=0 ) 2024-06-24 04:38:45,491 INFO > restLoginUsernamePasswordCallback (OK=3392 KO=0 ) 2024-06-24 04:38:45,491 INFO > restloginProgressiveProfileCallback (OK=543 KO=0 ) 2024-06-24 04:38:45,491 INFO 2024-06-24 04:38:45,491 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,491 INFO [- ] 0% 2024-06-24 04:38:45,492 INFO waiting: 1189925 / active: 56 / done: 3385 2024-06-24 04:38:45,492 INFO ================================================================================ 2024-06-24 04:38:45,492 INFO 2024-06-24 04:38:45,492 INFO 2024-06-24 04:38:45,492 INFO ================================================================================ 2024-06-24 04:38:45,492 INFO 2024-06-24 04:36:47 30s elapsed 2024-06-24 04:38:45,492 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,492 INFO > Global (OK=10582 KO=0 ) 2024-06-24 04:38:45,492 INFO > restLoginInitiate (OK=4931 KO=0 ) 2024-06-24 04:38:45,492 INFO > restLoginUsernamePasswordCallback (OK=4869 KO=0 ) 2024-06-24 04:38:45,492 INFO > restloginProgressiveProfileCallback (OK=782 KO=0 ) 2024-06-24 04:38:45,492 INFO 2024-06-24 04:38:45,492 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,492 INFO [- ] 0% 2024-06-24 04:38:45,492 INFO waiting: 1188412 / active: 96 / done: 4858 2024-06-24 04:38:45,492 INFO ================================================================================ 2024-06-24 04:38:45,492 INFO 2024-06-24 04:38:45,492 INFO 2024-06-24 04:38:45,492 INFO ================================================================================ 2024-06-24 04:38:45,492 INFO 2024-06-24 04:36:52 35s elapsed 2024-06-24 04:38:45,492 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,492 INFO > Global (OK=14193 KO=0 ) 2024-06-24 04:38:45,492 INFO [...] 2024-06-24 04:38:45,495 INFO headers: 2024-06-24 04:38:45,495 INFO content-type: application/json 2024-06-24 04:38:45,495 INFO Accept-API-Version: resource=2.0, protocol=1.0 2024-06-24 04:38:45,495 INFO accept: application/json 2024-06-24 04:38:45,495 INFO X-Forwarded-For: 201.137.230.177 2024-06-24 04:38:45,495 INFO User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_2) AppleWebKit/601.3.9 (KHTML, like Gecko) Version/9.0.2 Safari/601.3.9 2024-06-24 04:38:45,495 INFO host: openam-ema-perf-p1.forgeblocks.com 2024-06-24 04:38:45,495 INFO content-length: 0 2024-06-24 04:38:45,495 INFO body:StringChunksRequestBody{charset=UTF-8, content=} 2024-06-24 04:38:45,495 INFO ========================= 2024-06-24 04:38:45,496 INFO HTTP response: 2024-06-24 04:38:45,496 INFO status: 2024-06-24 04:38:45,496 INFO 502 Bad Gateway 2024-06-24 04:38:45,496 INFO headers: 2024-06-24 04:38:45,496 INFO Content-Type: text/html; charset=UTF-8 2024-06-24 04:38:45,496 INFO Referrer-Policy: no-referrer 2024-06-24 04:38:45,496 INFO Content-Length: 332 2024-06-24 04:38:45,496 INFO Date: Mon, 24 Jun 2024 04:36:40 GMT 2024-06-24 04:38:45,496 INFO Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000 2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFO body: 2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFO
2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFOPlease try again in 30 seconds. 2024-06-24 04:38:45,496 INFO
2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFO <<<<<<<<<<<<<<<<<<<<<<<<< 2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFO ================================================================================ 2024-06-24 04:38:45,496 INFO 2024-06-24 05:36:42 3625s elapsed 2024-06-24 04:38:45,496 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,496 INFO > Global (OK=2538588 KO=12 ) 2024-06-24 04:38:45,496 INFO > restLoginInitiate (OK=1191717 KO=6 ) 2024-06-24 04:38:45,496 INFO > restLoginUsernamePasswordCallback (OK=1191653 KO=4 ) 2024-06-24 04:38:45,496 INFO > restloginProgressiveProfileCallback (OK=155218 KO=2 ) 2024-06-24 04:38:45,496 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 04:38:45,496 INFO > status.find.is(200), but actually found 502 12 (100.0%) 2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,496 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,496 INFO [#########################################################################-] 99% 2024-06-24 04:38:45,496 INFO waiting: 1628 / active: 85 / done: 1191653 2024-06-24 04:38:45,496 INFO ================================================================================ 2024-06-24 04:38:45,496 INFO 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO ================================================================================ 2024-06-24 04:38:45,497 INFO 2024-06-24 05:36:47 3630s elapsed 2024-06-24 04:38:45,497 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,497 INFO > Global (OK=2541990 KO=12 ) 2024-06-24 04:38:45,497 INFO > restLoginInitiate (OK=1193343 KO=6 ) 2024-06-24 04:38:45,497 INFO > restLoginUsernamePasswordCallback (OK=1193277 KO=4 ) 2024-06-24 04:38:45,497 INFO > restloginProgressiveProfileCallback (OK=155370 KO=2 ) 2024-06-24 04:38:45,497 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 04:38:45,497 INFO > status.find.is(200), but actually found 502 12 (100.0%) 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,497 INFO [#########################################################################-] 99% 2024-06-24 04:38:45,497 INFO waiting: 0 / active: 90 / done: 1193276 2024-06-24 04:38:45,497 INFO ================================================================================ 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO ================================================================================ 2024-06-24 04:38:45,497 INFO 2024-06-24 05:36:47 3630s elapsed 2024-06-24 04:38:45,497 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 04:38:45,497 INFO > Global (OK=2542103 KO=12 ) 2024-06-24 04:38:45,497 INFO > restLoginInitiate (OK=1193360 KO=6 ) 2024-06-24 04:38:45,497 INFO > restLoginUsernamePasswordCallback (OK=1193356 KO=4 ) 2024-06-24 04:38:45,497 INFO > restloginProgressiveProfileCallback (OK=155387 KO=2 ) 2024-06-24 04:38:45,497 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 04:38:45,497 INFO > status.find.is(200), but actually found 502 12 (100.0%) 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 04:38:45,497 INFO [##########################################################################]100% 2024-06-24 04:38:45,497 INFO waiting: 0 / active: 0 / done: 1193366 2024-06-24 04:38:45,497 INFO ================================================================================ 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-24 04:38:45,497 INFO 2024-06-24 04:38:45,497 INFO ---------------------- 2024-06-24 04:38:45,497 INFO [24/Jun/2024 05:36:47] Create tar file login-tree-330.tar.gz 2024-06-24 04:38:45,497 INFO ---------------------- 2024-06-24 04:38:45,497 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-24 04:38:45,497 INFO RESULT_FOLDER=login-tree-330 2024-06-24 04:38:45,498 INFO SIMULATION_PATH=login-tree-330/loadtest-20240624033616516 2024-06-24 04:38:45,498 INFO PWD=/results/gatling/login-tree-330/loadtest-20240624033616516 2024-06-24 04:38:45,498 INFO [cmd] tar -czvf /results/gatling//login-tree-330.tar.gz --exclude=simulation.log * 2024-06-24 04:38:45,498 INFO /results/gatling 2024-06-24 04:38:45,498 INFO 2024-06-24 04:38:45,498 INFO ---------------------- 2024-06-24 04:38:45,498 INFO [24/Jun/2024 05:36:47] Update /results/gatling//latest 2024-06-24 04:38:45,498 INFO ---------------------- 2024-06-24 04:38:45,498 INFO 2024-06-24 04:38:45,498 INFO ---------------------- 2024-06-24 04:38:45,498 INFO [24/Jun/2024 05:36:47] Update /results/gatling//latest.tar.gz 2024-06-24 04:38:45,498 INFO ---------------------- 2024-06-24 04:38:45,498 INFO ________________________________________________________________________________ [2024-06-24 04:38:45] login-tree-330 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-24 04:38:45,498 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stderr.txt is small (0 lines), printing all lines: 2024-06-24 04:38:45,498 DEBUG ----- output ----- 2024-06-24 04:38:45,498 DEBUG 2024-06-24 04:38:45,498 INFO ________________________________________________________________________________ [2024-06-24 04:38:45] login-tree-330 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-24 04:38:45] login-tree-330 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-24 04:38:45] login-tree-330 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped