--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-150'] preceding_task: login-tree-150 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-23 06:51:13] login-tree-200 pre : N/A ________________________________________________________________________________ 2024-06-23 06:51:13 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-23 06:51:13 - 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-23 06:51:13,099 INFO 2024-06-23 06:51:13,099 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-23 06:51:13,099 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:13,410 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:13,411 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:13,411 DEBUG --- stdout --- 2024-06-23 06:51:13,411 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:13,411 DEBUG --- stderr --- 2024-06-23 06:51:13,411 DEBUG ________________________________________________________________________________ [2024-06-23 06:51:13] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-23 06:51:13,412 INFO 2024-06-23 06:51:13,412 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 06:51:13,412 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:13,737 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:13,737 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:13,737 DEBUG --- stdout --- 2024-06-23 06:51:13,738 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:13,738 DEBUG --- stderr --- 2024-06-23 06:51:13,738 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-p1" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-23 06:51:13] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-23 06:51:13,739 INFO 2024-06-23 06:51:13,739 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-23 06:51:13,739 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:14,064 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:14,064 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:14,064 DEBUG --- stdout --- 2024-06-23 06:51:14,064 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:14,064 DEBUG --- stderr --- 2024-06-23 06:51:14,064 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_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-p1\" --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-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login_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-23 06:51:14] 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-23 06:51:14,067 INFO 2024-06-23 06:51:14,067 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-23 06:51:14,067 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 06:51:14,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 06:51:14,335 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:14,335 DEBUG --- stdout --- 2024-06-23 06:51:14,335 DEBUG WAITING 2024-06-23 06:51:14,335 DEBUG --- stderr --- 2024-06-23 06:51:14,335 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 31 0 --:--:-- --:--:-- --:--:-- 31 ________________________________________________________________________________ [2024-06-23 06:51:14] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-23 06:51:14,335 INFO 2024-06-23 06:51:14,336 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-23 06:51:14,336 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:14,647 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:14,647 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:14,647 DEBUG --- stdout --- 2024-06-23 06:51:14,647 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:14,647 DEBUG --- stderr --- 2024-06-23 06:51:14,647 DEBUG 2024-06-23 06:51:14,648 INFO 2024-06-23 06:51:14,648 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/04_login-tree-200/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-23 06:51:14,648 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 06:51:16,006 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:16,006 DEBUG --- stdout --- 2024-06-23 06:51:16,006 DEBUG 2024-06-23 06:51:16,006 DEBUG --- stderr --- 2024-06-23 06:51:16,007 DEBUG Order has been successfully forwarded to overseer 2024-06-23 06:51:16 - INFO: Check if Gatling simulation started successfully 2024-06-23 06:51:16,007 INFO 2024-06-23 06:51:16,007 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-23 06:51:16,007 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 06:51:16,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 06:51:16,334 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:16,334 DEBUG --- stdout --- 2024-06-23 06:51:16,334 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 06:51:16,334 DEBUG --- stderr --- 2024-06-23 06:51:16,334 DEBUG 2024-06-23 06:51:16,335 INFO 2024-06-23 06:51:16,335 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-200/stdout.txt | grep "started..." 2024-06-23 06:51:16,335 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-23 06:51:17,073 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-23 06:51:22,862 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-23 06:51:22,863 INFO [loop_until]: OK (rc = 0) 2024-06-23 06:51:22,865 DEBUG --- stdout --- 2024-06-23 06:51:22,865 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 06:51:22,865 DEBUG --- stderr --- 2024-06-23 06:51:22,865 DEBUG ________________________________________________________________________________ [2024-06-23 06:51:22] 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 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-23 07:53:32] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 07:53:32,883 INFO 2024-06-23 07:53:32,883 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-23 07:53:32,883 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:33,211 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:33,211 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:33,211 DEBUG --- stdout --- 2024-06-23 07:53:33,211 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:33,211 DEBUG --- stderr --- 2024-06-23 07:53:33,211 DEBUG 2024-06-23 07:53:33,211 INFO 2024-06-23 07:53:33,211 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-23 07:53:33,211 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 07:53:33,993 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:33,993 DEBUG --- stdout --- 2024-06-23 07:53:33,993 DEBUG 2024-06-23 07:53:33,993 DEBUG --- stderr --- 2024-06-23 07:53:33,993 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 07:53:33] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 07:53:33 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/order.json 2024-06-23 07:53:33,994 INFO 2024-06-23 07:53:33,994 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-23 07:53:33,994 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:34,297 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:34,297 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:34,297 DEBUG --- stdout --- 2024-06-23 07:53:34,297 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:34,297 DEBUG --- stderr --- 2024-06-23 07:53:34,297 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 07:53:34,298 INFO 2024-06-23 07:53:34,298 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-23 07:53:34,298 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:34,605 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:34,605 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:34,605 DEBUG --- stdout --- 2024-06-23 07:53:34,605 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:34,605 DEBUG --- stderr --- 2024-06-23 07:53:34,605 DEBUG 2024-06-23 07:53:34,606 INFO 2024-06-23 07:53:34,606 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-23 07:53:34,606 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 07:53:35,410 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 07:53:35,410 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:35,410 DEBUG --- stdout --- 2024-06-23 07:53:35,410 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-06-23 07:53:35,410 DEBUG --- stderr --- 2024-06-23 07:53:35,410 DEBUG 2024-06-23 07:53:35,411 INFO 2024-06-23 07:53:35,411 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-23 07:53:35,411 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:35,735 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:35,735 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:35,736 DEBUG --- stdout --- 2024-06-23 07:53:35,736 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:35,736 DEBUG --- stderr --- 2024-06-23 07:53:35,736 DEBUG 2024-06-23 07:53:35,736 INFO 2024-06-23 07:53:35,736 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-200/rc.txt 2024-06-23 07:53:36,563 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 07:53:36,563 DEBUG --- stdout --- 2024-06-23 07:53:36,563 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-06-23 07:53:36,563 DEBUG --- stderr --- 2024-06-23 07:53:36,563 DEBUG 2024-06-23 07:53:36,563 INFO - Download file (size 4k) 2024-06-23 07:53:36,564 INFO 2024-06-23 07:53:36,564 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-23 07:53:36,564 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:36,876 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:36,876 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:36,876 DEBUG --- stdout --- 2024-06-23 07:53:36,876 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:36,876 DEBUG --- stderr --- 2024-06-23 07:53:36,876 DEBUG source : /results/orders/login-tree-200/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt 2024-06-23 07:53:36,877 INFO 2024-06-23 07:53:36,877 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-23 07:53:36,877 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:37,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:37,186 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:37,186 DEBUG --- stdout --- 2024-06-23 07:53:37,187 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:37,187 DEBUG --- stderr --- 2024-06-23 07:53:37,187 DEBUG 2024-06-23 07:53:37,187 INFO 2024-06-23 07:53:37,187 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-200/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt 2024-06-23 07:53:37,187 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 07:53:37,945 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:37,945 DEBUG --- stdout --- 2024-06-23 07:53:37,945 DEBUG tar: Removing leading `/' from member names 2024-06-23 07:53:37,945 DEBUG --- stderr --- 2024-06-23 07:53:37,945 DEBUG 2024-06-23 07:53:37,946 INFO 2024-06-23 07:53:37,946 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-23 07:53:37,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:38,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:38,258 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:38,258 DEBUG --- stdout --- 2024-06-23 07:53:38,259 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:38,259 DEBUG --- stderr --- 2024-06-23 07:53:38,259 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 07:53:38,259 INFO 2024-06-23 07:53:38,259 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:38,259 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:38,578 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:38,579 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:38,579 DEBUG --- stdout --- 2024-06-23 07:53:38,579 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:38,579 DEBUG --- stderr --- 2024-06-23 07:53:38,579 DEBUG 2024-06-23 07:53:38,579 INFO 2024-06-23 07:53:38,579 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-23 07:53:38,579 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 07:53:39,406 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 07:53:39,407 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:39,407 DEBUG --- stdout --- 2024-06-23 07:53:39,407 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-06-23 07:53:39,407 DEBUG --- stderr --- 2024-06-23 07:53:39,407 DEBUG 2024-06-23 07:53:39,407 INFO 2024-06-23 07:53:39,407 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-23 07:53:39,407 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:39,739 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:39,739 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:39,739 DEBUG --- stdout --- 2024-06-23 07:53:39,740 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:39,740 DEBUG --- stderr --- 2024-06-23 07:53:39,740 DEBUG 2024-06-23 07:53:39,740 INFO 2024-06-23 07:53:39,740 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-200/stderr.txt 2024-06-23 07:53:40,557 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 07:53:40,557 DEBUG --- stdout --- 2024-06-23 07:53:40,557 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-06-23 07:53:40,557 DEBUG --- stderr --- 2024-06-23 07:53:40,557 DEBUG 2024-06-23 07:53:40,557 INFO - Download file (size 0k) 2024-06-23 07:53:40,558 INFO 2024-06-23 07:53:40,558 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-23 07:53:40,558 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:40,881 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:40,881 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:40,881 DEBUG --- stdout --- 2024-06-23 07:53:40,881 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:40,881 DEBUG --- stderr --- 2024-06-23 07:53:40,881 DEBUG source : /results/orders/login-tree-200/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt 2024-06-23 07:53:40,881 INFO 2024-06-23 07:53:40,881 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-23 07:53:40,881 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:41,179 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:41,180 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:41,180 DEBUG --- stdout --- 2024-06-23 07:53:41,180 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:41,180 DEBUG --- stderr --- 2024-06-23 07:53:41,180 DEBUG 2024-06-23 07:53:41,180 INFO 2024-06-23 07:53:41,180 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-200/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt 2024-06-23 07:53:41,180 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 07:53:41,905 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:41,905 DEBUG --- stdout --- 2024-06-23 07:53:41,905 DEBUG tar: Removing leading `/' from member names 2024-06-23 07:53:41,905 DEBUG --- stderr --- 2024-06-23 07:53:41,905 DEBUG 2024-06-23 07:53:41,906 INFO 2024-06-23 07:53:41,906 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:41,906 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:42,209 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:42,209 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:42,209 DEBUG --- stdout --- 2024-06-23 07:53:42,210 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:42,210 DEBUG --- stderr --- 2024-06-23 07:53:42,210 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 07:53:42,210 INFO 2024-06-23 07:53:42,210 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-23 07:53:42,210 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:42,513 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:42,513 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:42,513 DEBUG --- stdout --- 2024-06-23 07:53:42,513 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:42,513 DEBUG --- stderr --- 2024-06-23 07:53:42,513 DEBUG 2024-06-23 07:53:42,514 INFO 2024-06-23 07:53:42,514 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-23 07:53:42,514 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 07:53:43,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 07:53:43,318 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:43,319 DEBUG --- stdout --- 2024-06-23 07:53:43,319 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-06-23 07:53:43,319 DEBUG --- stderr --- 2024-06-23 07:53:43,319 DEBUG 2024-06-23 07:53:43,320 INFO 2024-06-23 07:53:43,320 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-23 07:53:43,320 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:43,693 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:43,694 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:43,694 DEBUG --- stdout --- 2024-06-23 07:53:43,694 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:43,694 DEBUG --- stderr --- 2024-06-23 07:53:43,694 DEBUG 2024-06-23 07:53:43,694 INFO 2024-06-23 07:53:43,694 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-200/stdout.txt 2024-06-23 07:53:44,502 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 07:53:44,502 DEBUG --- stdout --- 2024-06-23 07:53:44,502 DEBUG 2852 /results/orders/login-tree-200/stdout.txt 2024-06-23 07:53:44,502 DEBUG --- stderr --- 2024-06-23 07:53:44,502 DEBUG 2024-06-23 07:53:44,502 INFO - Download file (size 2852k) 2024-06-23 07:53:44,502 INFO 2024-06-23 07:53:44,503 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-23 07:53:44,503 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:44,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:44,834 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:44,834 DEBUG --- stdout --- 2024-06-23 07:53:44,834 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:44,834 DEBUG --- stderr --- 2024-06-23 07:53:44,834 DEBUG source : /results/orders/login-tree-200/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stdout.txt 2024-06-23 07:53:44,834 INFO 2024-06-23 07:53:44,834 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 07:53:44,834 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:45,160 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:45,160 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:45,160 DEBUG --- stdout --- 2024-06-23 07:53:45,161 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:45,161 DEBUG --- stderr --- 2024-06-23 07:53:45,161 DEBUG 2024-06-23 07:53:45,161 INFO 2024-06-23 07:53:45,161 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-200/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stdout.txt 2024-06-23 07:53:45,161 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 07:53:46,354 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:46,354 DEBUG --- stdout --- 2024-06-23 07:53:46,354 DEBUG tar: Removing leading `/' from member names 2024-06-23 07:53:46,354 DEBUG --- stderr --- 2024-06-23 07:53:46,354 DEBUG ________________________________________________________________________________ [2024-06-23 07:53:46] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 07:53:46,355 INFO 2024-06-23 07:53:46,355 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-23 07:53:46,355 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:46,682 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:46,682 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:46,682 DEBUG --- stdout --- 2024-06-23 07:53:46,682 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:46,682 DEBUG --- stderr --- 2024-06-23 07:53:46,682 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 07:53:46,683 INFO 2024-06-23 07:53:46,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-23 07:53:46,683 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:47,073 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:47,074 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:47,074 DEBUG --- stdout --- 2024-06-23 07:53:47,074 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:47,074 DEBUG --- stderr --- 2024-06-23 07:53:47,074 DEBUG 2024-06-23 07:53:47,075 INFO 2024-06-23 07:53:47,075 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-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-23 07:53:47,075 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 07:53:47,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 07:53:47,900 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:47,900 DEBUG --- stdout --- 2024-06-23 07:53:47,900 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-06-23 07:53:47,900 DEBUG --- stderr --- 2024-06-23 07:53:47,900 DEBUG 2024-06-23 07:53:47,900 INFO 2024-06-23 07:53:47,900 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-23 07:53:47,900 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:48,225 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:48,226 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:48,226 DEBUG --- stdout --- 2024-06-23 07:53:48,226 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:48,226 DEBUG --- stderr --- 2024-06-23 07:53:48,226 DEBUG 2024-06-23 07:53:48,226 INFO 2024-06-23 07:53:48,227 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-200/cmd.txt 2024-06-23 07:53:49,028 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 07:53:49,028 DEBUG --- stdout --- 2024-06-23 07:53:49,028 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-06-23 07:53:49,028 DEBUG --- stderr --- 2024-06-23 07:53:49,028 DEBUG 2024-06-23 07:53:49,029 INFO - Download file (size 4k) 2024-06-23 07:53:49,029 INFO 2024-06-23 07:53:49,029 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-23 07:53:49,029 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:49,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:49,334 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:49,334 DEBUG --- stdout --- 2024-06-23 07:53:49,334 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:49,334 DEBUG --- stderr --- 2024-06-23 07:53:49,334 DEBUG source : /results/orders/login-tree-200/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt 2024-06-23 07:53:49,335 INFO 2024-06-23 07:53:49,335 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-23 07:53:49,335 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 07:53:49,657 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 07:53:49,657 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:49,657 DEBUG --- stdout --- 2024-06-23 07:53:49,657 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 07:53:49,658 DEBUG --- stderr --- 2024-06-23 07:53:49,658 DEBUG 2024-06-23 07:53:49,658 INFO 2024-06-23 07:53:49,658 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-200/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt 2024-06-23 07:53:49,658 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 07:53:50,405 INFO [loop_until]: OK (rc = 0) 2024-06-23 07:53:50,405 DEBUG --- stdout --- 2024-06-23 07:53:50,405 DEBUG tar: Removing leading `/' from member names 2024-06-23 07:53:50,405 DEBUG --- stderr --- 2024-06-23 07:53:50,405 DEBUG 2024-06-23 07:53:50,406 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/cmd.txt is small (1 lines), printing all lines: 2024-06-23 07:53:50,406 DEBUG ----- output ----- 2024-06-23 07:53:50,406 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-p1" --results-folder /results/gatling/login-tree-200 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 07:53:50,406 INFO ________________________________________________________________________________ [2024-06-23 07:53:50] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 07:53:50,406 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/rc.txt is small (1 lines), printing all lines: 2024-06-23 07:53:50,406 DEBUG ----- output ----- 2024-06-23 07:53:50,406 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 07:53:50,406 INFO ________________________________________________________________________________ [2024-06-23 07:53:50] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 07:53:50,413 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/04_login-tree-200/stdout.txt is 30640 lines): 2024-06-23 07:53:50,413 DEBUG ----- output ----- 2024-06-23 07:53:50,413 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 07:53:50,413 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 07:53:50,413 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 07:53:50,413 INFO 2024-06-23 07:53:50,413 INFO ================================================================================ 2024-06-23 07:53:50,413 INFO 2024-06-23 07:51:25 5s elapsed 2024-06-23 07:53:50,413 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,413 INFO > Global (OK=325 KO=0 ) 2024-06-23 07:53:50,413 INFO > protectLoginInitiate (OK=82 KO=0 ) 2024-06-23 07:53:50,413 INFO > PingOneProtectInitializeCallback (OK=81 KO=0 ) 2024-06-23 07:53:50,413 INFO > restLoginUsernamePasswordCallback (OK=79 KO=0 ) 2024-06-23 07:53:50,413 INFO > PingOneProtectEvaluationCallback (OK=70 KO=0 ) 2024-06-23 07:53:50,413 INFO > restloginProgressiveProfileCallback (OK=13 KO=0 ) 2024-06-23 07:53:50,413 INFO 2024-06-23 07:53:50,413 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,413 INFO [- ] 0% 2024-06-23 07:53:50,413 INFO waiting: 722433 / active: 14 / done: 70 2024-06-23 07:53:50,413 INFO ================================================================================ 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO ================================================================================ 2024-06-23 07:53:50,414 INFO 2024-06-23 07:51:30 10s elapsed 2024-06-23 07:53:50,414 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,414 INFO > Global (OK=1341 KO=0 ) 2024-06-23 07:53:50,414 INFO > protectLoginInitiate (OK=331 KO=0 ) 2024-06-23 07:53:50,414 INFO > PingOneProtectInitializeCallback (OK=328 KO=0 ) 2024-06-23 07:53:50,414 INFO > restLoginUsernamePasswordCallback (OK=324 KO=0 ) 2024-06-23 07:53:50,414 INFO > PingOneProtectEvaluationCallback (OK=308 KO=0 ) 2024-06-23 07:53:50,414 INFO > restloginProgressiveProfileCallback (OK=50 KO=0 ) 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,414 INFO [- ] 0% 2024-06-23 07:53:50,414 INFO waiting: 722182 / active: 30 / done: 305 2024-06-23 07:53:50,414 INFO ================================================================================ 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO ================================================================================ 2024-06-23 07:53:50,414 INFO 2024-06-23 07:51:35 15s elapsed 2024-06-23 07:53:50,414 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,414 INFO > Global (OK=3064 KO=0 ) 2024-06-23 07:53:50,414 INFO > protectLoginInitiate (OK=747 KO=0 ) 2024-06-23 07:53:50,414 INFO > PingOneProtectInitializeCallback (OK=743 KO=0 ) 2024-06-23 07:53:50,414 INFO > restLoginUsernamePasswordCallback (OK=737 KO=0 ) 2024-06-23 07:53:50,414 INFO > PingOneProtectEvaluationCallback (OK=710 KO=0 ) 2024-06-23 07:53:50,414 INFO > restloginProgressiveProfileCallback (OK=127 KO=0 ) 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,414 INFO [- ] 0% 2024-06-23 07:53:50,414 INFO waiting: 721765 / active: 44 / done: 708 2024-06-23 07:53:50,414 INFO ================================================================================ 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO 2024-06-23 07:53:50,414 INFO ================================================================================ 2024-06-23 07:53:50,414 INFO 2024-06-23 07:51:40 20s elapsed 2024-06-23 07:53:50,414 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,415 INFO > Global (OK=5485 KO=0 ) 2024-06-23 07:53:50,415 INFO > protectLoginInitiate (OK=1329 KO=0 ) 2024-06-23 07:53:50,415 INFO > PingOneProtectInitializeCallback (OK=1323 KO=0 ) 2024-06-23 07:53:50,415 INFO > restLoginUsernamePasswordCallback (OK=1316 KO=0 ) 2024-06-23 07:53:50,415 INFO > PingOneProtectEvaluationCallback (OK=1279 KO=0 ) 2024-06-23 07:53:50,415 INFO > restloginProgressiveProfileCallback (OK=238 KO=0 ) 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,415 INFO [- ] 0% 2024-06-23 07:53:50,415 INFO waiting: 721181 / active: 61 / done: 1275 2024-06-23 07:53:50,415 INFO ================================================================================ 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO ================================================================================ 2024-06-23 07:53:50,415 INFO 2024-06-23 07:51:45 25s elapsed 2024-06-23 07:53:50,415 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,415 INFO > Global (OK=8583 KO=0 ) 2024-06-23 07:53:50,415 INFO > protectLoginInitiate (OK=2077 KO=0 ) 2024-06-23 07:53:50,415 INFO > PingOneProtectInitializeCallback (OK=2071 KO=0 ) 2024-06-23 07:53:50,415 INFO > restLoginUsernamePasswordCallback (OK=2061 KO=0 ) 2024-06-23 07:53:50,415 INFO > PingOneProtectEvaluationCallback (OK=2016 KO=0 ) 2024-06-23 07:53:50,415 INFO > restloginProgressiveProfileCallback (OK=358 KO=0 ) 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,415 INFO [- ] 0% 2024-06-23 07:53:50,415 INFO waiting: 720431 / active: 71 / done: 2015 2024-06-23 07:53:50,415 INFO ================================================================================ 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO ================================================================================ 2024-06-23 07:53:50,415 INFO 2024-06-23 07:51:50 30s elapsed 2024-06-23 07:53:50,415 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,415 INFO > Global (OK=12372 KO=0 ) 2024-06-23 07:53:50,415 INFO > protectLoginInitiate (OK=2994 KO=0 ) 2024-06-23 07:53:50,415 INFO > PingOneProtectInitializeCallback (OK=2982 KO=0 ) 2024-06-23 07:53:50,415 INFO > restLoginUsernamePasswordCallback (OK=2969 KO=0 ) 2024-06-23 07:53:50,415 INFO > PingOneProtectEvaluationCallback (OK=2915 KO=0 ) 2024-06-23 07:53:50,415 INFO > restloginProgressiveProfileCallback (OK=512 KO=0 ) 2024-06-23 07:53:50,415 INFO 2024-06-23 07:53:50,415 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,415 INFO [- ] 0% 2024-06-23 07:53:50,415 INFO waiting: 719515 / active: 91 / done: 2911 2024-06-23 07:53:50,415 INFO ================================================================================ 2024-06-23 07:53:50,416 INFO 2024-06-23 07:53:50,416 INFO 2024-06-23 07:53:50,416 INFO [...] 2024-06-23 07:53:50,427 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,427 INFO > Global (OK=3048733 KO=277 ) 2024-06-23 07:53:50,427 INFO > protectLoginInitiate (OK=721527 KO=2 ) 2024-06-23 07:53:50,427 INFO > PingOneProtectInitializeCallback (OK=721517 KO=4 ) 2024-06-23 07:53:50,427 INFO > restLoginUsernamePasswordCallback (OK=721507 KO=3 ) 2024-06-23 07:53:50,427 INFO > PingOneProtectEvaluationCallback (OK=721321 KO=132 ) 2024-06-23 07:53:50,427 INFO > restloginProgressiveProfileCallback (OK=162861 KO=136 ) 2024-06-23 07:53:50,427 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 07:53:50,427 INFO > status.find.is(200), but actually found 401 135 (47.37%) 2024-06-23 07:53:50,427 INFO > jsonPath($.tokenId).find.exists, found nothing 132 (46.32%) 2024-06-23 07:53:50,427 INFO > status.find.is(200), but actually found 502 10 ( 3.51%) 2024-06-23 07:53:50,427 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,427 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,427 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,427 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,427 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,428 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,428 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 0.70%) 2024-06-23 07:53:50,428 INFO No attribute named 'callbacks' is defined 2024-06-23 07:53:50,428 INFO 2024-06-23 07:53:50,428 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,428 INFO [#########################################################################-] 99% 2024-06-23 07:53:50,428 INFO waiting: 979 / active: 90 / done: 721448 2024-06-23 07:53:50,428 INFO ================================================================================ 2024-06-23 07:53:50,428 INFO 2024-06-23 07:53:50,428 INFO 2024-06-23 07:53:50,428 INFO ================================================================================ 2024-06-23 07:53:50,428 INFO 2024-06-23 08:51:50 3630s elapsed 2024-06-23 07:53:50,428 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,428 INFO > Global (OK=3052914 KO=277 ) 2024-06-23 07:53:50,428 INFO > protectLoginInitiate (OK=722508 KO=2 ) 2024-06-23 07:53:50,428 INFO > PingOneProtectInitializeCallback (OK=722494 KO=4 ) 2024-06-23 07:53:50,428 INFO > restLoginUsernamePasswordCallback (OK=722486 KO=3 ) 2024-06-23 07:53:50,428 INFO > PingOneProtectEvaluationCallback (OK=722306 KO=132 ) 2024-06-23 07:53:50,428 INFO > restloginProgressiveProfileCallback (OK=163120 KO=136 ) 2024-06-23 07:53:50,428 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 07:53:50,428 INFO > status.find.is(200), but actually found 401 135 (47.37%) 2024-06-23 07:53:50,428 INFO > jsonPath($.tokenId).find.exists, found nothing 132 (46.32%) 2024-06-23 07:53:50,428 INFO > status.find.is(200), but actually found 502 10 ( 3.51%) 2024-06-23 07:53:50,428 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,428 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,428 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,428 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,428 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,428 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,428 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 0.70%) 2024-06-23 07:53:50,428 INFO No attribute named 'callbacks' is defined 2024-06-23 07:53:50,428 INFO 2024-06-23 07:53:50,428 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,428 INFO [#########################################################################-] 99% 2024-06-23 07:53:50,429 INFO waiting: 0 / active: 81 / done: 722436 2024-06-23 07:53:50,429 INFO ================================================================================ 2024-06-23 07:53:50,429 INFO 2024-06-23 07:53:50,429 INFO 2024-06-23 07:53:50,429 INFO ================================================================================ 2024-06-23 07:53:50,429 INFO 2024-06-23 08:51:50 3630s elapsed 2024-06-23 07:53:50,429 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 07:53:50,429 INFO > Global (OK=3053063 KO=277 ) 2024-06-23 07:53:50,429 INFO > protectLoginInitiate (OK=722515 KO=2 ) 2024-06-23 07:53:50,429 INFO > PingOneProtectInitializeCallback (OK=722511 KO=4 ) 2024-06-23 07:53:50,429 INFO > restLoginUsernamePasswordCallback (OK=722512 KO=3 ) 2024-06-23 07:53:50,429 INFO > PingOneProtectEvaluationCallback (OK=722383 KO=132 ) 2024-06-23 07:53:50,429 INFO > restloginProgressiveProfileCallback (OK=163142 KO=136 ) 2024-06-23 07:53:50,429 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 07:53:50,429 INFO > status.find.is(200), but actually found 401 135 (47.37%) 2024-06-23 07:53:50,429 INFO > jsonPath($.tokenId).find.exists, found nothing 132 (46.32%) 2024-06-23 07:53:50,429 INFO > status.find.is(200), but actually found 502 10 ( 3.51%) 2024-06-23 07:53:50,429 INFO > PingOneProtectInitializeCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,429 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,429 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,429 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,429 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 2 ( 0.70%) 2024-06-23 07:53:50,429 INFO attribute named 'callbacks' is defined 2024-06-23 07:53:50,429 INFO > restloginProgressiveProfileCallback: Failed to build request: 2 ( 0.70%) 2024-06-23 07:53:50,429 INFO No attribute named 'callbacks' is defined 2024-06-23 07:53:50,429 INFO 2024-06-23 07:53:50,429 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-23 07:53:50,429 INFO [##########################################################################]100% 2024-06-23 07:53:50,429 INFO waiting: 0 / active: 0 / done: 722517 2024-06-23 07:53:50,429 INFO ================================================================================ 2024-06-23 07:53:50,429 INFO 2024-06-23 07:53:50,429 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 07:53:50,429 INFO 2024-06-23 07:53:50,429 INFO ---------------------- 2024-06-23 07:53:50,430 INFO [23/Jun/2024 08:51:50] Create tar file login-tree-200.tar.gz 2024-06-23 07:53:50,430 INFO ---------------------- 2024-06-23 07:53:50,430 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 07:53:50,430 INFO RESULT_FOLDER=login-tree-200 2024-06-23 07:53:50,430 INFO SIMULATION_PATH=login-tree-200/loadtest-20240623065119647 2024-06-23 07:53:50,430 INFO PWD=/results/gatling/login-tree-200/loadtest-20240623065119647 2024-06-23 07:53:50,430 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-06-23 07:53:50,430 INFO /results/gatling 2024-06-23 07:53:50,430 INFO 2024-06-23 07:53:50,430 INFO ---------------------- 2024-06-23 07:53:50,430 INFO [23/Jun/2024 08:51:50] Update /results/gatling//latest 2024-06-23 07:53:50,430 INFO ---------------------- 2024-06-23 07:53:50,430 INFO 2024-06-23 07:53:50,430 INFO ---------------------- 2024-06-23 07:53:50,430 INFO [23/Jun/2024 08:51:50] Update /results/gatling//latest.tar.gz 2024-06-23 07:53:50,430 INFO ---------------------- 2024-06-23 07:53:50,430 INFO ________________________________________________________________________________ [2024-06-23 07:53:50] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 07:53:50,431 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-200/stderr.txt is small (0 lines), printing all lines: 2024-06-23 07:53:50,431 DEBUG ----- output ----- 2024-06-23 07:53:50,431 DEBUG 2024-06-23 07:53:50,431 INFO ________________________________________________________________________________ [2024-06-23 07:53:50] 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-23 07:53:50] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 07:53:50] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped