--Task-- name: login-tree-200 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-180'] preceding_task: login-tree-180 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 200, '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-12 01:43:44] login-tree-200 pre : N/A ________________________________________________________________________________ 2024-06-12 01:43:44 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-12 01:43:44 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-200/stdout.txt Pod log : /results/orders/login-tree-200 2024-06-12 01:43:44,961 INFO 2024-06-12 01:43:44,961 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:44,961 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:45,264 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:45,264 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:45,265 DEBUG --- stdout --- 2024-06-12 01:43:45,265 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:45,265 DEBUG --- stderr --- 2024-06-12 01:43:45,265 DEBUG ________________________________________________________________________________ [2024-06-12 01:43:45] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-12 01:43:45,265 INFO 2024-06-12 01:43:45,265 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:45,265 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:45,567 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:45,567 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:45,567 DEBUG --- stdout --- 2024-06-12 01:43:45,568 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:45,568 DEBUG --- stderr --- 2024-06-12 01:43:45,568 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-200 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-12 01:43:45] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-12 01:43:45,568 INFO 2024-06-12 01:43:45,568 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:45,568 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:45,884 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:45,884 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:45,884 DEBUG --- stdout --- 2024-06-12 01:43:45,884 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:45,884 DEBUG --- stderr --- 2024-06-12 01:43:45,884 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/order.json { "name": "login-tree-200", "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-200 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-200", "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=200 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-12 01:43:45] login-tree-200 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-12 01:43:45,886 INFO 2024-06-12 01:43:45,886 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-12 01:43:45,886 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-12 01:43:46,120 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 01:43:46,120 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:46,120 DEBUG --- stdout --- 2024-06-12 01:43:46,120 DEBUG WAITING 2024-06-12 01:43:46,120 DEBUG --- stderr --- 2024-06-12 01:43:46,120 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-06-12 01:43:46] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-12 01:43:46,121 INFO 2024-06-12 01:43:46,121 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:46,121 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:46,420 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:46,421 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:46,421 DEBUG --- stdout --- 2024-06-12 01:43:46,421 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:46,421 DEBUG --- stderr --- 2024-06-12 01:43:46,421 DEBUG 2024-06-12 01:43:46,421 INFO 2024-06-12 01:43:46,422 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/06_login-tree-200/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-12 01:43:46,422 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 01:43:47,801 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:47,801 DEBUG --- stdout --- 2024-06-12 01:43:47,801 DEBUG 2024-06-12 01:43:47,802 DEBUG --- stderr --- 2024-06-12 01:43:47,802 DEBUG Order has been successfully forwarded to overseer 2024-06-12 01:43:47 - INFO: Check if Gatling simulation started successfully 2024-06-12 01:43:47,802 INFO 2024-06-12 01:43:47,802 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:47,802 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:48,115 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:48,115 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:48,115 DEBUG --- stdout --- 2024-06-12 01:43:48,115 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:48,115 DEBUG --- stderr --- 2024-06-12 01:43:48,115 DEBUG 2024-06-12 01:43:48,116 INFO 2024-06-12 01:43:48,116 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-200/stdout.txt | grep "started..." 2024-06-12 01:43:48,116 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-12 01:43:48,869 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-12 01:43:54,608 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-12 01:43:54,608 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:54,609 DEBUG --- stdout --- 2024-06-12 01:43:54,609 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-12 01:43:54,609 DEBUG --- stderr --- 2024-06-12 01:43:54,609 DEBUG ________________________________________________________________________________ [2024-06-12 01:43:54] login-tree-200 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 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 07s, 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-12 02:46:04] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-12 02:46:04,666 INFO 2024-06-12 02:46:04,667 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:04,667 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:05,030 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:05,030 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:05,030 DEBUG --- stdout --- 2024-06-12 02:46:05,030 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:05,030 DEBUG --- stderr --- 2024-06-12 02:46:05,030 DEBUG 2024-06-12 02:46:05,031 INFO 2024-06-12 02:46:05,031 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-12 02:46:05,031 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 02:46:05,810 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:05,811 DEBUG --- stdout --- 2024-06-12 02:46:05,811 DEBUG 2024-06-12 02:46:05,811 DEBUG --- stderr --- 2024-06-12 02:46:05,811 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-12 02:46:05] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-12 02:46:05 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/order.json 2024-06-12 02:46:05,811 INFO 2024-06-12 02:46:05,812 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:05,812 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:06,115 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:06,115 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:06,115 DEBUG --- stdout --- 2024-06-12 02:46:06,115 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:06,115 DEBUG --- stderr --- 2024-06-12 02:46:06,115 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 02:46:06,115 INFO 2024-06-12 02:46:06,115 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:06,115 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:06,419 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:06,419 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:06,420 DEBUG --- stdout --- 2024-06-12 02:46:06,420 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:06,420 DEBUG --- stderr --- 2024-06-12 02:46:06,420 DEBUG 2024-06-12 02:46:06,420 INFO 2024-06-12 02:46:06,420 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-200/rc.txt" ]; then echo "/results/orders/login-tree-200/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-200/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/rc.txt file found" 2024-06-12 02:46:06,420 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 02:46:07,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 02:46:07,259 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:07,259 DEBUG --- stdout --- 2024-06-12 02:46:07,259 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-06-12 02:46:07,259 DEBUG --- stderr --- 2024-06-12 02:46:07,259 DEBUG 2024-06-12 02:46:07,259 INFO 2024-06-12 02:46:07,259 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:07,259 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:07,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:07,608 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:07,608 DEBUG --- stdout --- 2024-06-12 02:46:07,608 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:07,608 DEBUG --- stderr --- 2024-06-12 02:46:07,608 DEBUG 2024-06-12 02:46:07,609 INFO 2024-06-12 02:46:07,609 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-200/rc.txt 2024-06-12 02:46:08,417 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 02:46:08,418 DEBUG --- stdout --- 2024-06-12 02:46:08,418 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-06-12 02:46:08,418 DEBUG --- stderr --- 2024-06-12 02:46:08,418 DEBUG 2024-06-12 02:46:08,418 INFO - Download file (size 4k) 2024-06-12 02:46:08,418 INFO 2024-06-12 02:46:08,418 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:08,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:08,719 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:08,719 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:08,719 DEBUG --- stdout --- 2024-06-12 02:46:08,719 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:08,719 DEBUG --- stderr --- 2024-06-12 02:46:08,719 DEBUG source : /results/orders/login-tree-200/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/rc.txt 2024-06-12 02:46:08,720 INFO 2024-06-12 02:46:08,720 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:08,720 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:09,030 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:09,030 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:09,030 DEBUG --- stdout --- 2024-06-12 02:46:09,030 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:09,030 DEBUG --- stderr --- 2024-06-12 02:46:09,030 DEBUG 2024-06-12 02:46:09,031 INFO 2024-06-12 02:46:09,031 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-200/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/rc.txt 2024-06-12 02:46:09,031 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 02:46:09,777 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:09,778 DEBUG --- stdout --- 2024-06-12 02:46:09,778 DEBUG tar: Removing leading `/' from member names 2024-06-12 02:46:09,778 DEBUG --- stderr --- 2024-06-12 02:46:09,778 DEBUG 2024-06-12 02:46:09,778 INFO 2024-06-12 02:46:09,778 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:09,779 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:10,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:10,171 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:10,171 DEBUG --- stdout --- 2024-06-12 02:46:10,172 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:10,172 DEBUG --- stderr --- 2024-06-12 02:46:10,172 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 02:46:10,172 INFO 2024-06-12 02:46:10,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-12 02:46:10,172 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:10,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:10,493 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:10,493 DEBUG --- stdout --- 2024-06-12 02:46:10,494 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:10,494 DEBUG --- stderr --- 2024-06-12 02:46:10,494 DEBUG 2024-06-12 02:46:10,494 INFO 2024-06-12 02:46:10,494 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-200/stderr.txt" ]; then echo "/results/orders/login-tree-200/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stderr.txt file found" 2024-06-12 02:46:10,494 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 02:46:11,311 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 02:46:11,311 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:11,312 DEBUG --- stdout --- 2024-06-12 02:46:11,313 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-06-12 02:46:11,313 DEBUG --- stderr --- 2024-06-12 02:46:11,313 DEBUG 2024-06-12 02:46:11,313 INFO 2024-06-12 02:46:11,313 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:11,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:11,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:11,612 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:11,613 DEBUG --- stdout --- 2024-06-12 02:46:11,613 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:11,613 DEBUG --- stderr --- 2024-06-12 02:46:11,613 DEBUG 2024-06-12 02:46:11,613 INFO 2024-06-12 02:46:11,613 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-200/stderr.txt 2024-06-12 02:46:12,412 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 02:46:12,413 DEBUG --- stdout --- 2024-06-12 02:46:12,413 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-06-12 02:46:12,413 DEBUG --- stderr --- 2024-06-12 02:46:12,413 DEBUG 2024-06-12 02:46:12,413 INFO - Download file (size 0k) 2024-06-12 02:46:12,413 INFO 2024-06-12 02:46:12,413 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:12,413 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:12,713 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:12,714 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:12,714 DEBUG --- stdout --- 2024-06-12 02:46:12,714 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:12,714 DEBUG --- stderr --- 2024-06-12 02:46:12,714 DEBUG source : /results/orders/login-tree-200/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/stderr.txt 2024-06-12 02:46:12,715 INFO 2024-06-12 02:46:12,715 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:12,715 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:13,022 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:13,022 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:13,022 DEBUG --- stdout --- 2024-06-12 02:46:13,022 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:13,022 DEBUG --- stderr --- 2024-06-12 02:46:13,022 DEBUG 2024-06-12 02:46:13,023 INFO 2024-06-12 02:46:13,023 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-200/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/stderr.txt 2024-06-12 02:46:13,023 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 02:46:13,753 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:13,753 DEBUG --- stdout --- 2024-06-12 02:46:13,753 DEBUG tar: Removing leading `/' from member names 2024-06-12 02:46:13,753 DEBUG --- stderr --- 2024-06-12 02:46:13,753 DEBUG 2024-06-12 02:46:13,753 INFO 2024-06-12 02:46:13,753 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:13,754 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:14,054 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:14,054 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:14,055 DEBUG --- stdout --- 2024-06-12 02:46:14,055 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:14,055 DEBUG --- stderr --- 2024-06-12 02:46:14,055 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 02:46:14,055 INFO 2024-06-12 02:46:14,055 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:14,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:14,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:14,364 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:14,364 DEBUG --- stdout --- 2024-06-12 02:46:14,364 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:14,364 DEBUG --- stderr --- 2024-06-12 02:46:14,364 DEBUG 2024-06-12 02:46:14,365 INFO 2024-06-12 02:46:14,365 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-200/stdout.txt" ]; then echo "/results/orders/login-tree-200/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stdout.txt file found" 2024-06-12 02:46:14,365 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 02:46:15,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 02:46:15,173 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:15,173 DEBUG --- stdout --- 2024-06-12 02:46:15,173 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-06-12 02:46:15,174 DEBUG --- stderr --- 2024-06-12 02:46:15,174 DEBUG 2024-06-12 02:46:15,174 INFO 2024-06-12 02:46:15,174 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:15,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:15,475 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:15,475 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:15,475 DEBUG --- stdout --- 2024-06-12 02:46:15,475 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:15,475 DEBUG --- stderr --- 2024-06-12 02:46:15,475 DEBUG 2024-06-12 02:46:15,476 INFO 2024-06-12 02:46:15,476 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-200/stdout.txt 2024-06-12 02:46:16,277 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 02:46:16,277 DEBUG --- stdout --- 2024-06-12 02:46:16,277 DEBUG 728 /results/orders/login-tree-200/stdout.txt 2024-06-12 02:46:16,277 DEBUG --- stderr --- 2024-06-12 02:46:16,277 DEBUG 2024-06-12 02:46:16,277 INFO - Download file (size 728k) 2024-06-12 02:46:16,278 INFO 2024-06-12 02:46:16,278 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:16,278 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:16,583 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:16,583 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:16,583 DEBUG --- stdout --- 2024-06-12 02:46:16,584 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:16,584 DEBUG --- stderr --- 2024-06-12 02:46:16,584 DEBUG source : /results/orders/login-tree-200/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/stdout.txt 2024-06-12 02:46:16,584 INFO 2024-06-12 02:46:16,584 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:16,584 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:16,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:16,896 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:16,896 DEBUG --- stdout --- 2024-06-12 02:46:16,896 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:16,896 DEBUG --- stderr --- 2024-06-12 02:46:16,896 DEBUG 2024-06-12 02:46:16,896 INFO 2024-06-12 02:46:16,896 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-200/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/stdout.txt 2024-06-12 02:46:16,896 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 02:46:17,962 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:17,963 DEBUG --- stdout --- 2024-06-12 02:46:17,963 DEBUG tar: Removing leading `/' from member names 2024-06-12 02:46:17,963 DEBUG --- stderr --- 2024-06-12 02:46:17,963 DEBUG ________________________________________________________________________________ [2024-06-12 02:46:17] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-06-12 02:46:17,963 INFO 2024-06-12 02:46:17,964 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:17,964 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:18,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:18,282 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:18,282 DEBUG --- stdout --- 2024-06-12 02:46:18,282 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:18,282 DEBUG --- stderr --- 2024-06-12 02:46:18,283 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 02:46:18,283 INFO 2024-06-12 02:46:18,283 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:18,283 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:18,596 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:18,596 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:18,596 DEBUG --- stdout --- 2024-06-12 02:46:18,597 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:18,597 DEBUG --- stderr --- 2024-06-12 02:46:18,597 DEBUG 2024-06-12 02:46:18,597 INFO 2024-06-12 02:46:18,597 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-200/cmd.txt" ]; then echo "/results/orders/login-tree-200/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-200/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/cmd.txt file found" 2024-06-12 02:46:18,597 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 02:46:19,411 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 02:46:19,411 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:19,411 DEBUG --- stdout --- 2024-06-12 02:46:19,411 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-06-12 02:46:19,411 DEBUG --- stderr --- 2024-06-12 02:46:19,412 DEBUG 2024-06-12 02:46:19,412 INFO 2024-06-12 02:46:19,412 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:19,412 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:19,708 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:19,708 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:19,708 DEBUG --- stdout --- 2024-06-12 02:46:19,708 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:19,708 DEBUG --- stderr --- 2024-06-12 02:46:19,708 DEBUG 2024-06-12 02:46:19,709 INFO 2024-06-12 02:46:19,709 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-200/cmd.txt 2024-06-12 02:46:20,513 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 02:46:20,513 DEBUG --- stdout --- 2024-06-12 02:46:20,514 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-06-12 02:46:20,514 DEBUG --- stderr --- 2024-06-12 02:46:20,514 DEBUG 2024-06-12 02:46:20,514 INFO - Download file (size 4k) 2024-06-12 02:46:20,514 INFO 2024-06-12 02:46:20,514 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:20,514 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:20,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:20,833 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:20,833 DEBUG --- stdout --- 2024-06-12 02:46:20,833 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:20,833 DEBUG --- stderr --- 2024-06-12 02:46:20,833 DEBUG source : /results/orders/login-tree-200/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/cmd.txt 2024-06-12 02:46:20,834 INFO 2024-06-12 02:46:20,834 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 02:46:20,834 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 02:46:21,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 02:46:21,150 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:21,150 DEBUG --- stdout --- 2024-06-12 02:46:21,150 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 02:46:21,150 DEBUG --- stderr --- 2024-06-12 02:46:21,150 DEBUG 2024-06-12 02:46:21,151 INFO 2024-06-12 02:46:21,151 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-200/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/cmd.txt 2024-06-12 02:46:21,151 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 02:46:21,934 INFO [loop_until]: OK (rc = 0) 2024-06-12 02:46:21,934 DEBUG --- stdout --- 2024-06-12 02:46:21,934 DEBUG tar: Removing leading `/' from member names 2024-06-12 02:46:21,934 DEBUG --- stderr --- 2024-06-12 02:46:21,934 DEBUG 2024-06-12 02:46:21,934 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/cmd.txt is small (1 lines), printing all lines: 2024-06-12 02:46:21,934 DEBUG ----- output ----- 2024-06-12 02:46:21,934 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-200 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-12 02:46:21,935 INFO ________________________________________________________________________________ [2024-06-12 02:46:21] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-06-12 02:46:21,935 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/rc.txt is small (1 lines), printing all lines: 2024-06-12 02:46:21,935 DEBUG ----- output ----- 2024-06-12 02:46:21,935 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-12 02:46:21,935 INFO ________________________________________________________________________________ [2024-06-12 02:46:21] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-12 02:46:21,937 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/06_login-tree-200/stdout.txt is 11425 lines): 2024-06-12 02:46:21,937 DEBUG ----- output ----- 2024-06-12 02:46:21,937 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-12 02:46:21,937 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-12 02:46:21,937 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-12 02:46:21,937 INFO 2024-06-12 02:46:21,937 INFO ================================================================================ 2024-06-12 02:46:21,937 INFO 2024-06-12 02:43:56 5s elapsed 2024-06-12 02:46:21,937 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,937 INFO > Global (OK=181 KO=0 ) 2024-06-12 02:46:21,937 INFO > restLoginInitiate (OK=81 KO=0 ) 2024-06-12 02:46:21,937 INFO > restLoginUsernamePasswordCallback (OK=76 KO=0 ) 2024-06-12 02:46:21,937 INFO > restloginProgressiveProfileCallback (OK=24 KO=0 ) 2024-06-12 02:46:21,937 INFO 2024-06-12 02:46:21,937 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,937 INFO [- ] 0% 2024-06-12 02:46:21,937 INFO waiting: 722069 / active: 9 / done: 75 2024-06-12 02:46:21,937 INFO ================================================================================ 2024-06-12 02:46:21,937 INFO 2024-06-12 02:46:21,937 INFO 2024-06-12 02:46:21,937 INFO ================================================================================ 2024-06-12 02:46:21,938 INFO 2024-06-12 02:44:01 10s elapsed 2024-06-12 02:46:21,938 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,938 INFO > Global (OK=725 KO=0 ) 2024-06-12 02:46:21,938 INFO > restLoginInitiate (OK=328 KO=0 ) 2024-06-12 02:46:21,938 INFO > restLoginUsernamePasswordCallback (OK=318 KO=0 ) 2024-06-12 02:46:21,938 INFO > restloginProgressiveProfileCallback (OK=79 KO=0 ) 2024-06-12 02:46:21,938 INFO 2024-06-12 02:46:21,938 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,938 INFO [- ] 0% 2024-06-12 02:46:21,938 INFO waiting: 721818 / active: 19 / done: 316 2024-06-12 02:46:21,938 INFO ================================================================================ 2024-06-12 02:46:21,938 INFO 2024-06-12 02:46:21,938 INFO 2024-06-12 02:46:21,938 INFO ================================================================================ 2024-06-12 02:46:21,938 INFO 2024-06-12 02:44:06 15s elapsed 2024-06-12 02:46:21,938 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,938 INFO > Global (OK=1652 KO=0 ) 2024-06-12 02:46:21,938 INFO > restLoginInitiate (OK=742 KO=0 ) 2024-06-12 02:46:21,938 INFO > restLoginUsernamePasswordCallback (OK=726 KO=0 ) 2024-06-12 02:46:21,938 INFO > restloginProgressiveProfileCallback (OK=184 KO=0 ) 2024-06-12 02:46:21,938 INFO 2024-06-12 02:46:21,938 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,938 INFO [- ] 0% 2024-06-12 02:46:21,938 INFO waiting: 721401 / active: 30 / done: 722 2024-06-12 02:46:21,939 INFO ================================================================================ 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO ================================================================================ 2024-06-12 02:46:21,939 INFO 2024-06-12 02:44:11 20s elapsed 2024-06-12 02:46:21,939 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,939 INFO > Global (OK=2942 KO=0 ) 2024-06-12 02:46:21,939 INFO > restLoginInitiate (OK=1322 KO=0 ) 2024-06-12 02:46:21,939 INFO > restLoginUsernamePasswordCallback (OK=1301 KO=0 ) 2024-06-12 02:46:21,939 INFO > restloginProgressiveProfileCallback (OK=319 KO=0 ) 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,939 INFO [- ] 0% 2024-06-12 02:46:21,939 INFO waiting: 720817 / active: 40 / done: 1296 2024-06-12 02:46:21,939 INFO ================================================================================ 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO ================================================================================ 2024-06-12 02:46:21,939 INFO 2024-06-12 02:44:16 25s elapsed 2024-06-12 02:46:21,939 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,939 INFO > Global (OK=4631 KO=0 ) 2024-06-12 02:46:21,939 INFO > restLoginInitiate (OK=2069 KO=0 ) 2024-06-12 02:46:21,939 INFO > restLoginUsernamePasswordCallback (OK=2042 KO=0 ) 2024-06-12 02:46:21,939 INFO > restloginProgressiveProfileCallback (OK=520 KO=0 ) 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,939 INFO [- ] 0% 2024-06-12 02:46:21,939 INFO waiting: 720067 / active: 55 / done: 2031 2024-06-12 02:46:21,939 INFO ================================================================================ 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO 2024-06-12 02:46:21,939 INFO ================================================================================ 2024-06-12 02:46:21,939 INFO 2024-06-12 02:44:21 30s elapsed 2024-06-12 02:46:21,940 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,940 INFO > Global (OK=6685 KO=0 ) 2024-06-12 02:46:21,940 INFO > restLoginInitiate (OK=2982 KO=0 ) 2024-06-12 02:46:21,940 INFO > restLoginUsernamePasswordCallback (OK=2950 KO=0 ) 2024-06-12 02:46:21,940 INFO > restloginProgressiveProfileCallback (OK=753 KO=0 ) 2024-06-12 02:46:21,940 INFO 2024-06-12 02:46:21,940 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,940 INFO [- ] 0% 2024-06-12 02:46:21,940 INFO waiting: 719148 / active: 66 / done: 2939 2024-06-12 02:46:21,940 INFO ================================================================================ 2024-06-12 02:46:21,940 INFO 2024-06-12 02:46:21,940 INFO 2024-06-12 02:46:21,940 INFO ================================================================================ 2024-06-12 02:46:21,940 INFO 2024-06-12 02:44:26 35s elapsed 2024-06-12 02:46:21,940 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,940 INFO > Global (OK=8975 KO=0 ) 2024-06-12 02:46:21,940 INFO > restLoginInitiate (OK=3986 KO=0 ) 2024-06-12 02:46:21,940 INFO > restLoginUsernamePasswordCallback (OK=3953 KO=0 ) 2024-06-12 02:46:21,940 INFO > restloginProgressiveProfileCallback (OK=1036 KO=0 ) 2024-06-12 02:46:21,940 INFO 2024-06-12 02:46:21,940 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,940 INFO [- ] 0% 2024-06-12 02:46:21,940 INFO waiting: 718149 / active: 64 / done: 3940 2024-06-12 02:46:21,940 INFO ================================================================================ 2024-06-12 02:46:21,940 INFO [...] 2024-06-12 02:46:21,943 INFO 2024-06-12 02:46:21,943 INFO 2024-06-12 02:46:21,943 INFO ================================================================================ 2024-06-12 02:46:21,943 INFO 2024-06-12 03:44:06 3615s elapsed 2024-06-12 02:46:21,943 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,944 INFO > Global (OK=1629561 KO=3 ) 2024-06-12 02:46:21,944 INFO > restLoginInitiate (OK=719103 KO=1 ) 2024-06-12 02:46:21,944 INFO > restLoginUsernamePasswordCallback (OK=719055 KO=1 ) 2024-06-12 02:46:21,944 INFO > restloginProgressiveProfileCallback (OK=191403 KO=1 ) 2024-06-12 02:46:21,944 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 02:46:21,944 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-06-12 02:46:21,944 INFO 2024-06-12 02:46:21,944 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,944 INFO [#########################################################################-] 99% 2024-06-12 02:46:21,944 INFO waiting: 3028 / active: 79 / done: 719046 2024-06-12 02:46:21,944 INFO ================================================================================ 2024-06-12 02:46:21,944 INFO 2024-06-12 02:46:21,944 INFO 2024-06-12 02:46:21,944 INFO ================================================================================ 2024-06-12 02:46:21,944 INFO 2024-06-12 03:44:11 3620s elapsed 2024-06-12 02:46:21,944 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,944 INFO > Global (OK=1631906 KO=3 ) 2024-06-12 02:46:21,944 INFO > restLoginInitiate (OK=720137 KO=1 ) 2024-06-12 02:46:21,944 INFO > restLoginUsernamePasswordCallback (OK=720090 KO=1 ) 2024-06-12 02:46:21,944 INFO > restloginProgressiveProfileCallback (OK=191679 KO=1 ) 2024-06-12 02:46:21,944 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 02:46:21,944 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-06-12 02:46:21,944 INFO 2024-06-12 02:46:21,944 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,944 INFO [#########################################################################-] 99% 2024-06-12 02:46:21,944 INFO waiting: 1992 / active: 82 / done: 720079 2024-06-12 02:46:21,944 INFO ================================================================================ 2024-06-12 02:46:21,944 INFO 2024-06-12 02:46:21,944 INFO 2024-06-12 02:46:21,944 INFO ================================================================================ 2024-06-12 02:46:21,944 INFO 2024-06-12 03:44:16 3625s elapsed 2024-06-12 02:46:21,944 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,944 INFO > Global (OK=1634183 KO=3 ) 2024-06-12 02:46:21,944 INFO > restLoginInitiate (OK=721142 KO=1 ) 2024-06-12 02:46:21,944 INFO > restLoginUsernamePasswordCallback (OK=721102 KO=1 ) 2024-06-12 02:46:21,944 INFO > restloginProgressiveProfileCallback (OK=191939 KO=1 ) 2024-06-12 02:46:21,944 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 02:46:21,945 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,945 INFO [#########################################################################-] 99% 2024-06-12 02:46:21,945 INFO waiting: 992 / active: 62 / done: 721099 2024-06-12 02:46:21,945 INFO ================================================================================ 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO ================================================================================ 2024-06-12 02:46:21,945 INFO 2024-06-12 03:44:21 3630s elapsed 2024-06-12 02:46:21,945 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,945 INFO > Global (OK=1636395 KO=3 ) 2024-06-12 02:46:21,945 INFO > restLoginInitiate (OK=722136 KO=1 ) 2024-06-12 02:46:21,945 INFO > restLoginUsernamePasswordCallback (OK=722092 KO=1 ) 2024-06-12 02:46:21,945 INFO > restloginProgressiveProfileCallback (OK=192167 KO=1 ) 2024-06-12 02:46:21,945 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 02:46:21,945 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,945 INFO [#########################################################################-] 99% 2024-06-12 02:46:21,945 INFO waiting: 0 / active: 70 / done: 722083 2024-06-12 02:46:21,945 INFO ================================================================================ 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO ================================================================================ 2024-06-12 02:46:21,945 INFO 2024-06-12 03:44:22 3630s elapsed 2024-06-12 02:46:21,945 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 02:46:21,945 INFO > Global (OK=1636500 KO=3 ) 2024-06-12 02:46:21,945 INFO > restLoginInitiate (OK=722152 KO=1 ) 2024-06-12 02:46:21,945 INFO > restLoginUsernamePasswordCallback (OK=722151 KO=1 ) 2024-06-12 02:46:21,945 INFO > restloginProgressiveProfileCallback (OK=192197 KO=1 ) 2024-06-12 02:46:21,945 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 02:46:21,945 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 02:46:21,945 INFO [##########################################################################]100% 2024-06-12 02:46:21,945 INFO waiting: 0 / active: 0 / done: 722153 2024-06-12 02:46:21,945 INFO ================================================================================ 2024-06-12 02:46:21,945 INFO 2024-06-12 02:46:21,945 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-12 02:46:21,946 INFO 2024-06-12 02:46:21,946 INFO ---------------------- 2024-06-12 02:46:21,946 INFO [12/Jun/2024 03:44:22] Create tar file login-tree-200.tar.gz 2024-06-12 02:46:21,946 INFO ---------------------- 2024-06-12 02:46:21,946 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-12 02:46:21,946 INFO RESULT_FOLDER=login-tree-200 2024-06-12 02:46:21,946 INFO SIMULATION_PATH=login-tree-200/loadtest-20240612014351308 2024-06-12 02:46:21,946 INFO PWD=/results/gatling/login-tree-200/loadtest-20240612014351308 2024-06-12 02:46:21,946 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-06-12 02:46:21,946 INFO /results/gatling 2024-06-12 02:46:21,946 INFO 2024-06-12 02:46:21,946 INFO ---------------------- 2024-06-12 02:46:21,946 INFO [12/Jun/2024 03:44:22] Update /results/gatling//latest 2024-06-12 02:46:21,946 INFO ---------------------- 2024-06-12 02:46:21,946 INFO 2024-06-12 02:46:21,946 INFO ---------------------- 2024-06-12 02:46:21,946 INFO [12/Jun/2024 03:44:22] Update /results/gatling//latest.tar.gz 2024-06-12 02:46:21,946 INFO ---------------------- 2024-06-12 02:46:21,946 INFO ________________________________________________________________________________ [2024-06-12 02:46:21] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-12 02:46:21,946 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-200/stderr.txt is small (0 lines), printing all lines: 2024-06-12 02:46:21,947 DEBUG ----- output ----- 2024-06-12 02:46:21,947 DEBUG 2024-06-12 02:46:21,947 INFO ________________________________________________________________________________ [2024-06-12 02:46:21] login-tree-200 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-12 02:46:21] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-12 02:46:21] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped