--Task-- name: login-tree-100 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-50'] preceding_task: login-tree-50 options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 100, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-13 01:55:16] login-tree-100 pre : N/A ________________________________________________________________________________ 2024-06-13 01:55:16 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-13 01:55:16 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-100/stdout.txt Pod log : /results/orders/login-tree-100 2024-06-13 01:55:16,623 INFO 2024-06-13 01:55:16,624 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 01:55:16,624 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:16,936 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:16,936 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:16,936 DEBUG --- stdout --- 2024-06-13 01:55:16,936 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:16,936 DEBUG --- stderr --- 2024-06-13 01:55:16,936 DEBUG ________________________________________________________________________________ [2024-06-13 01:55:16] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-13 01:55:16,937 INFO 2024-06-13 01:55:16,937 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 01:55:16,937 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:17,255 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:17,256 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:17,256 DEBUG --- stdout --- 2024-06-13 01:55:17,256 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:17,256 DEBUG --- stderr --- 2024-06-13 01:55:17,256 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-13 01:55:17] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-13 01:55:17,257 INFO 2024-06-13 01:55:17,257 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 01:55:17,257 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:17,573 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:17,573 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:17,573 DEBUG --- stdout --- 2024-06-13 01:55:17,573 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:17,573 DEBUG --- stderr --- 2024-06-13 01:55:17,573 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/order.json { "name": "login-tree-100", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.loginProtect@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-100", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-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=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-13 01:55:17] login-tree-100 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-13 01:55:17,575 INFO 2024-06-13 01:55:17,575 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 01:55:17,575 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 01:55:17,808 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 01:55:17,809 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:17,809 DEBUG --- stdout --- 2024-06-13 01:55:17,809 DEBUG WAITING 2024-06-13 01:55:17,809 DEBUG --- stderr --- 2024-06-13 01:55:17,809 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-13 01:55:17] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-13 01:55:17,809 INFO 2024-06-13 01:55:17,809 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 01:55:17,809 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:18,114 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:18,114 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:18,114 DEBUG --- stdout --- 2024-06-13 01:55:18,114 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:18,114 DEBUG --- stderr --- 2024-06-13 01:55:18,114 DEBUG 2024-06-13 01:55:18,115 INFO 2024-06-13 01:55:18,115 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/03_login-tree-100/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-13 01:55:18,115 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 01:55:19,472 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:19,472 DEBUG --- stdout --- 2024-06-13 01:55:19,472 DEBUG 2024-06-13 01:55:19,472 DEBUG --- stderr --- 2024-06-13 01:55:19,472 DEBUG Order has been successfully forwarded to overseer 2024-06-13 01:55:19 - INFO: Check if Gatling simulation started successfully 2024-06-13 01:55:19,473 INFO 2024-06-13 01:55:19,473 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 01:55:19,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 01:55:19,777 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 01:55:19,777 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:19,777 DEBUG --- stdout --- 2024-06-13 01:55:19,777 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 01:55:19,778 DEBUG --- stderr --- 2024-06-13 01:55:19,778 DEBUG 2024-06-13 01:55:19,778 INFO 2024-06-13 01:55:19,778 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-100/stdout.txt | grep "started..." 2024-06-13 01:55:19,778 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 01:55:20,533 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-13 01:55:26,287 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-13 01:55:26,287 INFO [loop_until]: OK (rc = 0) 2024-06-13 01:55:26,287 DEBUG --- stdout --- 2024-06-13 01:55:26,288 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 01:55:26,288 DEBUG --- stderr --- 2024-06-13 01:55:26,288 DEBUG ________________________________________________________________________________ [2024-06-13 01:55:26] login-tree-100 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, 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-13 02:57:36] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-13 02:57:36,264 INFO 2024-06-13 02:57:36,264 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 02:57:36,264 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:36,603 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:36,603 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:36,603 DEBUG --- stdout --- 2024-06-13 02:57:36,603 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:36,603 DEBUG --- stderr --- 2024-06-13 02:57:36,603 DEBUG 2024-06-13 02:57:36,604 INFO 2024-06-13 02:57:36,604 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 02:57:36,604 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 02:57:37,522 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:37,522 DEBUG --- stdout --- 2024-06-13 02:57:37,522 DEBUG 2024-06-13 02:57:37,523 DEBUG --- stderr --- 2024-06-13 02:57:37,523 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-13 02:57:37] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-13 02:57:37 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/order.json 2024-06-13 02:57:37,523 INFO 2024-06-13 02:57:37,523 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 02:57:37,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:37,851 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:37,851 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:37,852 DEBUG --- stdout --- 2024-06-13 02:57:37,852 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:37,852 DEBUG --- stderr --- 2024-06-13 02:57:37,852 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 02:57:37,852 INFO 2024-06-13 02:57:37,852 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 02:57:37,852 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:38,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:38,161 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:38,161 DEBUG --- stdout --- 2024-06-13 02:57:38,161 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:38,161 DEBUG --- stderr --- 2024-06-13 02:57:38,161 DEBUG 2024-06-13 02:57:38,162 INFO 2024-06-13 02:57:38,162 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-100/rc.txt" ]; then echo "/results/orders/login-tree-100/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/rc.txt file found" 2024-06-13 02:57:38,162 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 02:57:38,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 02:57:38,973 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:38,973 DEBUG --- stdout --- 2024-06-13 02:57:38,973 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-06-13 02:57:38,973 DEBUG --- stderr --- 2024-06-13 02:57:38,973 DEBUG 2024-06-13 02:57:38,974 INFO 2024-06-13 02:57:38,974 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 02:57:38,974 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:39,286 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:39,287 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:39,287 DEBUG --- stdout --- 2024-06-13 02:57:39,287 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:39,287 DEBUG --- stderr --- 2024-06-13 02:57:39,287 DEBUG 2024-06-13 02:57:39,287 INFO 2024-06-13 02:57:39,287 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-100/rc.txt 2024-06-13 02:57:40,083 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 02:57:40,083 DEBUG --- stdout --- 2024-06-13 02:57:40,083 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-06-13 02:57:40,083 DEBUG --- stderr --- 2024-06-13 02:57:40,083 DEBUG 2024-06-13 02:57:40,083 INFO - Download file (size 4k) 2024-06-13 02:57:40,084 INFO 2024-06-13 02:57:40,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 02:57:40,084 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:40,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:40,380 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:40,380 DEBUG --- stdout --- 2024-06-13 02:57:40,380 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:40,380 DEBUG --- stderr --- 2024-06-13 02:57:40,380 DEBUG source : /results/orders/login-tree-100/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/rc.txt 2024-06-13 02:57:40,381 INFO 2024-06-13 02:57:40,381 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 02:57:40,381 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:40,702 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:40,702 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:40,702 DEBUG --- stdout --- 2024-06-13 02:57:40,702 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:40,702 DEBUG --- stderr --- 2024-06-13 02:57:40,702 DEBUG 2024-06-13 02:57:40,703 INFO 2024-06-13 02:57:40,703 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/rc.txt 2024-06-13 02:57:40,703 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 02:57:41,437 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:41,437 DEBUG --- stdout --- 2024-06-13 02:57:41,437 DEBUG tar: Removing leading `/' from member names 2024-06-13 02:57:41,437 DEBUG --- stderr --- 2024-06-13 02:57:41,437 DEBUG 2024-06-13 02:57:41,438 INFO 2024-06-13 02:57:41,438 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 02:57:41,438 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:41,753 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:41,753 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:41,753 DEBUG --- stdout --- 2024-06-13 02:57:41,753 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:41,753 DEBUG --- stderr --- 2024-06-13 02:57:41,753 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 02:57:41,753 INFO 2024-06-13 02:57:41,754 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 02:57:41,754 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:42,069 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:42,070 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:42,070 DEBUG --- stdout --- 2024-06-13 02:57:42,070 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:42,070 DEBUG --- stderr --- 2024-06-13 02:57:42,070 DEBUG 2024-06-13 02:57:42,071 INFO 2024-06-13 02:57:42,071 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-100/stderr.txt" ]; then echo "/results/orders/login-tree-100/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stderr.txt file found" 2024-06-13 02:57:42,071 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 02:57:42,883 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 02:57:42,884 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:42,884 DEBUG --- stdout --- 2024-06-13 02:57:42,884 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-06-13 02:57:42,884 DEBUG --- stderr --- 2024-06-13 02:57:42,884 DEBUG 2024-06-13 02:57:42,884 INFO 2024-06-13 02:57:42,884 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 02:57:42,884 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:43,196 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:43,196 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:43,196 DEBUG --- stdout --- 2024-06-13 02:57:43,196 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:43,196 DEBUG --- stderr --- 2024-06-13 02:57:43,196 DEBUG 2024-06-13 02:57:43,196 INFO 2024-06-13 02:57:43,197 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-100/stderr.txt 2024-06-13 02:57:43,999 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 02:57:43,999 DEBUG --- stdout --- 2024-06-13 02:57:44,000 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-06-13 02:57:44,000 DEBUG --- stderr --- 2024-06-13 02:57:44,000 DEBUG 2024-06-13 02:57:44,000 INFO - Download file (size 0k) 2024-06-13 02:57:44,001 INFO 2024-06-13 02:57:44,001 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 02:57:44,001 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:44,304 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:44,305 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:44,305 DEBUG --- stdout --- 2024-06-13 02:57:44,305 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:44,305 DEBUG --- stderr --- 2024-06-13 02:57:44,305 DEBUG source : /results/orders/login-tree-100/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stderr.txt 2024-06-13 02:57:44,305 INFO 2024-06-13 02:57:44,305 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 02:57:44,305 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:44,607 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:44,607 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:44,608 DEBUG --- stdout --- 2024-06-13 02:57:44,608 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:44,608 DEBUG --- stderr --- 2024-06-13 02:57:44,608 DEBUG 2024-06-13 02:57:44,608 INFO 2024-06-13 02:57:44,608 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stderr.txt 2024-06-13 02:57:44,608 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 02:57:45,328 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:45,328 DEBUG --- stdout --- 2024-06-13 02:57:45,328 DEBUG tar: Removing leading `/' from member names 2024-06-13 02:57:45,328 DEBUG --- stderr --- 2024-06-13 02:57:45,328 DEBUG 2024-06-13 02:57:45,329 INFO 2024-06-13 02:57:45,329 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 02:57:45,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:45,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:45,629 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:45,629 DEBUG --- stdout --- 2024-06-13 02:57:45,629 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:45,629 DEBUG --- stderr --- 2024-06-13 02:57:45,629 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 02:57:45,629 INFO 2024-06-13 02:57:45,629 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 02:57:45,629 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:45,941 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:45,941 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:45,941 DEBUG --- stdout --- 2024-06-13 02:57:45,941 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:45,941 DEBUG --- stderr --- 2024-06-13 02:57:45,941 DEBUG 2024-06-13 02:57:45,942 INFO 2024-06-13 02:57:45,942 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-100/stdout.txt" ]; then echo "/results/orders/login-tree-100/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stdout.txt file found" 2024-06-13 02:57:45,942 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 02:57:46,742 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 02:57:46,742 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:46,742 DEBUG --- stdout --- 2024-06-13 02:57:46,743 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-06-13 02:57:46,743 DEBUG --- stderr --- 2024-06-13 02:57:46,743 DEBUG 2024-06-13 02:57:46,743 INFO 2024-06-13 02:57:46,743 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 02:57:46,743 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:47,054 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:47,055 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:47,055 DEBUG --- stdout --- 2024-06-13 02:57:47,055 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:47,055 DEBUG --- stderr --- 2024-06-13 02:57:47,055 DEBUG 2024-06-13 02:57:47,055 INFO 2024-06-13 02:57:47,055 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-100/stdout.txt 2024-06-13 02:57:47,875 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 02:57:47,875 DEBUG --- stdout --- 2024-06-13 02:57:47,875 DEBUG 1080 /results/orders/login-tree-100/stdout.txt 2024-06-13 02:57:47,875 DEBUG --- stderr --- 2024-06-13 02:57:47,875 DEBUG 2024-06-13 02:57:47,875 INFO - Download file (size 1080k) 2024-06-13 02:57:47,876 INFO 2024-06-13 02:57:47,876 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 02:57:47,876 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:48,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:48,190 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:48,190 DEBUG --- stdout --- 2024-06-13 02:57:48,190 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:48,190 DEBUG --- stderr --- 2024-06-13 02:57:48,190 DEBUG source : /results/orders/login-tree-100/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stdout.txt 2024-06-13 02:57:48,191 INFO 2024-06-13 02:57:48,191 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 02:57:48,191 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:48,495 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:48,495 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:48,496 DEBUG --- stdout --- 2024-06-13 02:57:48,496 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:48,496 DEBUG --- stderr --- 2024-06-13 02:57:48,496 DEBUG 2024-06-13 02:57:48,496 INFO 2024-06-13 02:57:48,496 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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stdout.txt 2024-06-13 02:57:48,496 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 02:57:49,615 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:49,616 DEBUG --- stdout --- 2024-06-13 02:57:49,616 DEBUG tar: Removing leading `/' from member names 2024-06-13 02:57:49,616 DEBUG --- stderr --- 2024-06-13 02:57:49,616 DEBUG ________________________________________________________________________________ [2024-06-13 02:57:49] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-06-13 02:57:49,617 INFO 2024-06-13 02:57:49,617 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 02:57:49,618 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:49,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:49,994 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:49,994 DEBUG --- stdout --- 2024-06-13 02:57:49,994 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:49,995 DEBUG --- stderr --- 2024-06-13 02:57:49,995 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 02:57:49,995 INFO 2024-06-13 02:57:49,996 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 02:57:49,996 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:50,319 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:50,319 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:50,319 DEBUG --- stdout --- 2024-06-13 02:57:50,319 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:50,319 DEBUG --- stderr --- 2024-06-13 02:57:50,320 DEBUG 2024-06-13 02:57:50,320 INFO 2024-06-13 02:57:50,320 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-100/cmd.txt" ]; then echo "/results/orders/login-tree-100/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/cmd.txt file found" 2024-06-13 02:57:50,320 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 02:57:51,135 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 02:57:51,135 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:51,135 DEBUG --- stdout --- 2024-06-13 02:57:51,135 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-06-13 02:57:51,135 DEBUG --- stderr --- 2024-06-13 02:57:51,135 DEBUG 2024-06-13 02:57:51,135 INFO 2024-06-13 02:57:51,135 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 02:57:51,135 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:51,442 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:51,442 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:51,442 DEBUG --- stdout --- 2024-06-13 02:57:51,442 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:51,442 DEBUG --- stderr --- 2024-06-13 02:57:51,442 DEBUG 2024-06-13 02:57:51,442 INFO 2024-06-13 02:57:51,443 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-100/cmd.txt 2024-06-13 02:57:52,233 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 02:57:52,233 DEBUG --- stdout --- 2024-06-13 02:57:52,233 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-06-13 02:57:52,233 DEBUG --- stderr --- 2024-06-13 02:57:52,233 DEBUG 2024-06-13 02:57:52,233 INFO - Download file (size 4k) 2024-06-13 02:57:52,234 INFO 2024-06-13 02:57:52,234 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 02:57:52,234 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:52,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:52,597 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:52,598 DEBUG --- stdout --- 2024-06-13 02:57:52,598 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:52,598 DEBUG --- stderr --- 2024-06-13 02:57:52,598 DEBUG source : /results/orders/login-tree-100/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/cmd.txt 2024-06-13 02:57:52,598 INFO 2024-06-13 02:57:52,598 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 02:57:52,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 02:57:52,922 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 02:57:52,923 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:52,923 DEBUG --- stdout --- 2024-06-13 02:57:52,923 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 02:57:52,923 DEBUG --- stderr --- 2024-06-13 02:57:52,923 DEBUG 2024-06-13 02:57:52,923 INFO 2024-06-13 02:57:52,923 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/cmd.txt 2024-06-13 02:57:52,923 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 02:57:53,668 INFO [loop_until]: OK (rc = 0) 2024-06-13 02:57:53,668 DEBUG --- stdout --- 2024-06-13 02:57:53,668 DEBUG tar: Removing leading `/' from member names 2024-06-13 02:57:53,668 DEBUG --- stderr --- 2024-06-13 02:57:53,668 DEBUG 2024-06-13 02:57:53,668 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/cmd.txt is small (1 lines), printing all lines: 2024-06-13 02:57:53,668 DEBUG ----- output ----- 2024-06-13 02:57:53,668 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-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-13 02:57:53,668 INFO ________________________________________________________________________________ [2024-06-13 02:57:53] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-06-13 02:57:53,669 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/rc.txt is small (1 lines), printing all lines: 2024-06-13 02:57:53,669 DEBUG ----- output ----- 2024-06-13 02:57:53,669 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 02:57:53,669 INFO ________________________________________________________________________________ [2024-06-13 02:57:53] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-13 02:57:53,672 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/03_login-tree-100/stdout.txt is 15046 lines): 2024-06-13 02:57:53,672 DEBUG ----- output ----- 2024-06-13 02:57:53,672 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-13 02:57:53,672 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-13 02:57:53,672 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 02:57:53,672 INFO 2024-06-13 02:57:53,672 INFO ================================================================================ 2024-06-13 02:57:53,672 INFO 2024-06-13 02:55:28 5s elapsed 2024-06-13 02:57:53,672 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,672 INFO > Global (OK=141 KO=0 ) 2024-06-13 02:57:53,672 INFO > protectLoginInitiate (OK=40 KO=0 ) 2024-06-13 02:57:53,672 INFO > PingOneProtectInitializeCallback (OK=38 KO=0 ) 2024-06-13 02:57:53,672 INFO > restLoginUsernamePasswordCallback (OK=36 KO=0 ) 2024-06-13 02:57:53,672 INFO > PingOneProtectEvaluationCallback (OK=27 KO=0 ) 2024-06-13 02:57:53,672 INFO 2024-06-13 02:57:53,672 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,672 INFO [- ] 0% 2024-06-13 02:57:53,672 INFO waiting: 361451 / active: 16 / done: 26 2024-06-13 02:57:53,672 INFO ================================================================================ 2024-06-13 02:57:53,672 INFO 2024-06-13 02:57:53,672 INFO 2024-06-13 02:57:53,672 INFO ================================================================================ 2024-06-13 02:57:53,672 INFO 2024-06-13 02:55:33 10s elapsed 2024-06-13 02:57:53,672 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,672 INFO > Global (OK=619 KO=0 ) 2024-06-13 02:57:53,672 INFO > protectLoginInitiate (OK=163 KO=0 ) 2024-06-13 02:57:53,672 INFO > PingOneProtectInitializeCallback (OK=159 KO=0 ) 2024-06-13 02:57:53,672 INFO > restLoginUsernamePasswordCallback (OK=155 KO=0 ) 2024-06-13 02:57:53,672 INFO > PingOneProtectEvaluationCallback (OK=141 KO=0 ) 2024-06-13 02:57:53,672 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-13 02:57:53,672 INFO 2024-06-13 02:57:53,672 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,673 INFO [- ] 0% 2024-06-13 02:57:53,673 INFO waiting: 361326 / active: 26 / done: 141 2024-06-13 02:57:53,673 INFO ================================================================================ 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO ================================================================================ 2024-06-13 02:57:53,673 INFO 2024-06-13 02:55:38 15s elapsed 2024-06-13 02:57:53,673 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,673 INFO > Global (OK=1437 KO=0 ) 2024-06-13 02:57:53,673 INFO > protectLoginInitiate (OK=371 KO=0 ) 2024-06-13 02:57:53,673 INFO > PingOneProtectInitializeCallback (OK=365 KO=0 ) 2024-06-13 02:57:53,673 INFO > restLoginUsernamePasswordCallback (OK=359 KO=0 ) 2024-06-13 02:57:53,673 INFO > PingOneProtectEvaluationCallback (OK=332 KO=0 ) 2024-06-13 02:57:53,673 INFO > restloginProgressiveProfileCallback (OK=10 KO=0 ) 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,673 INFO [- ] 0% 2024-06-13 02:57:53,673 INFO waiting: 361117 / active: 44 / done: 332 2024-06-13 02:57:53,673 INFO ================================================================================ 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO ================================================================================ 2024-06-13 02:57:53,673 INFO 2024-06-13 02:55:43 20s elapsed 2024-06-13 02:57:53,673 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,673 INFO > Global (OK=2587 KO=0 ) 2024-06-13 02:57:53,673 INFO > protectLoginInitiate (OK=660 KO=0 ) 2024-06-13 02:57:53,673 INFO > PingOneProtectInitializeCallback (OK=654 KO=0 ) 2024-06-13 02:57:53,673 INFO > restLoginUsernamePasswordCallback (OK=644 KO=0 ) 2024-06-13 02:57:53,673 INFO > PingOneProtectEvaluationCallback (OK=615 KO=0 ) 2024-06-13 02:57:53,673 INFO > restloginProgressiveProfileCallback (OK=14 KO=0 ) 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,673 INFO [- ] 0% 2024-06-13 02:57:53,673 INFO waiting: 360826 / active: 52 / done: 615 2024-06-13 02:57:53,673 INFO ================================================================================ 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO 2024-06-13 02:57:53,673 INFO ================================================================================ 2024-06-13 02:57:53,673 INFO 2024-06-13 02:55:48 25s elapsed 2024-06-13 02:57:53,673 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,673 INFO > Global (OK=4073 KO=0 ) 2024-06-13 02:57:53,674 INFO > protectLoginInitiate (OK=1034 KO=0 ) 2024-06-13 02:57:53,674 INFO > PingOneProtectInitializeCallback (OK=1026 KO=0 ) 2024-06-13 02:57:53,674 INFO > restLoginUsernamePasswordCallback (OK=1015 KO=0 ) 2024-06-13 02:57:53,674 INFO > PingOneProtectEvaluationCallback (OK=977 KO=0 ) 2024-06-13 02:57:53,674 INFO > restloginProgressiveProfileCallback (OK=21 KO=0 ) 2024-06-13 02:57:53,674 INFO 2024-06-13 02:57:53,674 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,674 INFO [- ] 0% 2024-06-13 02:57:53,674 INFO waiting: 360450 / active: 66 / done: 977 2024-06-13 02:57:53,674 INFO ================================================================================ 2024-06-13 02:57:53,674 INFO 2024-06-13 02:57:53,674 INFO 2024-06-13 02:57:53,674 INFO ================================================================================ 2024-06-13 02:57:53,674 INFO 2024-06-13 02:55:53 30s elapsed 2024-06-13 02:57:53,674 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,674 INFO > Global (OK=5884 KO=0 ) 2024-06-13 02:57:53,674 INFO > protectLoginInitiate (OK=1491 KO=0 ) 2024-06-13 02:57:53,674 INFO > PingOneProtectInitializeCallback (OK=1479 KO=0 ) 2024-06-13 02:57:53,674 INFO > restLoginUsernamePasswordCallback (OK=1467 KO=0 ) 2024-06-13 02:57:53,674 INFO > PingOneProtectEvaluationCallback (OK=1417 KO=0 ) 2024-06-13 02:57:53,674 INFO > restloginProgressiveProfileCallback (OK=30 KO=0 ) 2024-06-13 02:57:53,674 INFO 2024-06-13 02:57:53,674 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,674 INFO [- ] 0% 2024-06-13 02:57:53,674 INFO waiting: 359992 / active: 84 / done: 1417 2024-06-13 02:57:53,674 INFO ================================================================================ 2024-06-13 02:57:53,674 INFO 2024-06-13 02:57:53,674 INFO 2024-06-13 02:57:53,674 INFO ================================================================================ 2024-06-13 02:57:53,674 INFO [...] 2024-06-13 02:57:53,678 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,678 INFO [#########################################################################-] 99% 2024-06-13 02:57:53,678 INFO waiting: 1476 / active: 72 / done: 359945 2024-06-13 02:57:53,678 INFO ================================================================================ 2024-06-13 02:57:53,678 INFO 2024-06-13 02:57:53,678 INFO 2024-06-13 02:57:53,679 INFO ================================================================================ 2024-06-13 02:57:53,679 INFO 2024-06-13 03:55:43 3620s elapsed 2024-06-13 02:57:53,679 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,679 INFO > Global (OK=1461345 KO=38 ) 2024-06-13 02:57:53,679 INFO > protectLoginInitiate (OK=360496 KO=0 ) 2024-06-13 02:57:53,679 INFO > PingOneProtectInitializeCallback (OK=360484 KO=0 ) 2024-06-13 02:57:53,679 INFO > restLoginUsernamePasswordCallback (OK=360471 KO=0 ) 2024-06-13 02:57:53,679 INFO > PingOneProtectEvaluationCallback (OK=360409 KO=19 ) 2024-06-13 02:57:53,679 INFO > restloginProgressiveProfileCallback (OK=19485 KO=19 ) 2024-06-13 02:57:53,679 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 02:57:53,679 INFO > status.find.is(200), but actually found 401 19 (50.00%) 2024-06-13 02:57:53,679 INFO > jsonPath($.tokenId).find.exists, found nothing 19 (50.00%) 2024-06-13 02:57:53,679 INFO 2024-06-13 02:57:53,679 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,679 INFO [#########################################################################-] 99% 2024-06-13 02:57:53,679 INFO waiting: 987 / active: 84 / done: 360422 2024-06-13 02:57:53,679 INFO ================================================================================ 2024-06-13 02:57:53,679 INFO 2024-06-13 02:57:53,679 INFO 2024-06-13 02:57:53,679 INFO ================================================================================ 2024-06-13 02:57:53,679 INFO 2024-06-13 03:55:48 3625s elapsed 2024-06-13 02:57:53,679 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,679 INFO > Global (OK=1463423 KO=38 ) 2024-06-13 02:57:53,679 INFO > protectLoginInitiate (OK=360999 KO=0 ) 2024-06-13 02:57:53,679 INFO > PingOneProtectInitializeCallback (OK=360989 KO=0 ) 2024-06-13 02:57:53,679 INFO > restLoginUsernamePasswordCallback (OK=360979 KO=0 ) 2024-06-13 02:57:53,679 INFO > PingOneProtectEvaluationCallback (OK=360909 KO=19 ) 2024-06-13 02:57:53,679 INFO > restloginProgressiveProfileCallback (OK=19547 KO=19 ) 2024-06-13 02:57:53,679 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 02:57:53,679 INFO > status.find.is(200), but actually found 401 19 (50.00%) 2024-06-13 02:57:53,679 INFO > jsonPath($.tokenId).find.exists, found nothing 19 (50.00%) 2024-06-13 02:57:53,679 INFO 2024-06-13 02:57:53,679 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,679 INFO [#########################################################################-] 99% 2024-06-13 02:57:53,679 INFO waiting: 485 / active: 83 / done: 360925 2024-06-13 02:57:53,679 INFO ================================================================================ 2024-06-13 02:57:53,679 INFO 2024-06-13 02:57:53,679 INFO 2024-06-13 02:57:53,679 INFO ================================================================================ 2024-06-13 02:57:53,679 INFO 2024-06-13 03:55:53 3630s elapsed 2024-06-13 02:57:53,680 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,680 INFO > Global (OK=1465394 KO=38 ) 2024-06-13 02:57:53,680 INFO > protectLoginInitiate (OK=361481 KO=0 ) 2024-06-13 02:57:53,680 INFO > PingOneProtectInitializeCallback (OK=361469 KO=0 ) 2024-06-13 02:57:53,680 INFO > restLoginUsernamePasswordCallback (OK=361456 KO=0 ) 2024-06-13 02:57:53,680 INFO > PingOneProtectEvaluationCallback (OK=361394 KO=19 ) 2024-06-13 02:57:53,680 INFO > restloginProgressiveProfileCallback (OK=19594 KO=19 ) 2024-06-13 02:57:53,680 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 02:57:53,680 INFO > status.find.is(200), but actually found 401 19 (50.00%) 2024-06-13 02:57:53,680 INFO > jsonPath($.tokenId).find.exists, found nothing 19 (50.00%) 2024-06-13 02:57:53,680 INFO 2024-06-13 02:57:53,680 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,680 INFO [#########################################################################-] 99% 2024-06-13 02:57:53,680 INFO waiting: 0 / active: 82 / done: 361411 2024-06-13 02:57:53,680 INFO ================================================================================ 2024-06-13 02:57:53,680 INFO 2024-06-13 02:57:53,680 INFO 2024-06-13 02:57:53,680 INFO ================================================================================ 2024-06-13 02:57:53,680 INFO 2024-06-13 03:55:54 3631s elapsed 2024-06-13 02:57:53,680 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 02:57:53,680 INFO > Global (OK=1465557 KO=38 ) 2024-06-13 02:57:53,680 INFO > protectLoginInitiate (OK=361493 KO=0 ) 2024-06-13 02:57:53,680 INFO > PingOneProtectInitializeCallback (OK=361493 KO=0 ) 2024-06-13 02:57:53,680 INFO > restLoginUsernamePasswordCallback (OK=361493 KO=0 ) 2024-06-13 02:57:53,680 INFO > PingOneProtectEvaluationCallback (OK=361474 KO=19 ) 2024-06-13 02:57:53,680 INFO > restloginProgressiveProfileCallback (OK=19604 KO=19 ) 2024-06-13 02:57:53,680 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 02:57:53,680 INFO > status.find.is(200), but actually found 401 19 (50.00%) 2024-06-13 02:57:53,680 INFO > jsonPath($.tokenId).find.exists, found nothing 19 (50.00%) 2024-06-13 02:57:53,680 INFO 2024-06-13 02:57:53,680 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 02:57:53,680 INFO [##########################################################################]100% 2024-06-13 02:57:53,680 INFO waiting: 0 / active: 0 / done: 361493 2024-06-13 02:57:53,680 INFO ================================================================================ 2024-06-13 02:57:53,680 INFO 2024-06-13 02:57:53,680 INFO Simulation com.forgerock.gatling.Loadtest completed in 3631 seconds 2024-06-13 02:57:53,680 INFO 2024-06-13 02:57:53,680 INFO ---------------------- 2024-06-13 02:57:53,680 INFO [13/Jun/2024 03:55:54] Create tar file login-tree-100.tar.gz 2024-06-13 02:57:53,680 INFO ---------------------- 2024-06-13 02:57:53,680 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-13 02:57:53,681 INFO RESULT_FOLDER=login-tree-100 2024-06-13 02:57:53,681 INFO SIMULATION_PATH=login-tree-100/loadtest-20240613015523005 2024-06-13 02:57:53,681 INFO PWD=/results/gatling/login-tree-100/loadtest-20240613015523005 2024-06-13 02:57:53,681 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-06-13 02:57:53,681 INFO /results/gatling 2024-06-13 02:57:53,681 INFO 2024-06-13 02:57:53,681 INFO ---------------------- 2024-06-13 02:57:53,681 INFO [13/Jun/2024 03:55:54] Update /results/gatling//latest 2024-06-13 02:57:53,681 INFO ---------------------- 2024-06-13 02:57:53,681 INFO 2024-06-13 02:57:53,681 INFO ---------------------- 2024-06-13 02:57:53,681 INFO [13/Jun/2024 03:55:54] Update /results/gatling//latest.tar.gz 2024-06-13 02:57:53,681 INFO ---------------------- 2024-06-13 02:57:53,681 INFO ________________________________________________________________________________ [2024-06-13 02:57:53] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-13 02:57:53,681 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stderr.txt is small (0 lines), printing all lines: 2024-06-13 02:57:53,681 DEBUG ----- output ----- 2024-06-13 02:57:53,681 DEBUG 2024-06-13 02:57:53,681 INFO ________________________________________________________________________________ [2024-06-13 02:57:53] login-tree-100 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-13 02:57:53] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-13 02:57:53] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped