--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.loginProtect', 'testMode': 'open', 'throughput': 200, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-13 05:04:32] login-tree-200 pre : N/A ________________________________________________________________________________ 2024-06-13 05:04:32 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-13 05:04:32 - 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-13 05:04:32,645 INFO 2024-06-13 05:04:32,645 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 05:04:32,645 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:04:32,962 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:04:32,962 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:32,962 DEBUG --- stdout --- 2024-06-13 05:04:32,962 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:04:32,962 DEBUG --- stderr --- 2024-06-13 05:04:32,962 DEBUG ________________________________________________________________________________ [2024-06-13 05:04:32] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-13 05:04:32,963 INFO 2024-06-13 05:04:32,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-13 05:04:32,963 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:04:33,270 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:04:33,270 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:33,270 DEBUG --- stdout --- 2024-06-13 05:04:33,270 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:04:33,270 DEBUG --- stderr --- 2024-06-13 05:04:33,270 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-13 05:04:33] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-13 05:04:33,271 INFO 2024-06-13 05:04:33,271 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 05:04:33,271 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:04:33,570 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:04:33,570 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:33,570 DEBUG --- stdout --- 2024-06-13 05:04:33,570 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:04:33,570 DEBUG --- stderr --- 2024-06-13 05:04:33,570 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.loginProtect@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_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=200 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-13 05:04:33] 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-13 05:04:33,572 INFO 2024-06-13 05:04:33,572 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 05:04:33,572 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 05:04:33,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 05:04:33,864 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:33,864 DEBUG --- stdout --- 2024-06-13 05:04:33,864 DEBUG WAITING 2024-06-13 05:04:33,864 DEBUG --- stderr --- 2024-06-13 05:04:33,864 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-06-13 05:04:33] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-13 05:04:33,865 INFO 2024-06-13 05:04:33,865 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 05:04:33,865 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:04:34,170 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:04:34,170 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:34,170 DEBUG --- stdout --- 2024-06-13 05:04:34,170 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:04:34,170 DEBUG --- stderr --- 2024-06-13 05:04:34,170 DEBUG 2024-06-13 05:04:34,171 INFO 2024-06-13 05:04:34,171 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-13 05:04:34,171 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 05:04:35,566 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:35,566 DEBUG --- stdout --- 2024-06-13 05:04:35,567 DEBUG 2024-06-13 05:04:35,567 DEBUG --- stderr --- 2024-06-13 05:04:35,567 DEBUG Order has been successfully forwarded to overseer 2024-06-13 05:04:35 - INFO: Check if Gatling simulation started successfully 2024-06-13 05:04:35,567 INFO 2024-06-13 05:04:35,567 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 05:04:35,567 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:04:35,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:04:35,878 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:35,878 DEBUG --- stdout --- 2024-06-13 05:04:35,878 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:04:35,878 DEBUG --- stderr --- 2024-06-13 05:04:35,878 DEBUG 2024-06-13 05:04:35,879 INFO 2024-06-13 05:04:35,879 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-13 05:04:35,879 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 05:04:36,612 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-13 05:04:42,349 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-13 05:04:42,350 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:04:42,350 DEBUG --- stdout --- 2024-06-13 05:04:42,350 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-13 05:04:42,350 DEBUG --- stderr --- 2024-06-13 05:04:42,350 DEBUG ________________________________________________________________________________ [2024-06-13 05:04:42] 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 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 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 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-13 06:06:52] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-13 06:06:52,647 INFO 2024-06-13 06:06:52,647 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:52,647 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:52,978 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:52,978 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:52,978 DEBUG --- stdout --- 2024-06-13 06:06:52,978 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:52,978 DEBUG --- stderr --- 2024-06-13 06:06:52,978 DEBUG 2024-06-13 06:06:52,979 INFO 2024-06-13 06:06:52,979 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-13 06:06:52,979 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:06:53,761 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:53,762 DEBUG --- stdout --- 2024-06-13 06:06:53,762 DEBUG 2024-06-13 06:06:53,762 DEBUG --- stderr --- 2024-06-13 06:06:53,762 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-13 06:06:53] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-13 06:06:53 - 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-13 06:06:53,763 INFO 2024-06-13 06:06:53,763 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:53,763 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:54,066 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:54,066 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:54,067 DEBUG --- stdout --- 2024-06-13 06:06:54,067 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:54,067 DEBUG --- stderr --- 2024-06-13 06:06:54,067 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:06:54,068 INFO 2024-06-13 06:06:54,068 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:54,068 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:54,366 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:54,366 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:54,366 DEBUG --- stdout --- 2024-06-13 06:06:54,366 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:54,366 DEBUG --- stderr --- 2024-06-13 06:06:54,366 DEBUG 2024-06-13 06:06:54,367 INFO 2024-06-13 06:06:54,367 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-13 06:06:54,367 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:06:55,175 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:06:55,175 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:55,175 DEBUG --- stdout --- 2024-06-13 06:06:55,176 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-06-13 06:06:55,176 DEBUG --- stderr --- 2024-06-13 06:06:55,176 DEBUG 2024-06-13 06:06:55,176 INFO 2024-06-13 06:06:55,176 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:55,176 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:55,470 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:55,471 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:55,471 DEBUG --- stdout --- 2024-06-13 06:06:55,471 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:55,471 DEBUG --- stderr --- 2024-06-13 06:06:55,471 DEBUG 2024-06-13 06:06:55,471 INFO 2024-06-13 06:06:55,471 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-13 06:06:56,266 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:06:56,266 DEBUG --- stdout --- 2024-06-13 06:06:56,266 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-06-13 06:06:56,266 DEBUG --- stderr --- 2024-06-13 06:06:56,266 DEBUG 2024-06-13 06:06:56,266 INFO - Download file (size 4k) 2024-06-13 06:06:56,267 INFO 2024-06-13 06:06:56,267 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:56,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:56,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:56,566 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:56,566 DEBUG --- stdout --- 2024-06-13 06:06:56,567 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:56,567 DEBUG --- stderr --- 2024-06-13 06:06:56,567 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-13 06:06:56,567 INFO 2024-06-13 06:06:56,567 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:56,567 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:56,867 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:56,867 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:56,867 DEBUG --- stdout --- 2024-06-13 06:06:56,867 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:56,867 DEBUG --- stderr --- 2024-06-13 06:06:56,867 DEBUG 2024-06-13 06:06:56,867 INFO 2024-06-13 06:06:56,868 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-13 06:06:56,868 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:06:57,586 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:57,586 DEBUG --- stdout --- 2024-06-13 06:06:57,587 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:06:57,587 DEBUG --- stderr --- 2024-06-13 06:06:57,587 DEBUG 2024-06-13 06:06:57,587 INFO 2024-06-13 06:06:57,588 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:57,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:57,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:57,895 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:57,895 DEBUG --- stdout --- 2024-06-13 06:06:57,895 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:57,895 DEBUG --- stderr --- 2024-06-13 06:06:57,895 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:06:57,895 INFO 2024-06-13 06:06:57,896 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:57,896 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:58,203 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:58,203 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:58,203 DEBUG --- stdout --- 2024-06-13 06:06:58,203 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:58,203 DEBUG --- stderr --- 2024-06-13 06:06:58,203 DEBUG 2024-06-13 06:06:58,204 INFO 2024-06-13 06:06:58,204 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-13 06:06:58,204 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:06:59,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:06:59,009 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:59,009 DEBUG --- stdout --- 2024-06-13 06:06:59,009 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-06-13 06:06:59,009 DEBUG --- stderr --- 2024-06-13 06:06:59,009 DEBUG 2024-06-13 06:06:59,009 INFO 2024-06-13 06:06:59,009 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:06:59,009 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:06:59,322 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:06:59,323 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:06:59,324 DEBUG --- stdout --- 2024-06-13 06:06:59,324 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:06:59,325 DEBUG --- stderr --- 2024-06-13 06:06:59,325 DEBUG 2024-06-13 06:06:59,325 INFO 2024-06-13 06:06:59,325 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-13 06:07:00,144 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:07:00,144 DEBUG --- stdout --- 2024-06-13 06:07:00,144 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-06-13 06:07:00,144 DEBUG --- stderr --- 2024-06-13 06:07:00,144 DEBUG 2024-06-13 06:07:00,144 INFO - Download file (size 0k) 2024-06-13 06:07:00,145 INFO 2024-06-13 06:07:00,145 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:00,145 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:00,457 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:00,457 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:00,457 DEBUG --- stdout --- 2024-06-13 06:07:00,457 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:00,457 DEBUG --- stderr --- 2024-06-13 06:07:00,457 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-13 06:07:00,458 INFO 2024-06-13 06:07:00,458 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:00,458 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:00,755 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:00,755 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:00,755 DEBUG --- stdout --- 2024-06-13 06:07:00,755 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:00,755 DEBUG --- stderr --- 2024-06-13 06:07:00,755 DEBUG 2024-06-13 06:07:00,756 INFO 2024-06-13 06:07:00,756 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-13 06:07:00,756 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:07:01,528 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:01,528 DEBUG --- stdout --- 2024-06-13 06:07:01,529 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:07:01,529 DEBUG --- stderr --- 2024-06-13 06:07:01,529 DEBUG 2024-06-13 06:07:01,529 INFO 2024-06-13 06:07:01,529 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:01,529 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:01,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:01,835 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:01,835 DEBUG --- stdout --- 2024-06-13 06:07:01,835 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:01,835 DEBUG --- stderr --- 2024-06-13 06:07:01,835 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:07:01,836 INFO 2024-06-13 06:07:01,836 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:01,836 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:02,143 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:02,143 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:02,143 DEBUG --- stdout --- 2024-06-13 06:07:02,143 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:02,143 DEBUG --- stderr --- 2024-06-13 06:07:02,143 DEBUG 2024-06-13 06:07:02,144 INFO 2024-06-13 06:07:02,144 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-13 06:07:02,144 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:07:02,959 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:07:02,959 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:02,959 DEBUG --- stdout --- 2024-06-13 06:07:02,959 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-06-13 06:07:02,959 DEBUG --- stderr --- 2024-06-13 06:07:02,959 DEBUG 2024-06-13 06:07:02,959 INFO 2024-06-13 06:07:02,959 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:02,960 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:03,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:03,259 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:03,259 DEBUG --- stdout --- 2024-06-13 06:07:03,259 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:03,259 DEBUG --- stderr --- 2024-06-13 06:07:03,259 DEBUG 2024-06-13 06:07:03,259 INFO 2024-06-13 06:07:03,260 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-13 06:07:04,057 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:07:04,057 DEBUG --- stdout --- 2024-06-13 06:07:04,057 DEBUG 3120176 /results/orders/login-tree-200/stdout.txt 2024-06-13 06:07:04,057 DEBUG --- stderr --- 2024-06-13 06:07:04,057 DEBUG 2024-06-13 06:07:04,057 INFO - Download file (size 3120176k) 2024-06-13 06:07:04,058 INFO 2024-06-13 06:07:04,058 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:04,058 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:04,356 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:04,357 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:04,358 DEBUG --- stdout --- 2024-06-13 06:07:04,358 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:04,358 DEBUG --- stderr --- 2024-06-13 06:07:04,358 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-13 06:07:04,359 INFO 2024-06-13 06:07:04,359 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:07:04,359 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:07:04,660 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:07:04,660 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:07:04,660 DEBUG --- stdout --- 2024-06-13 06:07:04,660 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:07:04,660 DEBUG --- stderr --- 2024-06-13 06:07:04,660 DEBUG 2024-06-13 06:07:04,661 INFO 2024-06-13 06:07:04,661 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-13 06:07:04,661 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:08:18,223 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:18,223 DEBUG --- stdout --- 2024-06-13 06:08:18,223 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:08:18,223 DEBUG --- stderr --- 2024-06-13 06:08:18,224 DEBUG ________________________________________________________________________________ [2024-06-13 06:08:18] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-06-13 06:08:18,224 INFO 2024-06-13 06:08:18,224 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:08:18,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:08:18,551 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:08:18,551 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:18,552 DEBUG --- stdout --- 2024-06-13 06:08:18,552 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:08:18,552 DEBUG --- stderr --- 2024-06-13 06:08:18,552 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 06:08:18,552 INFO 2024-06-13 06:08:18,552 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:08:18,552 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:08:18,846 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:08:18,846 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:18,846 DEBUG --- stdout --- 2024-06-13 06:08:18,847 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:08:18,847 DEBUG --- stderr --- 2024-06-13 06:08:18,847 DEBUG 2024-06-13 06:08:18,847 INFO 2024-06-13 06:08:18,847 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-13 06:08:18,847 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 06:08:19,682 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 06:08:19,682 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:19,682 DEBUG --- stdout --- 2024-06-13 06:08:19,682 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-06-13 06:08:19,682 DEBUG --- stderr --- 2024-06-13 06:08:19,682 DEBUG 2024-06-13 06:08:19,683 INFO 2024-06-13 06:08:19,683 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:08:19,683 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:08:19,986 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:08:19,987 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:19,987 DEBUG --- stdout --- 2024-06-13 06:08:19,987 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:08:19,987 DEBUG --- stderr --- 2024-06-13 06:08:19,987 DEBUG 2024-06-13 06:08:19,987 INFO 2024-06-13 06:08:19,987 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-13 06:08:20,784 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 06:08:20,784 DEBUG --- stdout --- 2024-06-13 06:08:20,784 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-06-13 06:08:20,784 DEBUG --- stderr --- 2024-06-13 06:08:20,784 DEBUG 2024-06-13 06:08:20,784 INFO - Download file (size 4k) 2024-06-13 06:08:20,785 INFO 2024-06-13 06:08:20,785 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:08:20,785 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:08:21,083 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:08:21,083 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:21,083 DEBUG --- stdout --- 2024-06-13 06:08:21,084 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:08:21,084 DEBUG --- stderr --- 2024-06-13 06:08:21,084 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-13 06:08:21,084 INFO 2024-06-13 06:08:21,084 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 06:08:21,084 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 06:08:21,381 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 06:08:21,382 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:21,382 DEBUG --- stdout --- 2024-06-13 06:08:21,382 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 06:08:21,382 DEBUG --- stderr --- 2024-06-13 06:08:21,382 DEBUG 2024-06-13 06:08:21,382 INFO 2024-06-13 06:08:21,382 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-13 06:08:21,382 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 06:08:22,114 INFO [loop_until]: OK (rc = 0) 2024-06-13 06:08:22,114 DEBUG --- stdout --- 2024-06-13 06:08:22,115 DEBUG tar: Removing leading `/' from member names 2024-06-13 06:08:22,115 DEBUG --- stderr --- 2024-06-13 06:08:22,115 DEBUG 2024-06-13 06:08:22,115 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-13 06:08:22,115 DEBUG ----- output ----- 2024-06-13 06:08:22,115 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-13 06:08:22,115 INFO ________________________________________________________________________________ [2024-06-13 06:08:22] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-06-13 06:08:22,116 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-13 06:08:22,116 DEBUG ----- output ----- 2024-06-13 06:08:22,116 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 06:08:22,116 INFO ________________________________________________________________________________ [2024-06-13 06:08:22] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-13 06:08:26,408 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 18270643 lines): 2024-06-13 06:08:26,408 DEBUG ----- output ----- 2024-06-13 06:08:26,408 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-13 06:08:26,408 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-13 06:08:26,408 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 06:08:26,408 INFO 2024-06-13 06:08:26,408 INFO ================================================================================ 2024-06-13 06:08:26,408 INFO 2024-06-13 06:04:44 5s elapsed 2024-06-13 06:08:26,408 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:26,408 INFO > Global (OK=294 KO=0 ) 2024-06-13 06:08:26,408 INFO > protectLoginInitiate (OK=79 KO=0 ) 2024-06-13 06:08:26,408 INFO > PingOneProtectInitializeCallback (OK=76 KO=0 ) 2024-06-13 06:08:26,408 INFO > restLoginUsernamePasswordCallback (OK=73 KO=0 ) 2024-06-13 06:08:26,408 INFO > PingOneProtectEvaluationCallback (OK=54 KO=0 ) 2024-06-13 06:08:26,408 INFO > restloginProgressiveProfileCallback (OK=12 KO=0 ) 2024-06-13 06:08:26,408 INFO 2024-06-13 06:08:26,409 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:26,409 INFO [- ] 0% 2024-06-13 06:08:26,409 INFO waiting: 722818 / active: 31 / done: 53 2024-06-13 06:08:26,409 INFO ================================================================================ 2024-06-13 06:08:26,409 INFO 2024-06-13 06:08:26,409 INFO 2024-06-13 06:08:26,409 INFO ================================================================================ 2024-06-13 06:08:26,409 INFO 2024-06-13 06:04:49 10s elapsed 2024-06-13 06:08:26,409 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:26,409 INFO > Global (OK=1308 KO=0 ) 2024-06-13 06:08:26,409 INFO > protectLoginInitiate (OK=327 KO=0 ) 2024-06-13 06:08:26,409 INFO > PingOneProtectInitializeCallback (OK=320 KO=0 ) 2024-06-13 06:08:26,409 INFO > restLoginUsernamePasswordCallback (OK=311 KO=0 ) 2024-06-13 06:08:26,409 INFO > PingOneProtectEvaluationCallback (OK=281 KO=0 ) 2024-06-13 06:08:26,409 INFO > restloginProgressiveProfileCallback (OK=69 KO=0 ) 2024-06-13 06:08:26,409 INFO 2024-06-13 06:08:26,409 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:26,409 INFO [- ] 0% 2024-06-13 06:08:26,409 INFO waiting: 722568 / active: 57 / done: 277 2024-06-13 06:08:26,409 INFO ================================================================================ 2024-06-13 06:08:26,409 INFO 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO ================================================================================ 2024-06-13 06:08:26,410 INFO 2024-06-13 06:04:54 15s elapsed 2024-06-13 06:08:26,410 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:26,410 INFO > Global (OK=3021 KO=0 ) 2024-06-13 06:08:26,410 INFO > protectLoginInitiate (OK=740 KO=0 ) 2024-06-13 06:08:26,410 INFO > PingOneProtectInitializeCallback (OK=730 KO=0 ) 2024-06-13 06:08:26,410 INFO > restLoginUsernamePasswordCallback (OK=718 KO=0 ) 2024-06-13 06:08:26,410 INFO > PingOneProtectEvaluationCallback (OK=667 KO=0 ) 2024-06-13 06:08:26,410 INFO > restloginProgressiveProfileCallback (OK=166 KO=0 ) 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:26,410 INFO [- ] 0% 2024-06-13 06:08:26,410 INFO waiting: 722151 / active: 90 / done: 661 2024-06-13 06:08:26,410 INFO ================================================================================ 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO ================================================================================ 2024-06-13 06:08:26,410 INFO 2024-06-13 06:04:59 20s elapsed 2024-06-13 06:08:26,410 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:26,410 INFO > Global (OK=5441 KO=0 ) 2024-06-13 06:08:26,410 INFO > protectLoginInitiate (OK=1320 KO=0 ) 2024-06-13 06:08:26,410 INFO > PingOneProtectInitializeCallback (OK=1307 KO=0 ) 2024-06-13 06:08:26,410 INFO > restLoginUsernamePasswordCallback (OK=1291 KO=0 ) 2024-06-13 06:08:26,410 INFO > PingOneProtectEvaluationCallback (OK=1227 KO=0 ) 2024-06-13 06:08:26,410 INFO > restloginProgressiveProfileCallback (OK=296 KO=0 ) 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:26,410 INFO [- ] 0% 2024-06-13 06:08:26,410 INFO waiting: 721567 / active: 116 / done: 1219 2024-06-13 06:08:26,410 INFO ================================================================================ 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO 2024-06-13 06:08:26,410 INFO ================================================================================ 2024-06-13 06:08:26,410 INFO 2024-06-13 06:05:04 25s elapsed 2024-06-13 06:08:26,411 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:26,411 INFO > Global (OK=8569 KO=0 ) 2024-06-13 06:08:26,411 INFO > protectLoginInitiate (OK=2067 KO=0 ) 2024-06-13 06:08:26,411 INFO > PingOneProtectInitializeCallback (OK=2048 KO=0 ) 2024-06-13 06:08:26,411 INFO > restLoginUsernamePasswordCallback (OK=2024 KO=0 ) 2024-06-13 06:08:26,411 INFO > PingOneProtectEvaluationCallback (OK=1941 KO=0 ) 2024-06-13 06:08:26,411 INFO > restloginProgressiveProfileCallback (OK=489 KO=0 ) 2024-06-13 06:08:26,411 INFO 2024-06-13 06:08:26,411 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:26,411 INFO [- ] 0% 2024-06-13 06:08:26,411 INFO waiting: 720817 / active: 158 / done: 1927 2024-06-13 06:08:26,411 INFO ================================================================================ 2024-06-13 06:08:26,411 INFO 2024-06-13 06:08:26,411 INFO 2024-06-13 06:08:26,411 INFO ================================================================================ 2024-06-13 06:08:26,411 INFO 2024-06-13 06:05:09 30s elapsed 2024-06-13 06:08:26,411 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:26,411 INFO > Global (OK=12319 KO=0 ) 2024-06-13 06:08:26,411 INFO > protectLoginInitiate (OK=2970 KO=0 ) 2024-06-13 06:08:26,411 INFO > PingOneProtectInitializeCallback (OK=2940 KO=0 ) 2024-06-13 06:08:26,411 INFO > restLoginUsernamePasswordCallback (OK=2898 KO=0 ) 2024-06-13 06:08:26,411 INFO > PingOneProtectEvaluationCallback (OK=2801 KO=0 ) 2024-06-13 06:08:26,411 INFO > restloginProgressiveProfileCallback (OK=710 KO=0 ) 2024-06-13 06:08:26,411 INFO 2024-06-13 06:08:26,411 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:26,411 INFO [- ] 0% 2024-06-13 06:08:26,411 INFO waiting: 719897 / active: 218 / done: 2787 2024-06-13 06:08:26,411 INFO ================================================================================ 2024-06-13 06:08:26,411 INFO 2024-06-13 06:08:26,411 INFO 2024-06-13 06:08:26,411 INFO [...] 2024-06-13 06:08:32,693 INFO > PingOneProtectInitializeCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,694 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,694 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,694 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,694 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,694 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,694 INFO > restloginProgressiveProfileCallback: Failed to build request: 60860 ( 8.96%) 2024-06-13 06:08:32,694 INFO No attribute named 'callbacks' is defined 2024-06-13 06:08:32,694 INFO > status.find.is(200), but actually found 401 14892 ( 2.19%) 2024-06-13 06:08:32,694 INFO > status.find.is(200), but actually found 502 13637 ( 2.01%) 2024-06-13 06:08:32,694 INFO > jsonPath($.tokenId).find.exists, found nothing 7035 ( 1.04%) 2024-06-13 06:08:32,694 INFO > status.find.is(200), but actually found 400 1213 ( 0.18%) 2024-06-13 06:08:32,694 INFO > j.i.IOException: Premature close 5 ( 0.00%) 2024-06-13 06:08:32,694 INFO 2024-06-13 06:08:32,694 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:32,694 INFO [#########################################################################-] 99% 2024-06-13 06:08:32,694 INFO waiting: 0 / active: 1628 / done: 721274 2024-06-13 06:08:32,694 INFO ================================================================================ 2024-06-13 06:08:32,694 INFO 2024-06-13 06:08:32,694 INFO 2024-06-13 06:08:32,694 INFO ================================================================================ 2024-06-13 06:08:32,694 INFO 2024-06-13 07:05:24 3645s elapsed 2024-06-13 06:08:32,694 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:32,694 INFO > Global (OK=2537766 KO=435865) 2024-06-13 06:08:32,694 INFO > protectLoginInitiate (OK=662042 KO=60860 ) 2024-06-13 06:08:32,694 INFO > PingOneProtectInitializeCallback (OK=622117 KO=39925 ) 2024-06-13 06:08:32,694 INFO > restLoginUsernamePasswordCallback (OK=585551 KO=76491 ) 2024-06-13 06:08:32,694 INFO > PingOneProtectEvaluationCallback (OK=539406 KO=122209) 2024-06-13 06:08:32,694 INFO > restloginProgressiveProfileCallback (OK=128650 KO=136380) 2024-06-13 06:08:32,694 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 06:08:32,694 INFO > status.find.is(200), but actually found 503 399083 (58.75%) 2024-06-13 06:08:32,695 INFO > PingOneProtectInitializeCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > restloginProgressiveProfileCallback: Failed to build request: 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO No attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > status.find.is(200), but actually found 401 14892 ( 2.19%) 2024-06-13 06:08:32,695 INFO > status.find.is(200), but actually found 502 13637 ( 2.01%) 2024-06-13 06:08:32,695 INFO > jsonPath($.tokenId).find.exists, found nothing 7035 ( 1.04%) 2024-06-13 06:08:32,695 INFO > status.find.is(200), but actually found 400 1213 ( 0.18%) 2024-06-13 06:08:32,695 INFO > j.i.IOException: Premature close 5 ( 0.00%) 2024-06-13 06:08:32,695 INFO 2024-06-13 06:08:32,695 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:32,695 INFO [#########################################################################-] 99% 2024-06-13 06:08:32,695 INFO waiting: 0 / active: 441 / done: 722461 2024-06-13 06:08:32,695 INFO ================================================================================ 2024-06-13 06:08:32,695 INFO 2024-06-13 06:08:32,695 INFO 2024-06-13 06:08:32,695 INFO ================================================================================ 2024-06-13 06:08:32,695 INFO 2024-06-13 07:05:26 3646s elapsed 2024-06-13 06:08:32,695 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 06:08:32,695 INFO > Global (OK=2538314 KO=435865) 2024-06-13 06:08:32,695 INFO > protectLoginInitiate (OK=662042 KO=60860 ) 2024-06-13 06:08:32,695 INFO > PingOneProtectInitializeCallback (OK=622117 KO=39925 ) 2024-06-13 06:08:32,695 INFO > restLoginUsernamePasswordCallback (OK=585551 KO=76491 ) 2024-06-13 06:08:32,695 INFO > PingOneProtectEvaluationCallback (OK=539833 KO=122209) 2024-06-13 06:08:32,695 INFO > restloginProgressiveProfileCallback (OK=128771 KO=136380) 2024-06-13 06:08:32,695 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 06:08:32,695 INFO > status.find.is(200), but actually found 503 399083 (58.75%) 2024-06-13 06:08:32,695 INFO > PingOneProtectInitializeCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO attribute named 'callbacks' is defined 2024-06-13 06:08:32,695 INFO > restloginProgressiveProfileCallback: Failed to build request: 60860 ( 8.96%) 2024-06-13 06:08:32,695 INFO No attribute named 'callbacks' is defined 2024-06-13 06:08:32,696 INFO > status.find.is(200), but actually found 401 14892 ( 2.19%) 2024-06-13 06:08:32,696 INFO > status.find.is(200), but actually found 502 13637 ( 2.01%) 2024-06-13 06:08:32,696 INFO > jsonPath($.tokenId).find.exists, found nothing 7035 ( 1.04%) 2024-06-13 06:08:32,696 INFO > status.find.is(200), but actually found 400 1213 ( 0.18%) 2024-06-13 06:08:32,696 INFO > j.i.IOException: Premature close 5 ( 0.00%) 2024-06-13 06:08:32,696 INFO 2024-06-13 06:08:32,696 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 06:08:32,696 INFO [##########################################################################]100% 2024-06-13 06:08:32,696 INFO waiting: 0 / active: 0 / done: 722902 2024-06-13 06:08:32,696 INFO ================================================================================ 2024-06-13 06:08:32,696 INFO 2024-06-13 06:08:32,696 INFO Simulation com.forgerock.gatling.Loadtest completed in 3646 seconds 2024-06-13 06:08:32,696 INFO 2024-06-13 06:08:32,696 INFO ---------------------- 2024-06-13 06:08:32,696 INFO [13/Jun/2024 07:05:26] Create tar file login-tree-200.tar.gz 2024-06-13 06:08:32,696 INFO ---------------------- 2024-06-13 06:08:32,696 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-13 06:08:32,696 INFO RESULT_FOLDER=login-tree-200 2024-06-13 06:08:32,696 INFO SIMULATION_PATH=login-tree-200/loadtest-20240613050439256 2024-06-13 06:08:32,696 INFO PWD=/results/gatling/login-tree-200/loadtest-20240613050439256 2024-06-13 06:08:32,696 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-06-13 06:08:32,696 INFO /results/gatling 2024-06-13 06:08:32,696 INFO 2024-06-13 06:08:32,696 INFO ---------------------- 2024-06-13 06:08:32,696 INFO [13/Jun/2024 07:05:26] Update /results/gatling//latest 2024-06-13 06:08:32,696 INFO ---------------------- 2024-06-13 06:08:32,696 INFO 2024-06-13 06:08:32,696 INFO ---------------------- 2024-06-13 06:08:32,696 INFO [13/Jun/2024 07:05:26] Update /results/gatling//latest.tar.gz 2024-06-13 06:08:32,696 INFO ---------------------- 2024-06-13 06:08:32,696 INFO ________________________________________________________________________________ [2024-06-13 06:08:32] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-13 06:08:32,697 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-13 06:08:32,697 DEBUG ----- output ----- 2024-06-13 06:08:32,697 DEBUG 2024-06-13 06:08:32,697 INFO ________________________________________________________________________________ [2024-06-13 06:08:32] 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-13 06:09:36] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-13 06:09:36] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped