--Task-- name: mixedquery-50 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: idm 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: ['mixedquery-40'] preceding_task: mixedquery-40 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 50} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 07:43:57] mixedquery-50 pre : N/A ________________________________________________________________________________ [2024-04-24 07:43:57] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 07:43:57] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-50/stdout.txt Pod log : /results/orders/mixedquery-50 2024-04-24 07:43:57,629 INFO 2024-04-24 07:43:57,630 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-04-24 07:43:57,630 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:57,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:57,984 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:57,984 DEBUG --- stdout --- 2024-04-24 07:43:57,984 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:57,984 DEBUG --- stderr --- 2024-04-24 07:43:57,984 DEBUG ________________________________________________________________________________ [2024-04-24 07:43:57] mixedquery-50 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 07:43:57,985 INFO 2024-04-24 07:43:57,985 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-04-24 07:43:57,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:58,298 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:58,299 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:58,299 DEBUG --- stdout --- 2024-04-24 07:43:58,299 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:58,299 DEBUG --- stderr --- 2024-04-24 07:43:58,299 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 "idm.MixedQuery@medium" --results-folder /results/gatling/mixedquery-50 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 07:43:58] mixedquery-50 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 07:43:58,299 INFO 2024-04-24 07:43:58,299 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-04-24 07:43:58,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:58,610 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:58,611 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:58,611 DEBUG --- stdout --- 2024-04-24 07:43:58,611 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:58,611 DEBUG --- stderr --- 2024-04-24 07:43:58,611 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/order.json { "name": "mixedquery-50", "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 \"idm.MixedQuery@medium\" --results-folder /results/gatling/mixedquery-50 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-50", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dcsvfile=/results/idm/user_feeder.csv -Dduration=1800 -Didm_host=xlou.iam.xlou-cdm.engineeringpit.com -Dnum_users=10000000 -DtestCase=idm.MixedQuery -DtestMode=open -Dthroughput=50 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 07:43:58] mixedquery-50 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-04-24 07:43:58,613 INFO 2024-04-24 07:43:58,613 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 07:43:58,613 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 07:43:58,849 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:43:58,849 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:58,849 DEBUG --- stdout --- 2024-04-24 07:43:58,849 DEBUG WAITING 2024-04-24 07:43:58,850 DEBUG --- stderr --- 2024-04-24 07:43:58,850 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-04-24 07:43:58] mixedquery-50 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 07:43:58,850 INFO 2024-04-24 07:43:58,851 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-04-24 07:43:58,851 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:59,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:59,190 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:59,191 DEBUG --- stdout --- 2024-04-24 07:43:59,191 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:59,191 DEBUG --- stderr --- 2024-04-24 07:43:59,191 DEBUG 2024-04-24 07:43:59,191 INFO 2024-04-24 07:43:59,191 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 07:43:59,191 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:44:00,600 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:44:00,600 DEBUG --- stdout --- 2024-04-24 07:44:00,600 DEBUG 2024-04-24 07:44:00,600 DEBUG --- stderr --- 2024-04-24 07:44:00,600 DEBUG Order has been successfully forwarded to overseer [2024-04-24 07:44:00] - INFO: Check if Gatling simulation started successfully 2024-04-24 07:44:00,601 INFO 2024-04-24 07:44:00,601 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-04-24 07:44:00,601 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:44:00,919 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:44:00,919 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:44:00,919 DEBUG --- stdout --- 2024-04-24 07:44:00,919 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:44:00,919 DEBUG --- stderr --- 2024-04-24 07:44:00,919 DEBUG 2024-04-24 07:44:00,920 INFO 2024-04-24 07:44:00,920 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m -- head -n 25 /results/orders/mixedquery-50/stdout.txt | grep "started..." 2024-04-24 07:44:00,920 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 07:44:01,687 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 07:44:07,456 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-24 07:44:07,456 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:44:07,456 DEBUG --- stdout --- 2024-04-24 07:44:07,456 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 07:44:07,456 DEBUG --- stderr --- 2024-04-24 07:44:07,456 DEBUG ________________________________________________________________________________ [2024-04-24 07:44:07] mixedquery-50 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 36m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry PASS : Order stopped by itself after 32m 05s and before timeout (36m) ________________________________________________________________________________ [2024-04-24 08:16:12] mixedquery-50 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 08:16:12,999 INFO 2024-04-24 08:16:12,999 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-04-24 08:16:12,999 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:13,400 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:13,400 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:13,400 DEBUG --- stdout --- 2024-04-24 08:16:13,400 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:13,400 DEBUG --- stderr --- 2024-04-24 08:16:13,400 DEBUG 2024-04-24 08:16:13,401 INFO 2024-04-24 08:16:13,401 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m -- rm -f /results/orders/order.json 2024-04-24 08:16:13,401 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:16:14,244 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:14,246 DEBUG --- stdout --- 2024-04-24 08:16:14,246 DEBUG 2024-04-24 08:16:14,246 DEBUG --- stderr --- 2024-04-24 08:16:14,246 DEBUG Order ran during 32m 13s ________________________________________________________________________________ [2024-04-24 08:16:14] mixedquery-50 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 08:16:14] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/order.json 2024-04-24 08:16:14,247 INFO 2024-04-24 08:16:14,247 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-04-24 08:16:14,247 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:14,604 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:14,604 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:14,604 DEBUG --- stdout --- 2024-04-24 08:16:14,605 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:14,605 DEBUG --- stderr --- 2024-04-24 08:16:14,605 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:16:14,605 INFO 2024-04-24 08:16:14,605 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-04-24 08:16:14,605 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:14,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:14,928 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:14,928 DEBUG --- stdout --- 2024-04-24 08:16:14,928 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:14,928 DEBUG --- stderr --- 2024-04-24 08:16:14,928 DEBUG 2024-04-24 08:16:14,929 INFO 2024-04-24 08:16:14,929 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/mixedquery-50/rc.txt" ]; then echo "/results/orders/mixedquery-50/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-50/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-50/rc.txt file found" 2024-04-24 08:16:14,929 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:16:15,772 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:16:15,772 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:15,772 DEBUG --- stdout --- 2024-04-24 08:16:15,772 DEBUG /results/orders/mixedquery-50/rc.txt file found 2024-04-24 08:16:15,772 DEBUG --- stderr --- 2024-04-24 08:16:15,772 DEBUG 2024-04-24 08:16:15,773 INFO 2024-04-24 08:16:15,773 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-04-24 08:16:15,773 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:16,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:16,098 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:16,099 DEBUG --- stdout --- 2024-04-24 08:16:16,099 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:16,099 DEBUG --- stderr --- 2024-04-24 08:16:16,099 DEBUG 2024-04-24 08:16:16,099 INFO 2024-04-24 08:16:16,099 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/mixedquery-50/rc.txt 2024-04-24 08:16:16,982 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:16:16,982 DEBUG --- stdout --- 2024-04-24 08:16:16,982 DEBUG 4 /results/orders/mixedquery-50/rc.txt 2024-04-24 08:16:16,982 DEBUG --- stderr --- 2024-04-24 08:16:16,982 DEBUG 2024-04-24 08:16:16,982 INFO - Download file (size 4k) 2024-04-24 08:16:16,983 INFO 2024-04-24 08:16:16,983 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-04-24 08:16:16,983 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:17,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:17,315 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:17,315 DEBUG --- stdout --- 2024-04-24 08:16:17,315 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:17,315 DEBUG --- stderr --- 2024-04-24 08:16:17,315 DEBUG source : /results/orders/mixedquery-50/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/rc.txt 2024-04-24 08:16:17,315 INFO 2024-04-24 08:16:17,315 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-04-24 08:16:17,315 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:17,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:17,632 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:17,632 DEBUG --- stdout --- 2024-04-24 08:16:17,633 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:17,633 DEBUG --- stderr --- 2024-04-24 08:16:17,633 DEBUG 2024-04-24 08:16:17,633 INFO 2024-04-24 08:16:17,633 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-50/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/rc.txt 2024-04-24 08:16:17,633 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:16:18,413 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:18,413 DEBUG --- stdout --- 2024-04-24 08:16:18,413 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:16:18,413 DEBUG --- stderr --- 2024-04-24 08:16:18,413 DEBUG 2024-04-24 08:16:18,414 INFO 2024-04-24 08:16:18,414 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-04-24 08:16:18,414 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:18,732 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:18,732 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:18,732 DEBUG --- stdout --- 2024-04-24 08:16:18,732 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:18,732 DEBUG --- stderr --- 2024-04-24 08:16:18,732 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:16:18,733 INFO 2024-04-24 08:16:18,733 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-04-24 08:16:18,733 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:19,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:19,050 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:19,050 DEBUG --- stdout --- 2024-04-24 08:16:19,051 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:19,051 DEBUG --- stderr --- 2024-04-24 08:16:19,051 DEBUG 2024-04-24 08:16:19,051 INFO 2024-04-24 08:16:19,052 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/mixedquery-50/stderr.txt" ]; then echo "/results/orders/mixedquery-50/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-50/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-50/stderr.txt file found" 2024-04-24 08:16:19,052 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:16:19,936 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:16:19,937 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:19,937 DEBUG --- stdout --- 2024-04-24 08:16:19,937 DEBUG /results/orders/mixedquery-50/stderr.txt file found 2024-04-24 08:16:19,937 DEBUG --- stderr --- 2024-04-24 08:16:19,937 DEBUG 2024-04-24 08:16:19,937 INFO 2024-04-24 08:16:19,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-04-24 08:16:19,937 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:20,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:20,259 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:20,259 DEBUG --- stdout --- 2024-04-24 08:16:20,259 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:20,259 DEBUG --- stderr --- 2024-04-24 08:16:20,259 DEBUG 2024-04-24 08:16:20,259 INFO 2024-04-24 08:16:20,259 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/mixedquery-50/stderr.txt 2024-04-24 08:16:21,096 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:16:21,096 DEBUG --- stdout --- 2024-04-24 08:16:21,096 DEBUG 0 /results/orders/mixedquery-50/stderr.txt 2024-04-24 08:16:21,096 DEBUG --- stderr --- 2024-04-24 08:16:21,096 DEBUG 2024-04-24 08:16:21,096 INFO - Download file (size 0k) 2024-04-24 08:16:21,096 INFO 2024-04-24 08:16:21,096 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-04-24 08:16:21,096 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:21,412 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:21,412 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:21,412 DEBUG --- stdout --- 2024-04-24 08:16:21,412 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:21,413 DEBUG --- stderr --- 2024-04-24 08:16:21,413 DEBUG source : /results/orders/mixedquery-50/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/stderr.txt 2024-04-24 08:16:21,413 INFO 2024-04-24 08:16:21,413 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:16:21,413 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:21,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:21,730 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:21,730 DEBUG --- stdout --- 2024-04-24 08:16:21,731 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:21,731 DEBUG --- stderr --- 2024-04-24 08:16:21,731 DEBUG 2024-04-24 08:16:21,731 INFO 2024-04-24 08:16:21,731 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-50/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/stderr.txt 2024-04-24 08:16:21,731 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:16:22,485 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:22,486 DEBUG --- stdout --- 2024-04-24 08:16:22,486 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:16:22,486 DEBUG --- stderr --- 2024-04-24 08:16:22,486 DEBUG 2024-04-24 08:16:22,486 INFO 2024-04-24 08:16:22,486 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-04-24 08:16:22,486 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:22,800 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:22,801 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:22,801 DEBUG --- stdout --- 2024-04-24 08:16:22,801 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:22,801 DEBUG --- stderr --- 2024-04-24 08:16:22,801 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:16:22,801 INFO 2024-04-24 08:16:22,801 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-04-24 08:16:22,801 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:23,115 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:23,115 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:23,115 DEBUG --- stdout --- 2024-04-24 08:16:23,115 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:23,115 DEBUG --- stderr --- 2024-04-24 08:16:23,115 DEBUG 2024-04-24 08:16:23,116 INFO 2024-04-24 08:16:23,116 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/mixedquery-50/stdout.txt" ]; then echo "/results/orders/mixedquery-50/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-50/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-50/stdout.txt file found" 2024-04-24 08:16:23,116 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:16:23,945 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:16:23,945 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:23,945 DEBUG --- stdout --- 2024-04-24 08:16:23,946 DEBUG /results/orders/mixedquery-50/stdout.txt file found 2024-04-24 08:16:23,946 DEBUG --- stderr --- 2024-04-24 08:16:23,946 DEBUG 2024-04-24 08:16:23,946 INFO 2024-04-24 08:16:23,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-04-24 08:16:23,946 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:24,262 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:24,262 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:24,262 DEBUG --- stdout --- 2024-04-24 08:16:24,262 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:24,262 DEBUG --- stderr --- 2024-04-24 08:16:24,262 DEBUG 2024-04-24 08:16:24,263 INFO 2024-04-24 08:16:24,263 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/mixedquery-50/stdout.txt 2024-04-24 08:16:25,106 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:16:25,108 DEBUG --- stdout --- 2024-04-24 08:16:25,108 DEBUG 540 /results/orders/mixedquery-50/stdout.txt 2024-04-24 08:16:25,108 DEBUG --- stderr --- 2024-04-24 08:16:25,109 DEBUG 2024-04-24 08:16:25,109 INFO - Download file (size 540k) 2024-04-24 08:16:25,109 INFO 2024-04-24 08:16:25,110 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-04-24 08:16:25,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:25,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:25,433 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:25,433 DEBUG --- stdout --- 2024-04-24 08:16:25,434 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:25,434 DEBUG --- stderr --- 2024-04-24 08:16:25,434 DEBUG source : /results/orders/mixedquery-50/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/stdout.txt 2024-04-24 08:16:25,434 INFO 2024-04-24 08:16:25,434 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-04-24 08:16:25,434 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:25,754 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:25,755 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:25,755 DEBUG --- stdout --- 2024-04-24 08:16:25,755 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:25,755 DEBUG --- stderr --- 2024-04-24 08:16:25,755 DEBUG 2024-04-24 08:16:25,755 INFO 2024-04-24 08:16:25,755 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-50/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/stdout.txt 2024-04-24 08:16:25,755 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:16:26,858 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:26,859 DEBUG --- stdout --- 2024-04-24 08:16:26,859 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:16:26,859 DEBUG --- stderr --- 2024-04-24 08:16:26,859 DEBUG ________________________________________________________________________________ [2024-04-24 08:16:26] mixedquery-50 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 08:16:26,859 INFO 2024-04-24 08:16:26,859 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-04-24 08:16:26,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:27,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:27,175 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:27,175 DEBUG --- stdout --- 2024-04-24 08:16:27,175 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:27,175 DEBUG --- stderr --- 2024-04-24 08:16:27,175 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:16:27,175 INFO 2024-04-24 08:16:27,175 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-04-24 08:16:27,175 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:27,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:27,492 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:27,492 DEBUG --- stdout --- 2024-04-24 08:16:27,492 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:27,492 DEBUG --- stderr --- 2024-04-24 08:16:27,492 DEBUG 2024-04-24 08:16:27,493 INFO 2024-04-24 08:16:27,493 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- bash -c 'if [ -f "/results/orders/mixedquery-50/cmd.txt" ]; then echo "/results/orders/mixedquery-50/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-50/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-50/cmd.txt file found" 2024-04-24 08:16:27,493 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:16:28,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:16:28,334 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:28,334 DEBUG --- stdout --- 2024-04-24 08:16:28,334 DEBUG /results/orders/mixedquery-50/cmd.txt file found 2024-04-24 08:16:28,334 DEBUG --- stderr --- 2024-04-24 08:16:28,334 DEBUG 2024-04-24 08:16:28,334 INFO 2024-04-24 08:16:28,334 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-04-24 08:16:28,335 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:28,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:28,651 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:28,651 DEBUG --- stdout --- 2024-04-24 08:16:28,651 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:28,651 DEBUG --- stderr --- 2024-04-24 08:16:28,651 DEBUG 2024-04-24 08:16:28,651 INFO 2024-04-24 08:16:28,651 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/mixedquery-50/cmd.txt 2024-04-24 08:16:29,487 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:16:29,488 DEBUG --- stdout --- 2024-04-24 08:16:29,488 DEBUG 4 /results/orders/mixedquery-50/cmd.txt 2024-04-24 08:16:29,488 DEBUG --- stderr --- 2024-04-24 08:16:29,488 DEBUG 2024-04-24 08:16:29,488 INFO - Download file (size 4k) 2024-04-24 08:16:29,488 INFO 2024-04-24 08:16:29,488 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-04-24 08:16:29,488 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:29,837 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:29,837 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:29,837 DEBUG --- stdout --- 2024-04-24 08:16:29,837 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:29,837 DEBUG --- stderr --- 2024-04-24 08:16:29,837 DEBUG source : /results/orders/mixedquery-50/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/cmd.txt 2024-04-24 08:16:29,838 INFO 2024-04-24 08:16:29,838 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-04-24 08:16:29,838 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:30,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:30,151 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:30,152 DEBUG --- stdout --- 2024-04-24 08:16:30,152 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:30,152 DEBUG --- stderr --- 2024-04-24 08:16:30,152 DEBUG 2024-04-24 08:16:30,153 INFO 2024-04-24 08:16:30,153 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-50/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/cmd.txt 2024-04-24 08:16:30,153 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:16:30,919 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:30,919 DEBUG --- stdout --- 2024-04-24 08:16:30,919 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:16:30,919 DEBUG --- stderr --- 2024-04-24 08:16:30,919 DEBUG 2024-04-24 08:16:30,920 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/cmd.txt is small (1 lines), printing all lines: 2024-04-24 08:16:30,920 DEBUG ----- output ----- 2024-04-24 08:16:30,920 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 "idm.MixedQuery@medium" --results-folder /results/gatling/mixedquery-50 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 08:16:30,920 INFO ________________________________________________________________________________ [2024-04-24 08:16:30] mixedquery-50 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 08:16:30,920 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/rc.txt is small (1 lines), printing all lines: 2024-04-24 08:16:30,920 DEBUG ----- output ----- 2024-04-24 08:16:30,920 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 08:16:30,920 INFO ________________________________________________________________________________ [2024-04-24 08:16:30] mixedquery-50 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 08:16:30,922 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/stdout.txt is 8082 lines): 2024-04-24 08:16:30,922 DEBUG ----- output ----- 2024-04-24 08:16:30,922 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 08:16:30,922 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 08:16:30,922 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO ================================================================================ 2024-04-24 08:16:30,922 INFO 2024-04-24 08:44:11 5s elapsed 2024-04-24 08:16:30,922 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,922 INFO > Global (OK=0 KO=0 ) 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,922 INFO [- ] 0% 2024-04-24 08:16:30,922 INFO waiting: 90705 / active: 7 / done: 14 2024-04-24 08:16:30,922 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,922 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:16:30,922 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:16:30,922 INFO ================================================================================ 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO ================================================================================ 2024-04-24 08:16:30,922 INFO 2024-04-24 08:44:16 10s elapsed 2024-04-24 08:16:30,922 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,922 INFO > Global (OK=0 KO=0 ) 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO 2024-04-24 08:16:30,922 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,922 INFO [- ] 0% 2024-04-24 08:16:30,922 INFO waiting: 90642 / active: 16 / done: 68 2024-04-24 08:16:30,923 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,923 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:16:30,923 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:16:30,923 INFO ================================================================================ 2024-04-24 08:16:30,923 INFO 2024-04-24 08:16:30,923 INFO 2024-04-24 08:16:30,923 INFO ================================================================================ 2024-04-24 08:16:30,923 INFO 2024-04-24 08:44:21 15s elapsed 2024-04-24 08:16:30,923 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,923 INFO > Global (OK=103 KO=0 ) 2024-04-24 08:16:30,923 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:16:30,923 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:16:30,923 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,923 INFO > ReadManagedUser (OK=28 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryBySn (OK=27 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryFull (OK=15 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryStartsWith (OK=18 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryPartial (OK=12 KO=0 ) 2024-04-24 08:16:30,923 INFO 2024-04-24 08:16:30,923 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,923 INFO [- ] 0% 2024-04-24 08:16:30,923 INFO waiting: 90538 / active: 2 / done: 186 2024-04-24 08:16:30,923 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,923 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:16:30,923 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:16:30,923 INFO ================================================================================ 2024-04-24 08:16:30,923 INFO 2024-04-24 08:16:30,923 INFO 2024-04-24 08:16:30,923 INFO ================================================================================ 2024-04-24 08:16:30,923 INFO 2024-04-24 08:44:26 20s elapsed 2024-04-24 08:16:30,923 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,923 INFO > Global (OK=246 KO=0 ) 2024-04-24 08:16:30,923 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:16:30,923 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:16:30,923 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,923 INFO > ReadManagedUser (OK=50 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryBySn (OK=58 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryFull (OK=50 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryStartsWith (OK=45 KO=0 ) 2024-04-24 08:16:30,923 INFO > queryPartial (OK=40 KO=0 ) 2024-04-24 08:16:30,923 INFO 2024-04-24 08:16:30,923 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,924 INFO [- ] 0% 2024-04-24 08:16:30,924 INFO waiting: 90392 / active: 5 / done: 329 2024-04-24 08:16:30,924 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,924 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:16:30,924 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:16:30,924 INFO ================================================================================ 2024-04-24 08:16:30,924 INFO 2024-04-24 08:16:30,924 INFO 2024-04-24 08:16:30,924 INFO ================================================================================ 2024-04-24 08:16:30,924 INFO 2024-04-24 08:44:31 25s elapsed 2024-04-24 08:16:30,924 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,924 INFO > Global (OK=436 KO=0 ) 2024-04-24 08:16:30,924 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:16:30,924 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:16:30,924 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,924 INFO > ReadManagedUser (OK=110 KO=0 ) 2024-04-24 08:16:30,924 INFO > queryBySn (OK=90 KO=0 ) 2024-04-24 08:16:30,924 INFO > queryFull (OK=80 KO=0 ) 2024-04-24 08:16:30,924 INFO > queryStartsWith (OK=81 KO=0 ) 2024-04-24 08:16:30,924 INFO > queryPartial (OK=72 KO=0 ) 2024-04-24 08:16:30,924 INFO 2024-04-24 08:16:30,924 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,924 INFO [- ] 0% 2024-04-24 08:16:30,924 INFO waiting: 90205 / active: 2 / done: 519 2024-04-24 08:16:30,924 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,924 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:16:30,924 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:16:30,924 INFO ================================================================================ 2024-04-24 08:16:30,924 INFO 2024-04-24 08:16:30,924 INFO 2024-04-24 08:16:30,924 INFO [...] 2024-04-24 08:16:30,927 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,927 INFO > ReadManagedUser (OK=18141 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryBySn (OK=17834 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryFull (OK=17850 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryStartsWith (OK=18255 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryPartial (OK=18035 KO=0 ) 2024-04-24 08:16:30,927 INFO 2024-04-24 08:16:30,927 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,927 INFO [#########################################################################-] 99% 2024-04-24 08:16:30,927 INFO waiting: 523 / active: 2 / done: 90201 2024-04-24 08:16:30,927 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,927 INFO [##########################################################################]100% 2024-04-24 08:16:30,927 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:16:30,927 INFO ================================================================================ 2024-04-24 08:16:30,927 INFO 2024-04-24 08:16:30,927 INFO 2024-04-24 08:16:30,927 INFO ================================================================================ 2024-04-24 08:16:30,927 INFO 2024-04-24 09:14:31 1825s elapsed 2024-04-24 08:16:30,927 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,927 INFO > Global (OK=90393 KO=0 ) 2024-04-24 08:16:30,927 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:16:30,927 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:16:30,927 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,927 INFO > ReadManagedUser (OK=18198 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryBySn (OK=17884 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryFull (OK=17896 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryStartsWith (OK=18309 KO=0 ) 2024-04-24 08:16:30,927 INFO > queryPartial (OK=18103 KO=0 ) 2024-04-24 08:16:30,927 INFO 2024-04-24 08:16:30,927 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,927 INFO [#########################################################################-] 99% 2024-04-24 08:16:30,927 INFO waiting: 243 / active: 7 / done: 90476 2024-04-24 08:16:30,927 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,927 INFO [##########################################################################]100% 2024-04-24 08:16:30,927 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:16:30,927 INFO ================================================================================ 2024-04-24 08:16:30,927 INFO 2024-04-24 08:16:30,927 INFO 2024-04-24 08:16:30,928 INFO ================================================================================ 2024-04-24 08:16:30,928 INFO 2024-04-24 09:14:36 1830s elapsed 2024-04-24 08:16:30,928 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,928 INFO > Global (OK=90635 KO=0 ) 2024-04-24 08:16:30,928 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:16:30,928 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:16:30,928 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,928 INFO > ReadManagedUser (OK=18231 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryBySn (OK=17926 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryFull (OK=17944 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryStartsWith (OK=18378 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryPartial (OK=18153 KO=0 ) 2024-04-24 08:16:30,928 INFO 2024-04-24 08:16:30,928 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,928 INFO [#########################################################################-] 99% 2024-04-24 08:16:30,928 INFO waiting: 0 / active: 8 / done: 90718 2024-04-24 08:16:30,928 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,928 INFO [##########################################################################]100% 2024-04-24 08:16:30,928 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:16:30,928 INFO ================================================================================ 2024-04-24 08:16:30,928 INFO 2024-04-24 08:16:30,928 INFO 2024-04-24 08:16:30,928 INFO ================================================================================ 2024-04-24 08:16:30,928 INFO 2024-04-24 09:14:36 1830s elapsed 2024-04-24 08:16:30,928 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:16:30,928 INFO > Global (OK=90643 KO=0 ) 2024-04-24 08:16:30,928 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:16:30,928 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:16:30,928 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:16:30,928 INFO > ReadManagedUser (OK=18231 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryBySn (OK=17926 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryFull (OK=17944 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryStartsWith (OK=18381 KO=0 ) 2024-04-24 08:16:30,928 INFO > queryPartial (OK=18158 KO=0 ) 2024-04-24 08:16:30,928 INFO 2024-04-24 08:16:30,928 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:16:30,928 INFO [##########################################################################]100% 2024-04-24 08:16:30,928 INFO waiting: 0 / active: 0 / done: 90726 2024-04-24 08:16:30,928 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:16:30,928 INFO [##########################################################################]100% 2024-04-24 08:16:30,928 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:16:30,928 INFO ================================================================================ 2024-04-24 08:16:30,929 INFO 2024-04-24 08:16:30,929 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-24 08:16:30,929 INFO 2024-04-24 08:16:30,929 INFO ---------------------- 2024-04-24 08:16:30,929 INFO [24/Apr/2024 09:14:36] Create tar file mixedquery-50.tar.gz 2024-04-24 08:16:30,929 INFO ---------------------- 2024-04-24 08:16:30,929 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 08:16:30,929 INFO RESULT_FOLDER=mixedquery-50 2024-04-24 08:16:30,929 INFO SIMULATION_PATH=mixedquery-50/loadtest-20240424074405943 2024-04-24 08:16:30,929 INFO PWD=/results/gatling/mixedquery-50/loadtest-20240424074405943 2024-04-24 08:16:30,929 INFO [cmd] tar -czvf /results/gatling//mixedquery-50.tar.gz --exclude=simulation.log * 2024-04-24 08:16:30,929 INFO /results/gatling 2024-04-24 08:16:30,929 INFO 2024-04-24 08:16:30,929 INFO ---------------------- 2024-04-24 08:16:30,929 INFO [24/Apr/2024 09:14:36] Update /results/gatling//latest 2024-04-24 08:16:30,929 INFO ---------------------- 2024-04-24 08:16:30,929 INFO 2024-04-24 08:16:30,929 INFO ---------------------- 2024-04-24 08:16:30,929 INFO [24/Apr/2024 09:14:36] Update /results/gatling//latest.tar.gz 2024-04-24 08:16:30,929 INFO ---------------------- 2024-04-24 08:16:30,929 INFO ________________________________________________________________________________ [2024-04-24 08:16:30] mixedquery-50 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 08:16:30,929 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/05_mixedquery-50/stderr.txt is small (0 lines), printing all lines: 2024-04-24 08:16:30,929 DEBUG ----- output ----- 2024-04-24 08:16:30,929 DEBUG 2024-04-24 08:16:30,930 INFO ________________________________________________________________________________ [2024-04-24 08:16:30] mixedquery-50 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-04-24 08:16:30] mixedquery-50 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 08:16:30] mixedquery-50 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped