--Task-- name: login-tree-10 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: [] preceding_task: None options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 10, '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-11 20:30:34] login-tree-10 pre : N/A ________________________________________________________________________________ 2024-06-11 20:30:34 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-11 20:30:34 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-10/stdout.txt Pod log : /results/orders/login-tree-10 2024-06-11 20:30:34,932 INFO 2024-06-11 20:30:34,932 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-11 20:30:34,932 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 20:30:35,266 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 20:30:35,266 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:35,266 DEBUG --- stdout --- 2024-06-11 20:30:35,266 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 20:30:35,266 DEBUG --- stderr --- 2024-06-11 20:30:35,266 DEBUG 2024-06-11 20:30:35 - INFO: Get overseer pod resources requests memory 2024-06-11 20:30:35,267 INFO 2024-06-11 20:30:35,267 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-6c7d65494f-sln6z --output jsonpath={.spec.containers[].resources.requests.memory} 2024-06-11 20:30:35,267 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 20:30:35,605 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:35,605 DEBUG --- stdout --- 2024-06-11 20:30:35,605 DEBUG 4Gi 2024-06-11 20:30:35,605 DEBUG --- stderr --- 2024-06-11 20:30:35,605 DEBUG ________________________________________________________________________________ [2024-06-11 20:30:35] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-11 20:30:35,606 INFO 2024-06-11 20:30:35,606 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-11 20:30:35,606 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 20:30:35,915 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 20:30:35,915 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:35,916 DEBUG --- stdout --- 2024-06-11 20:30:35,916 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 20:30:35,916 DEBUG --- stderr --- 2024-06-11 20:30:35,916 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-p1protect" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-11 20:30:35] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-11 20:30:35,916 INFO 2024-06-11 20:30:35,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-11 20:30:35,917 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 20:30:36,214 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 20:30:36,215 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:36,215 DEBUG --- stdout --- 2024-06-11 20:30:36,215 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 20:30:36,215 DEBUG --- stderr --- 2024-06-11 20:30:36,215 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json { "name": "login-tree-10", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-10", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -Dlogin_tree=Login -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=10 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-11 20:30:36] login-tree-10 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-11 20:30:36,216 INFO 2024-06-11 20:30:36,216 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-11 20:30:36,217 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 20:30:36,505 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 20:30:36,505 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:36,505 DEBUG --- stdout --- 2024-06-11 20:30:36,505 DEBUG WAITING 2024-06-11 20:30:36,505 DEBUG --- stderr --- 2024-06-11 20:30:36,506 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 26 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-06-11 20:30:36] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-11 20:30:36,506 INFO 2024-06-11 20:30:36,506 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-11 20:30:36,506 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 20:30:36,800 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 20:30:36,800 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:36,800 DEBUG --- stdout --- 2024-06-11 20:30:36,801 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 20:30:36,801 DEBUG --- stderr --- 2024-06-11 20:30:36,801 DEBUG 2024-06-11 20:30:36,801 INFO 2024-06-11 20:30:36,801 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-11 20:30:36,801 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 20:30:38,328 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:38,329 DEBUG --- stdout --- 2024-06-11 20:30:38,329 DEBUG 2024-06-11 20:30:38,329 DEBUG --- stderr --- 2024-06-11 20:30:38,329 DEBUG Order has been successfully forwarded to overseer 2024-06-11 20:30:38 - INFO: Check if Gatling simulation started successfully 2024-06-11 20:30:38,329 INFO 2024-06-11 20:30:38,329 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-11 20:30:38,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 20:30:38,631 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 20:30:38,631 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:38,631 DEBUG --- stdout --- 2024-06-11 20:30:38,631 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 20:30:38,631 DEBUG --- stderr --- 2024-06-11 20:30:38,631 DEBUG 2024-06-11 20:30:38,632 INFO 2024-06-11 20:30:38,632 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-10/stdout.txt | grep "started..." 2024-06-11 20:30:38,632 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 20:30:39,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-11 20:30:45,130 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-11 20:30:45,131 INFO [loop_until]: OK (rc = 0) 2024-06-11 20:30:45,131 DEBUG --- stdout --- 2024-06-11 20:30:45,131 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-11 20:30:45,131 DEBUG --- stderr --- 2024-06-11 20:30:45,131 DEBUG ________________________________________________________________________________ [2024-06-11 20:30:45] login-tree-10 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, 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 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 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-11 21:32:55] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-11 21:32:55,447 INFO 2024-06-11 21:32:55,448 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-11 21:32:55,448 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:32:55,786 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:32:55,786 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:55,786 DEBUG --- stdout --- 2024-06-11 21:32:55,786 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:32:55,786 DEBUG --- stderr --- 2024-06-11 21:32:55,786 DEBUG 2024-06-11 21:32:55,787 INFO 2024-06-11 21:32:55,787 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-11 21:32:55,787 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 21:32:56,592 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:56,592 DEBUG --- stdout --- 2024-06-11 21:32:56,592 DEBUG 2024-06-11 21:32:56,592 DEBUG --- stderr --- 2024-06-11 21:32:56,592 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-11 21:32:56] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-11 21:32:56 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json 2024-06-11 21:32:56,593 INFO 2024-06-11 21:32:56,593 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-11 21:32:56,593 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:32:56,905 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:32:56,905 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:56,905 DEBUG --- stdout --- 2024-06-11 21:32:56,905 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:32:56,905 DEBUG --- stderr --- 2024-06-11 21:32:56,905 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 21:32:56,906 INFO 2024-06-11 21:32:56,906 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-11 21:32:56,906 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:32:57,224 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:32:57,225 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:57,225 DEBUG --- stdout --- 2024-06-11 21:32:57,225 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:32:57,225 DEBUG --- stderr --- 2024-06-11 21:32:57,225 DEBUG 2024-06-11 21:32:57,225 INFO 2024-06-11 21:32:57,225 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/rc.txt" ]; then echo "/results/orders/login-tree-10/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/rc.txt file found" 2024-06-11 21:32:57,226 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 21:32:58,037 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 21:32:58,038 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:58,038 DEBUG --- stdout --- 2024-06-11 21:32:58,038 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-06-11 21:32:58,038 DEBUG --- stderr --- 2024-06-11 21:32:58,038 DEBUG 2024-06-11 21:32:58,038 INFO 2024-06-11 21:32:58,038 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-11 21:32:58,038 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:32:58,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:32:58,339 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:58,340 DEBUG --- stdout --- 2024-06-11 21:32:58,340 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:32:58,340 DEBUG --- stderr --- 2024-06-11 21:32:58,340 DEBUG 2024-06-11 21:32:58,340 INFO 2024-06-11 21:32:58,340 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/rc.txt 2024-06-11 21:32:59,150 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 21:32:59,150 DEBUG --- stdout --- 2024-06-11 21:32:59,150 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-06-11 21:32:59,150 DEBUG --- stderr --- 2024-06-11 21:32:59,150 DEBUG 2024-06-11 21:32:59,150 INFO - Download file (size 4k) 2024-06-11 21:32:59,151 INFO 2024-06-11 21:32:59,151 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-11 21:32:59,151 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:32:59,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:32:59,505 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:59,505 DEBUG --- stdout --- 2024-06-11 21:32:59,505 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:32:59,505 DEBUG --- stderr --- 2024-06-11 21:32:59,505 DEBUG source : /results/orders/login-tree-10/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-11 21:32:59,505 INFO 2024-06-11 21:32:59,505 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-11 21:32:59,505 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:32:59,822 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:32:59,822 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:32:59,822 DEBUG --- stdout --- 2024-06-11 21:32:59,822 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:32:59,822 DEBUG --- stderr --- 2024-06-11 21:32:59,822 DEBUG 2024-06-11 21:32:59,823 INFO 2024-06-11 21:32:59,823 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-06-11 21:32:59,823 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 21:33:00,552 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:00,553 DEBUG --- stdout --- 2024-06-11 21:33:00,553 DEBUG tar: Removing leading `/' from member names 2024-06-11 21:33:00,553 DEBUG --- stderr --- 2024-06-11 21:33:00,553 DEBUG 2024-06-11 21:33:00,553 INFO 2024-06-11 21:33:00,553 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-11 21:33:00,553 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:00,866 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:00,866 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:00,866 DEBUG --- stdout --- 2024-06-11 21:33:00,866 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:00,866 DEBUG --- stderr --- 2024-06-11 21:33:00,867 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 21:33:00,867 INFO 2024-06-11 21:33:00,867 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-11 21:33:00,867 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:01,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:01,168 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:01,169 DEBUG --- stdout --- 2024-06-11 21:33:01,169 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:01,169 DEBUG --- stderr --- 2024-06-11 21:33:01,169 DEBUG 2024-06-11 21:33:01,169 INFO 2024-06-11 21:33:01,169 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stderr.txt" ]; then echo "/results/orders/login-tree-10/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stderr.txt file found" 2024-06-11 21:33:01,169 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 21:33:01,962 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 21:33:01,963 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:01,963 DEBUG --- stdout --- 2024-06-11 21:33:01,963 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-06-11 21:33:01,963 DEBUG --- stderr --- 2024-06-11 21:33:01,963 DEBUG 2024-06-11 21:33:01,963 INFO 2024-06-11 21:33:01,963 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-11 21:33:01,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:02,263 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:02,263 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:02,263 DEBUG --- stdout --- 2024-06-11 21:33:02,263 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:02,263 DEBUG --- stderr --- 2024-06-11 21:33:02,263 DEBUG 2024-06-11 21:33:02,263 INFO 2024-06-11 21:33:02,264 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/stderr.txt 2024-06-11 21:33:03,068 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 21:33:03,068 DEBUG --- stdout --- 2024-06-11 21:33:03,068 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-06-11 21:33:03,068 DEBUG --- stderr --- 2024-06-11 21:33:03,068 DEBUG 2024-06-11 21:33:03,068 INFO - Download file (size 0k) 2024-06-11 21:33:03,069 INFO 2024-06-11 21:33:03,069 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-11 21:33:03,069 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:03,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:03,390 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:03,390 DEBUG --- stdout --- 2024-06-11 21:33:03,391 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:03,391 DEBUG --- stderr --- 2024-06-11 21:33:03,391 DEBUG source : /results/orders/login-tree-10/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-11 21:33:03,391 INFO 2024-06-11 21:33:03,391 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-11 21:33:03,391 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:03,706 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:03,706 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:03,706 DEBUG --- stdout --- 2024-06-11 21:33:03,706 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:03,706 DEBUG --- stderr --- 2024-06-11 21:33:03,706 DEBUG 2024-06-11 21:33:03,707 INFO 2024-06-11 21:33:03,707 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-06-11 21:33:03,707 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 21:33:04,488 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:04,489 DEBUG --- stdout --- 2024-06-11 21:33:04,489 DEBUG tar: Removing leading `/' from member names 2024-06-11 21:33:04,489 DEBUG --- stderr --- 2024-06-11 21:33:04,489 DEBUG 2024-06-11 21:33:04,489 INFO 2024-06-11 21:33:04,489 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-11 21:33:04,489 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:04,789 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:04,789 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:04,789 DEBUG --- stdout --- 2024-06-11 21:33:04,789 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:04,789 DEBUG --- stderr --- 2024-06-11 21:33:04,789 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 21:33:04,790 INFO 2024-06-11 21:33:04,790 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-11 21:33:04,790 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:05,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:05,098 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:05,098 DEBUG --- stdout --- 2024-06-11 21:33:05,099 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:05,099 DEBUG --- stderr --- 2024-06-11 21:33:05,099 DEBUG 2024-06-11 21:33:05,099 INFO 2024-06-11 21:33:05,100 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/stdout.txt" ]; then echo "/results/orders/login-tree-10/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stdout.txt file found" 2024-06-11 21:33:05,100 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 21:33:05,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 21:33:05,928 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:05,929 DEBUG --- stdout --- 2024-06-11 21:33:05,929 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-06-11 21:33:05,929 DEBUG --- stderr --- 2024-06-11 21:33:05,929 DEBUG 2024-06-11 21:33:05,930 INFO 2024-06-11 21:33:05,930 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-11 21:33:05,931 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:06,268 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:06,269 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:06,269 DEBUG --- stdout --- 2024-06-11 21:33:06,269 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:06,269 DEBUG --- stderr --- 2024-06-11 21:33:06,269 DEBUG 2024-06-11 21:33:06,269 INFO 2024-06-11 21:33:06,269 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/stdout.txt 2024-06-11 21:33:07,079 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 21:33:07,079 DEBUG --- stdout --- 2024-06-11 21:33:07,080 DEBUG 712 /results/orders/login-tree-10/stdout.txt 2024-06-11 21:33:07,080 DEBUG --- stderr --- 2024-06-11 21:33:07,080 DEBUG 2024-06-11 21:33:07,080 INFO - Download file (size 712k) 2024-06-11 21:33:07,080 INFO 2024-06-11 21:33:07,080 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-11 21:33:07,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:07,418 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:07,418 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:07,418 DEBUG --- stdout --- 2024-06-11 21:33:07,418 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:07,418 DEBUG --- stderr --- 2024-06-11 21:33:07,418 DEBUG source : /results/orders/login-tree-10/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-11 21:33:07,419 INFO 2024-06-11 21:33:07,419 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-11 21:33:07,419 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:07,723 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:07,723 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:07,723 DEBUG --- stdout --- 2024-06-11 21:33:07,723 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:07,724 DEBUG --- stderr --- 2024-06-11 21:33:07,724 DEBUG 2024-06-11 21:33:07,724 INFO 2024-06-11 21:33:07,724 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-06-11 21:33:07,724 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 21:33:08,781 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:08,782 DEBUG --- stdout --- 2024-06-11 21:33:08,782 DEBUG tar: Removing leading `/' from member names 2024-06-11 21:33:08,782 DEBUG --- stderr --- 2024-06-11 21:33:08,782 DEBUG ________________________________________________________________________________ [2024-06-11 21:33:08] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-06-11 21:33:08,783 INFO 2024-06-11 21:33:08,783 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 21:33:08,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:09,106 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:09,106 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:09,106 DEBUG --- stdout --- 2024-06-11 21:33:09,106 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:09,106 DEBUG --- stderr --- 2024-06-11 21:33:09,106 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 21:33:09,107 INFO 2024-06-11 21:33:09,107 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-11 21:33:09,107 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:09,406 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:09,407 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:09,407 DEBUG --- stdout --- 2024-06-11 21:33:09,407 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:09,407 DEBUG --- stderr --- 2024-06-11 21:33:09,407 DEBUG 2024-06-11 21:33:09,407 INFO 2024-06-11 21:33:09,408 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-10/cmd.txt" ]; then echo "/results/orders/login-tree-10/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/cmd.txt file found" 2024-06-11 21:33:09,408 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 21:33:10,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 21:33:10,211 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:10,211 DEBUG --- stdout --- 2024-06-11 21:33:10,211 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-06-11 21:33:10,211 DEBUG --- stderr --- 2024-06-11 21:33:10,211 DEBUG 2024-06-11 21:33:10,211 INFO 2024-06-11 21:33:10,211 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-11 21:33:10,211 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:10,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:10,510 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:10,510 DEBUG --- stdout --- 2024-06-11 21:33:10,510 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:10,510 DEBUG --- stderr --- 2024-06-11 21:33:10,510 DEBUG 2024-06-11 21:33:10,510 INFO 2024-06-11 21:33:10,510 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-10/cmd.txt 2024-06-11 21:33:11,309 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 21:33:11,310 DEBUG --- stdout --- 2024-06-11 21:33:11,311 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-06-11 21:33:11,311 DEBUG --- stderr --- 2024-06-11 21:33:11,311 DEBUG 2024-06-11 21:33:11,311 INFO - Download file (size 4k) 2024-06-11 21:33:11,312 INFO 2024-06-11 21:33:11,312 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-11 21:33:11,312 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:11,610 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:11,610 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:11,610 DEBUG --- stdout --- 2024-06-11 21:33:11,610 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:11,610 DEBUG --- stderr --- 2024-06-11 21:33:11,610 DEBUG source : /results/orders/login-tree-10/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-11 21:33:11,611 INFO 2024-06-11 21:33:11,611 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-11 21:33:11,611 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:11,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:11,907 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:11,907 DEBUG --- stdout --- 2024-06-11 21:33:11,907 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:11,907 DEBUG --- stderr --- 2024-06-11 21:33:11,907 DEBUG 2024-06-11 21:33:11,907 INFO 2024-06-11 21:33:11,907 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-10/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-06-11 21:33:11,907 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 21:33:12,641 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:12,641 DEBUG --- stdout --- 2024-06-11 21:33:12,642 DEBUG tar: Removing leading `/' from member names 2024-06-11 21:33:12,642 DEBUG --- stderr --- 2024-06-11 21:33:12,642 DEBUG 2024-06-11 21:33:12,642 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt is small (1 lines), printing all lines: 2024-06-11 21:33:12,642 DEBUG ----- output ----- 2024-06-11 21:33:12,642 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-p1protect" --results-folder /results/gatling/login-tree-10 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-11 21:33:12,642 INFO ________________________________________________________________________________ [2024-06-11 21:33:12] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-06-11 21:33:12,643 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt is small (1 lines), printing all lines: 2024-06-11 21:33:12,643 DEBUG ----- output ----- 2024-06-11 21:33:12,643 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-11 21:33:12,643 INFO ________________________________________________________________________________ [2024-06-11 21:33:12] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-11 21:33:12,645 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt is 11343 lines): 2024-06-11 21:33:12,645 DEBUG ----- output ----- 2024-06-11 21:33:12,645 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-11 21:33:12,645 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-11 21:33:12,645 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-11 21:33:12,645 INFO 2024-06-11 21:33:12,645 INFO ================================================================================ 2024-06-11 21:33:12,645 INFO 2024-06-11 21:30:47 5s elapsed 2024-06-11 21:33:12,645 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,645 INFO > Global (OK=8 KO=0 ) 2024-06-11 21:33:12,645 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-06-11 21:33:12,645 INFO > restLoginUsernamePasswordCallback (OK=4 KO=0 ) 2024-06-11 21:33:12,645 INFO 2024-06-11 21:33:12,645 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,645 INFO [- ] 0% 2024-06-11 21:33:12,645 INFO waiting: 36485 / active: 1 / done: 4 2024-06-11 21:33:12,645 INFO ================================================================================ 2024-06-11 21:33:12,645 INFO 2024-06-11 21:33:12,645 INFO 2024-06-11 21:33:12,645 INFO ================================================================================ 2024-06-11 21:33:12,645 INFO 2024-06-11 21:30:52 10s elapsed 2024-06-11 21:33:12,645 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,645 INFO > Global (OK=32 KO=0 ) 2024-06-11 21:33:12,645 INFO > restLoginInitiate (OK=16 KO=0 ) 2024-06-11 21:33:12,645 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-06-11 21:33:12,645 INFO 2024-06-11 21:33:12,645 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,645 INFO [- ] 0% 2024-06-11 21:33:12,645 INFO waiting: 36473 / active: 1 / done: 16 2024-06-11 21:33:12,646 INFO ================================================================================ 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO ================================================================================ 2024-06-11 21:33:12,646 INFO 2024-06-11 21:30:57 15s elapsed 2024-06-11 21:33:12,646 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,646 INFO > Global (OK=73 KO=0 ) 2024-06-11 21:33:12,646 INFO > restLoginInitiate (OK=37 KO=0 ) 2024-06-11 21:33:12,646 INFO > restLoginUsernamePasswordCallback (OK=36 KO=0 ) 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,646 INFO [- ] 0% 2024-06-11 21:33:12,646 INFO waiting: 36452 / active: 2 / done: 36 2024-06-11 21:33:12,646 INFO ================================================================================ 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO ================================================================================ 2024-06-11 21:33:12,646 INFO 2024-06-11 21:31:02 20s elapsed 2024-06-11 21:33:12,646 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,646 INFO > Global (OK=131 KO=0 ) 2024-06-11 21:33:12,646 INFO > restLoginInitiate (OK=66 KO=0 ) 2024-06-11 21:33:12,646 INFO > restLoginUsernamePasswordCallback (OK=65 KO=0 ) 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,646 INFO [- ] 0% 2024-06-11 21:33:12,646 INFO waiting: 36423 / active: 2 / done: 65 2024-06-11 21:33:12,646 INFO ================================================================================ 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO ================================================================================ 2024-06-11 21:33:12,646 INFO 2024-06-11 21:31:07 25s elapsed 2024-06-11 21:33:12,646 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,646 INFO > Global (OK=206 KO=0 ) 2024-06-11 21:33:12,646 INFO > restLoginInitiate (OK=104 KO=0 ) 2024-06-11 21:33:12,646 INFO > restLoginUsernamePasswordCallback (OK=102 KO=0 ) 2024-06-11 21:33:12,646 INFO 2024-06-11 21:33:12,646 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,647 INFO [- ] 0% 2024-06-11 21:33:12,647 INFO waiting: 36385 / active: 3 / done: 102 2024-06-11 21:33:12,647 INFO ================================================================================ 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO ================================================================================ 2024-06-11 21:33:12,647 INFO 2024-06-11 21:31:12 30s elapsed 2024-06-11 21:33:12,647 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,647 INFO > Global (OK=298 KO=0 ) 2024-06-11 21:33:12,647 INFO > restLoginInitiate (OK=150 KO=0 ) 2024-06-11 21:33:12,647 INFO > restLoginUsernamePasswordCallback (OK=148 KO=0 ) 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,647 INFO [- ] 0% 2024-06-11 21:33:12,647 INFO waiting: 36340 / active: 2 / done: 148 2024-06-11 21:33:12,647 INFO ================================================================================ 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO ================================================================================ 2024-06-11 21:33:12,647 INFO 2024-06-11 21:31:17 35s elapsed 2024-06-11 21:33:12,647 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,647 INFO > Global (OK=402 KO=0 ) 2024-06-11 21:33:12,647 INFO > restLoginInitiate (OK=202 KO=0 ) 2024-06-11 21:33:12,647 INFO > restLoginUsernamePasswordCallback (OK=200 KO=0 ) 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,647 INFO [- ] 0% 2024-06-11 21:33:12,647 INFO waiting: 36285 / active: 5 / done: 200 2024-06-11 21:33:12,647 INFO ================================================================================ 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO 2024-06-11 21:33:12,647 INFO ================================================================================ 2024-06-11 21:33:12,647 INFO 2024-06-11 21:31:22 40s elapsed 2024-06-11 21:33:12,647 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,647 INFO > Global (OK=519 KO=0 ) 2024-06-11 21:33:12,647 INFO > restLoginInitiate (OK=260 KO=0 ) 2024-06-11 21:33:12,647 INFO [...] 2024-06-11 21:33:12,650 INFO 2024-06-11 21:33:12,650 INFO 2024-06-11 21:33:12,651 INFO ================================================================================ 2024-06-11 21:33:12,651 INFO 2024-06-11 22:30:57 3615s elapsed 2024-06-11 21:33:12,651 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,651 INFO > Global (OK=72733 KO=2 ) 2024-06-11 21:33:12,651 INFO > restLoginInitiate (OK=36362 KO=1 ) 2024-06-11 21:33:12,651 INFO > restLoginUsernamePasswordCallback (OK=36358 KO=1 ) 2024-06-11 21:33:12,651 INFO > restloginProgressiveProfileCallback (OK=13 KO=0 ) 2024-06-11 21:33:12,651 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 21:33:12,651 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 21:33:12,651 INFO 2024-06-11 21:33:12,651 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,651 INFO [#########################################################################-] 99% 2024-06-11 21:33:12,651 INFO waiting: 127 / active: 3 / done: 36360 2024-06-11 21:33:12,651 INFO ================================================================================ 2024-06-11 21:33:12,651 INFO 2024-06-11 21:33:12,651 INFO 2024-06-11 21:33:12,651 INFO ================================================================================ 2024-06-11 21:33:12,651 INFO 2024-06-11 22:31:02 3620s elapsed 2024-06-11 21:33:12,651 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,651 INFO > Global (OK=72818 KO=2 ) 2024-06-11 21:33:12,651 INFO > restLoginInitiate (OK=36403 KO=1 ) 2024-06-11 21:33:12,651 INFO > restLoginUsernamePasswordCallback (OK=36402 KO=1 ) 2024-06-11 21:33:12,651 INFO > restloginProgressiveProfileCallback (OK=13 KO=0 ) 2024-06-11 21:33:12,651 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 21:33:12,651 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 21:33:12,651 INFO 2024-06-11 21:33:12,651 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,651 INFO [#########################################################################-] 99% 2024-06-11 21:33:12,651 INFO waiting: 85 / active: 1 / done: 36404 2024-06-11 21:33:12,651 INFO ================================================================================ 2024-06-11 21:33:12,651 INFO 2024-06-11 21:33:12,651 INFO 2024-06-11 21:33:12,651 INFO ================================================================================ 2024-06-11 21:33:12,651 INFO 2024-06-11 22:31:07 3625s elapsed 2024-06-11 21:33:12,651 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,651 INFO > Global (OK=72901 KO=2 ) 2024-06-11 21:33:12,651 INFO > restLoginInitiate (OK=36446 KO=1 ) 2024-06-11 21:33:12,651 INFO > restLoginUsernamePasswordCallback (OK=36442 KO=1 ) 2024-06-11 21:33:12,651 INFO > restloginProgressiveProfileCallback (OK=13 KO=0 ) 2024-06-11 21:33:12,652 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 21:33:12,652 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,652 INFO [#########################################################################-] 99% 2024-06-11 21:33:12,652 INFO waiting: 43 / active: 3 / done: 36444 2024-06-11 21:33:12,652 INFO ================================================================================ 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO ================================================================================ 2024-06-11 21:33:12,652 INFO 2024-06-11 22:31:12 3630s elapsed 2024-06-11 21:33:12,652 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,652 INFO > Global (OK=72986 KO=2 ) 2024-06-11 21:33:12,652 INFO > restLoginInitiate (OK=36487 KO=1 ) 2024-06-11 21:33:12,652 INFO > restLoginUsernamePasswordCallback (OK=36486 KO=1 ) 2024-06-11 21:33:12,652 INFO > restloginProgressiveProfileCallback (OK=13 KO=0 ) 2024-06-11 21:33:12,652 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 21:33:12,652 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,652 INFO [#########################################################################-] 99% 2024-06-11 21:33:12,652 INFO waiting: 0 / active: 2 / done: 36488 2024-06-11 21:33:12,652 INFO ================================================================================ 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO ================================================================================ 2024-06-11 21:33:12,652 INFO 2024-06-11 22:31:12 3630s elapsed 2024-06-11 21:33:12,652 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 21:33:12,652 INFO > Global (OK=72990 KO=2 ) 2024-06-11 21:33:12,652 INFO > restLoginInitiate (OK=36489 KO=1 ) 2024-06-11 21:33:12,652 INFO > restLoginUsernamePasswordCallback (OK=36488 KO=1 ) 2024-06-11 21:33:12,652 INFO > restloginProgressiveProfileCallback (OK=13 KO=0 ) 2024-06-11 21:33:12,652 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 21:33:12,652 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 21:33:12,652 INFO [##########################################################################]100% 2024-06-11 21:33:12,652 INFO waiting: 0 / active: 0 / done: 36490 2024-06-11 21:33:12,652 INFO ================================================================================ 2024-06-11 21:33:12,652 INFO 2024-06-11 21:33:12,652 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-11 21:33:12,653 INFO 2024-06-11 21:33:12,653 INFO ---------------------- 2024-06-11 21:33:12,653 INFO [11/Jun/2024 22:31:12] Create tar file login-tree-10.tar.gz 2024-06-11 21:33:12,653 INFO ---------------------- 2024-06-11 21:33:12,653 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-11 21:33:12,653 INFO RESULT_FOLDER=login-tree-10 2024-06-11 21:33:12,653 INFO SIMULATION_PATH=login-tree-10/loadtest-20240611203041758 2024-06-11 21:33:12,653 INFO PWD=/results/gatling/login-tree-10/loadtest-20240611203041758 2024-06-11 21:33:12,653 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-06-11 21:33:12,653 INFO /results/gatling 2024-06-11 21:33:12,653 INFO 2024-06-11 21:33:12,653 INFO ---------------------- 2024-06-11 21:33:12,653 INFO [11/Jun/2024 22:31:12] Update /results/gatling//latest 2024-06-11 21:33:12,653 INFO ---------------------- 2024-06-11 21:33:12,653 INFO 2024-06-11 21:33:12,653 INFO ---------------------- 2024-06-11 21:33:12,653 INFO [11/Jun/2024 22:31:12] Update /results/gatling//latest.tar.gz 2024-06-11 21:33:12,653 INFO ---------------------- 2024-06-11 21:33:12,653 INFO ________________________________________________________________________________ [2024-06-11 21:33:12] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-11 21:33:12,654 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt is small (0 lines), printing all lines: 2024-06-11 21:33:12,654 DEBUG ----- output ----- 2024-06-11 21:33:12,654 DEBUG 2024-06-11 21:33:12,654 INFO ________________________________________________________________________________ [2024-06-11 21:33:12] login-tree-10 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-11 21:33:12] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-11 21:33:12] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped