--Task-- name: mixedquery-30 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-20'] preceding_task: mixedquery-20 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 30} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 06:38:48] mixedquery-30 pre : N/A ________________________________________________________________________________ [2024-04-24 06:38:48] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 06:38:48] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-30/stdout.txt Pod log : /results/orders/mixedquery-30 2024-04-24 06:38:48,848 INFO 2024-04-24 06:38:48,848 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 06:38:48,848 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:49,166 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:49,166 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:49,167 DEBUG --- stdout --- 2024-04-24 06:38:49,167 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:49,167 DEBUG --- stderr --- 2024-04-24 06:38:49,167 DEBUG ________________________________________________________________________________ [2024-04-24 06:38:49] mixedquery-30 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 06:38:49,167 INFO 2024-04-24 06:38:49,167 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 06:38:49,167 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:49,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:49,485 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:49,486 DEBUG --- stdout --- 2024-04-24 06:38:49,486 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:49,486 DEBUG --- stderr --- 2024-04-24 06:38:49,486 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-30 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 06:38:49] mixedquery-30 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 06:38:49,487 INFO 2024-04-24 06:38:49,487 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 06:38:49,487 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:49,805 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:49,805 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:49,805 DEBUG --- stdout --- 2024-04-24 06:38:49,805 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:49,805 DEBUG --- stderr --- 2024-04-24 06:38:49,805 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/order.json { "name": "mixedquery-30", "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-30 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-30", "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=30 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 06:38:49] mixedquery-30 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 06:38:49,808 INFO 2024-04-24 06:38:49,808 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 06:38:49,808 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 06:38:50,051 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:38:50,051 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:50,051 DEBUG --- stdout --- 2024-04-24 06:38:50,051 DEBUG WAITING 2024-04-24 06:38:50,051 DEBUG --- stderr --- 2024-04-24 06:38:50,051 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 06:38:50] mixedquery-30 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 06:38:50,052 INFO 2024-04-24 06:38:50,052 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 06:38:50,052 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:50,429 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:50,430 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:50,430 DEBUG --- stdout --- 2024-04-24 06:38:50,430 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:50,430 DEBUG --- stderr --- 2024-04-24 06:38:50,430 DEBUG 2024-04-24 06:38:50,430 INFO 2024-04-24 06:38:50,430 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/03_mixedquery-30/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 06:38:50,430 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:38:51,823 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:51,823 DEBUG --- stdout --- 2024-04-24 06:38:51,823 DEBUG 2024-04-24 06:38:51,823 DEBUG --- stderr --- 2024-04-24 06:38:51,823 DEBUG Order has been successfully forwarded to overseer [2024-04-24 06:38:51] - INFO: Check if Gatling simulation started successfully 2024-04-24 06:38:51,824 INFO 2024-04-24 06:38:51,824 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 06:38:51,824 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:52,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:52,178 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:52,178 DEBUG --- stdout --- 2024-04-24 06:38:52,178 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:52,178 DEBUG --- stderr --- 2024-04-24 06:38:52,178 DEBUG 2024-04-24 06:38:52,178 INFO 2024-04-24 06:38:52,179 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-30/stdout.txt | grep "started..." 2024-04-24 06:38:52,179 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 06:38:52,967 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 06:38:58,742 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-24 06:38:58,742 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:58,742 DEBUG --- stdout --- 2024-04-24 06:38:58,743 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 06:38:58,743 DEBUG --- stderr --- 2024-04-24 06:38:58,743 DEBUG ________________________________________________________________________________ [2024-04-24 06:38:58] mixedquery-30 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 04s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 05s, retry Order is running since 30m 05s, retry PASS : Order stopped by itself after 32m 05s and before timeout (36m) ________________________________________________________________________________ [2024-04-24 07:11:04] mixedquery-30 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 07:11:04,620 INFO 2024-04-24 07:11:04,621 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:11:04,621 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:04,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:04,965 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:04,965 DEBUG --- stdout --- 2024-04-24 07:11:04,965 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:04,965 DEBUG --- stderr --- 2024-04-24 07:11:04,965 DEBUG 2024-04-24 07:11:04,965 INFO 2024-04-24 07:11:04,965 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 07:11:04,965 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:11:05,795 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:05,795 DEBUG --- stdout --- 2024-04-24 07:11:05,795 DEBUG 2024-04-24 07:11:05,795 DEBUG --- stderr --- 2024-04-24 07:11:05,795 DEBUG Order ran during 32m 13s ________________________________________________________________________________ [2024-04-24 07:11:05] mixedquery-30 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 07:11:05] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/order.json 2024-04-24 07:11:05,797 INFO 2024-04-24 07:11:05,797 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:11:05,797 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:06,183 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:06,185 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:06,186 DEBUG --- stdout --- 2024-04-24 07:11:06,186 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:06,186 DEBUG --- stderr --- 2024-04-24 07:11:06,186 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:11:06,187 INFO 2024-04-24 07:11:06,187 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:11:06,187 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:06,557 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:06,557 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:06,557 DEBUG --- stdout --- 2024-04-24 07:11:06,557 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:06,557 DEBUG --- stderr --- 2024-04-24 07:11:06,557 DEBUG 2024-04-24 07:11:06,558 INFO 2024-04-24 07:11:06,559 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-30/rc.txt" ]; then echo "/results/orders/mixedquery-30/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-30/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-30/rc.txt file found" 2024-04-24 07:11:06,559 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:11:07,447 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:11:07,447 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:07,447 DEBUG --- stdout --- 2024-04-24 07:11:07,447 DEBUG /results/orders/mixedquery-30/rc.txt file found 2024-04-24 07:11:07,447 DEBUG --- stderr --- 2024-04-24 07:11:07,447 DEBUG 2024-04-24 07:11:07,447 INFO 2024-04-24 07:11:07,447 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:11:07,447 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:07,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:07,758 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:07,758 DEBUG --- stdout --- 2024-04-24 07:11:07,758 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:07,758 DEBUG --- stderr --- 2024-04-24 07:11:07,758 DEBUG 2024-04-24 07:11:07,758 INFO 2024-04-24 07:11:07,758 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-30/rc.txt 2024-04-24 07:11:08,600 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:11:08,600 DEBUG --- stdout --- 2024-04-24 07:11:08,600 DEBUG 4 /results/orders/mixedquery-30/rc.txt 2024-04-24 07:11:08,600 DEBUG --- stderr --- 2024-04-24 07:11:08,600 DEBUG 2024-04-24 07:11:08,600 INFO - Download file (size 4k) 2024-04-24 07:11:08,601 INFO 2024-04-24 07:11:08,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:11:08,601 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:08,913 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:08,913 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:08,913 DEBUG --- stdout --- 2024-04-24 07:11:08,913 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:08,913 DEBUG --- stderr --- 2024-04-24 07:11:08,913 DEBUG source : /results/orders/mixedquery-30/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/rc.txt 2024-04-24 07:11:08,914 INFO 2024-04-24 07:11:08,914 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:11:08,914 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:09,226 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:09,227 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:09,227 DEBUG --- stdout --- 2024-04-24 07:11:09,227 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:09,227 DEBUG --- stderr --- 2024-04-24 07:11:09,227 DEBUG 2024-04-24 07:11:09,227 INFO 2024-04-24 07:11:09,227 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-30/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/rc.txt 2024-04-24 07:11:09,227 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:11:09,980 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:09,980 DEBUG --- stdout --- 2024-04-24 07:11:09,980 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:11:09,980 DEBUG --- stderr --- 2024-04-24 07:11:09,980 DEBUG 2024-04-24 07:11:09,980 INFO 2024-04-24 07:11:09,980 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:11:09,980 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:10,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:10,331 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:10,331 DEBUG --- stdout --- 2024-04-24 07:11:10,331 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:10,331 DEBUG --- stderr --- 2024-04-24 07:11:10,331 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:11:10,332 INFO 2024-04-24 07:11:10,332 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:11:10,332 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:10,722 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:10,722 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:10,722 DEBUG --- stdout --- 2024-04-24 07:11:10,722 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:10,722 DEBUG --- stderr --- 2024-04-24 07:11:10,722 DEBUG 2024-04-24 07:11:10,723 INFO 2024-04-24 07:11:10,723 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-30/stderr.txt" ]; then echo "/results/orders/mixedquery-30/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-30/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-30/stderr.txt file found" 2024-04-24 07:11:10,723 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:11:11,575 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:11:11,575 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:11,576 DEBUG --- stdout --- 2024-04-24 07:11:11,576 DEBUG /results/orders/mixedquery-30/stderr.txt file found 2024-04-24 07:11:11,576 DEBUG --- stderr --- 2024-04-24 07:11:11,576 DEBUG 2024-04-24 07:11:11,576 INFO 2024-04-24 07:11:11,576 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:11:11,576 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:11,959 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:11,959 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:11,959 DEBUG --- stdout --- 2024-04-24 07:11:11,960 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:11,960 DEBUG --- stderr --- 2024-04-24 07:11:11,960 DEBUG 2024-04-24 07:11:11,960 INFO 2024-04-24 07:11:11,960 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-30/stderr.txt 2024-04-24 07:11:12,848 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:11:12,849 DEBUG --- stdout --- 2024-04-24 07:11:12,849 DEBUG 0 /results/orders/mixedquery-30/stderr.txt 2024-04-24 07:11:12,849 DEBUG --- stderr --- 2024-04-24 07:11:12,849 DEBUG 2024-04-24 07:11:12,849 INFO - Download file (size 0k) 2024-04-24 07:11:12,849 INFO 2024-04-24 07:11:12,849 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:11:12,850 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:13,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:13,169 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:13,169 DEBUG --- stdout --- 2024-04-24 07:11:13,169 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:13,169 DEBUG --- stderr --- 2024-04-24 07:11:13,169 DEBUG source : /results/orders/mixedquery-30/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/stderr.txt 2024-04-24 07:11:13,169 INFO 2024-04-24 07:11:13,169 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:11:13,169 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:13,481 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:13,482 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:13,482 DEBUG --- stdout --- 2024-04-24 07:11:13,482 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:13,482 DEBUG --- stderr --- 2024-04-24 07:11:13,482 DEBUG 2024-04-24 07:11:13,482 INFO 2024-04-24 07:11:13,482 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-30/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/stderr.txt 2024-04-24 07:11:13,482 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:11:14,240 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:14,240 DEBUG --- stdout --- 2024-04-24 07:11:14,240 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:11:14,240 DEBUG --- stderr --- 2024-04-24 07:11:14,240 DEBUG 2024-04-24 07:11:14,241 INFO 2024-04-24 07:11:14,241 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:11:14,241 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:14,557 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:14,557 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:14,557 DEBUG --- stdout --- 2024-04-24 07:11:14,557 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:14,557 DEBUG --- stderr --- 2024-04-24 07:11:14,557 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:11:14,558 INFO 2024-04-24 07:11:14,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-04-24 07:11:14,558 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:14,873 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:14,874 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:14,874 DEBUG --- stdout --- 2024-04-24 07:11:14,874 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:14,874 DEBUG --- stderr --- 2024-04-24 07:11:14,874 DEBUG 2024-04-24 07:11:14,874 INFO 2024-04-24 07:11:14,874 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-30/stdout.txt" ]; then echo "/results/orders/mixedquery-30/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-30/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-30/stdout.txt file found" 2024-04-24 07:11:14,875 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:11:15,709 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:11:15,709 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:15,709 DEBUG --- stdout --- 2024-04-24 07:11:15,709 DEBUG /results/orders/mixedquery-30/stdout.txt file found 2024-04-24 07:11:15,709 DEBUG --- stderr --- 2024-04-24 07:11:15,709 DEBUG 2024-04-24 07:11:15,710 INFO 2024-04-24 07:11:15,710 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:11:15,710 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:16,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:16,030 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:16,030 DEBUG --- stdout --- 2024-04-24 07:11:16,030 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:16,030 DEBUG --- stderr --- 2024-04-24 07:11:16,030 DEBUG 2024-04-24 07:11:16,030 INFO 2024-04-24 07:11:16,031 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-30/stdout.txt 2024-04-24 07:11:16,886 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:11:16,888 DEBUG --- stdout --- 2024-04-24 07:11:16,888 DEBUG 532 /results/orders/mixedquery-30/stdout.txt 2024-04-24 07:11:16,888 DEBUG --- stderr --- 2024-04-24 07:11:16,888 DEBUG 2024-04-24 07:11:16,888 INFO - Download file (size 532k) 2024-04-24 07:11:16,889 INFO 2024-04-24 07:11:16,889 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:11:16,889 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:17,218 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:17,219 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:17,219 DEBUG --- stdout --- 2024-04-24 07:11:17,219 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:17,219 DEBUG --- stderr --- 2024-04-24 07:11:17,219 DEBUG source : /results/orders/mixedquery-30/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/stdout.txt 2024-04-24 07:11:17,220 INFO 2024-04-24 07:11:17,220 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:11:17,220 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:17,535 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:17,536 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:17,536 DEBUG --- stdout --- 2024-04-24 07:11:17,536 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:17,536 DEBUG --- stderr --- 2024-04-24 07:11:17,536 DEBUG 2024-04-24 07:11:17,537 INFO 2024-04-24 07:11:17,537 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-30/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/stdout.txt 2024-04-24 07:11:17,537 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:11:18,696 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:18,696 DEBUG --- stdout --- 2024-04-24 07:11:18,696 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:11:18,696 DEBUG --- stderr --- 2024-04-24 07:11:18,696 DEBUG ________________________________________________________________________________ [2024-04-24 07:11:18] mixedquery-30 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 07:11:18,697 INFO 2024-04-24 07:11:18,697 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:11:18,697 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:19,025 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:19,025 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:19,025 DEBUG --- stdout --- 2024-04-24 07:11:19,025 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:19,025 DEBUG --- stderr --- 2024-04-24 07:11:19,026 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:11:19,026 INFO 2024-04-24 07:11:19,026 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:11:19,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:19,344 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:19,345 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:19,345 DEBUG --- stdout --- 2024-04-24 07:11:19,345 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:19,345 DEBUG --- stderr --- 2024-04-24 07:11:19,345 DEBUG 2024-04-24 07:11:19,345 INFO 2024-04-24 07:11:19,345 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-30/cmd.txt" ]; then echo "/results/orders/mixedquery-30/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-30/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-30/cmd.txt file found" 2024-04-24 07:11:19,345 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:11:20,177 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:11:20,177 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:20,177 DEBUG --- stdout --- 2024-04-24 07:11:20,177 DEBUG /results/orders/mixedquery-30/cmd.txt file found 2024-04-24 07:11:20,177 DEBUG --- stderr --- 2024-04-24 07:11:20,177 DEBUG 2024-04-24 07:11:20,178 INFO 2024-04-24 07:11:20,178 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:11:20,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:20,499 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:20,499 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:20,499 DEBUG --- stdout --- 2024-04-24 07:11:20,499 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:20,499 DEBUG --- stderr --- 2024-04-24 07:11:20,499 DEBUG 2024-04-24 07:11:20,500 INFO 2024-04-24 07:11:20,500 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-30/cmd.txt 2024-04-24 07:11:21,354 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:11:21,354 DEBUG --- stdout --- 2024-04-24 07:11:21,355 DEBUG 4 /results/orders/mixedquery-30/cmd.txt 2024-04-24 07:11:21,355 DEBUG --- stderr --- 2024-04-24 07:11:21,355 DEBUG 2024-04-24 07:11:21,355 INFO - Download file (size 4k) 2024-04-24 07:11:21,355 INFO 2024-04-24 07:11:21,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-04-24 07:11:21,355 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:21,674 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:21,674 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:21,674 DEBUG --- stdout --- 2024-04-24 07:11:21,674 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:21,674 DEBUG --- stderr --- 2024-04-24 07:11:21,674 DEBUG source : /results/orders/mixedquery-30/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/cmd.txt 2024-04-24 07:11:21,675 INFO 2024-04-24 07:11:21,675 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:11:21,675 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:21,988 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:21,988 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:21,989 DEBUG --- stdout --- 2024-04-24 07:11:21,989 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:21,990 DEBUG --- stderr --- 2024-04-24 07:11:21,990 DEBUG 2024-04-24 07:11:21,990 INFO 2024-04-24 07:11:21,990 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-30/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/cmd.txt 2024-04-24 07:11:21,990 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:11:22,749 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:22,749 DEBUG --- stdout --- 2024-04-24 07:11:22,749 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:11:22,749 DEBUG --- stderr --- 2024-04-24 07:11:22,749 DEBUG 2024-04-24 07:11:22,749 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/cmd.txt is small (1 lines), printing all lines: 2024-04-24 07:11:22,749 DEBUG ----- output ----- 2024-04-24 07:11:22,749 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-30 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 07:11:22,750 INFO ________________________________________________________________________________ [2024-04-24 07:11:22] mixedquery-30 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 07:11:22,750 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/rc.txt is small (1 lines), printing all lines: 2024-04-24 07:11:22,751 DEBUG ----- output ----- 2024-04-24 07:11:22,751 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 07:11:22,751 INFO ________________________________________________________________________________ [2024-04-24 07:11:22] mixedquery-30 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 07:11:22,752 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/03_mixedquery-30/stdout.txt is 8060 lines): 2024-04-24 07:11:22,752 DEBUG ----- output ----- 2024-04-24 07:11:22,752 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 07:11:22,753 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 07:11:22,753 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO ================================================================================ 2024-04-24 07:11:22,753 INFO 2024-04-24 07:39:02 5s elapsed 2024-04-24 07:11:22,753 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO > Global (OK=0 KO=0 ) 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO [- ] 0% 2024-04-24 07:11:22,753 INFO waiting: 54511 / active: 4 / done: 9 2024-04-24 07:11:22,753 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:11:22,753 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:11:22,753 INFO ================================================================================ 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO ================================================================================ 2024-04-24 07:11:22,753 INFO 2024-04-24 07:39:07 10s elapsed 2024-04-24 07:11:22,753 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO > Global (OK=0 KO=0 ) 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO [- ] 0% 2024-04-24 07:11:22,753 INFO waiting: 54473 / active: 10 / done: 41 2024-04-24 07:11:22,753 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:11:22,753 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:11:22,753 INFO ================================================================================ 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO 2024-04-24 07:11:22,753 INFO ================================================================================ 2024-04-24 07:11:22,753 INFO 2024-04-24 07:39:12 15s elapsed 2024-04-24 07:11:22,753 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,753 INFO > Global (OK=60 KO=0 ) 2024-04-24 07:11:22,753 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:11:22,753 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:11:22,754 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,754 INFO > ReadManagedUser (OK=8 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryBySn (OK=11 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryStartsWith (OK=9 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryPartial (OK=12 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryFull (OK=17 KO=0 ) 2024-04-24 07:11:22,754 INFO 2024-04-24 07:11:22,754 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,754 INFO [- ] 0% 2024-04-24 07:11:22,754 INFO waiting: 54411 / active: 4 / done: 109 2024-04-24 07:11:22,754 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,754 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:11:22,754 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:11:22,754 INFO ================================================================================ 2024-04-24 07:11:22,754 INFO 2024-04-24 07:11:22,754 INFO 2024-04-24 07:11:22,754 INFO ================================================================================ 2024-04-24 07:11:22,754 INFO 2024-04-24 07:39:17 20s elapsed 2024-04-24 07:11:22,754 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,754 INFO > Global (OK=150 KO=0 ) 2024-04-24 07:11:22,754 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:11:22,754 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:11:22,754 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,754 INFO > ReadManagedUser (OK=32 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryBySn (OK=26 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryStartsWith (OK=28 KO=0 ) 2024-04-24 07:11:22,754 INFO > queryPartial (OK=29 KO=0 ) 2024-04-24 07:11:22,755 INFO > queryFull (OK=32 KO=0 ) 2024-04-24 07:11:22,755 INFO 2024-04-24 07:11:22,755 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,755 INFO [- ] 0% 2024-04-24 07:11:22,755 INFO waiting: 54323 / active: 2 / done: 199 2024-04-24 07:11:22,755 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,755 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:11:22,755 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:11:22,755 INFO ================================================================================ 2024-04-24 07:11:22,755 INFO 2024-04-24 07:11:22,755 INFO 2024-04-24 07:11:22,755 INFO ================================================================================ 2024-04-24 07:11:22,755 INFO 2024-04-24 07:39:22 25s elapsed 2024-04-24 07:11:22,755 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,755 INFO > Global (OK=261 KO=0 ) 2024-04-24 07:11:22,755 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:11:22,755 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:11:22,755 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,755 INFO > ReadManagedUser (OK=56 KO=0 ) 2024-04-24 07:11:22,755 INFO > queryBySn (OK=53 KO=0 ) 2024-04-24 07:11:22,755 INFO > queryStartsWith (OK=49 KO=0 ) 2024-04-24 07:11:22,755 INFO > queryPartial (OK=48 KO=0 ) 2024-04-24 07:11:22,755 INFO > queryFull (OK=52 KO=0 ) 2024-04-24 07:11:22,755 INFO 2024-04-24 07:11:22,755 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,755 INFO [- ] 0% 2024-04-24 07:11:22,755 INFO waiting: 54211 / active: 3 / done: 310 2024-04-24 07:11:22,755 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,755 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:11:22,755 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:11:22,755 INFO ================================================================================ 2024-04-24 07:11:22,755 INFO 2024-04-24 07:11:22,755 INFO 2024-04-24 07:11:22,755 INFO [...] 2024-04-24 07:11:22,758 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,758 INFO > ReadManagedUser (OK=10617 KO=0 ) 2024-04-24 07:11:22,758 INFO > queryBySn (OK=10990 KO=0 ) 2024-04-24 07:11:22,758 INFO > queryStartsWith (OK=10911 KO=0 ) 2024-04-24 07:11:22,758 INFO > queryPartial (OK=10729 KO=0 ) 2024-04-24 07:11:22,758 INFO > queryFull (OK=10784 KO=0 ) 2024-04-24 07:11:22,758 INFO 2024-04-24 07:11:22,758 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,758 INFO [#########################################################################-] 99% 2024-04-24 07:11:22,758 INFO waiting: 437 / active: 4 / done: 54083 2024-04-24 07:11:22,758 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,758 INFO [##########################################################################]100% 2024-04-24 07:11:22,758 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:11:22,758 INFO ================================================================================ 2024-04-24 07:11:22,758 INFO 2024-04-24 07:11:22,758 INFO 2024-04-24 07:11:22,758 INFO ================================================================================ 2024-04-24 07:11:22,758 INFO 2024-04-24 08:09:17 1820s elapsed 2024-04-24 07:11:22,758 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,758 INFO > Global (OK=54202 KO=0 ) 2024-04-24 07:11:22,758 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:11:22,758 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:11:22,759 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,759 INFO > ReadManagedUser (OK=10658 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryBySn (OK=11020 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryStartsWith (OK=10944 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryPartial (OK=10754 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryFull (OK=10823 KO=0 ) 2024-04-24 07:11:22,759 INFO 2024-04-24 07:11:22,759 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,759 INFO [#########################################################################-] 99% 2024-04-24 07:11:22,759 INFO waiting: 270 / active: 3 / done: 54251 2024-04-24 07:11:22,759 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,759 INFO [##########################################################################]100% 2024-04-24 07:11:22,759 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:11:22,759 INFO ================================================================================ 2024-04-24 07:11:22,759 INFO 2024-04-24 07:11:22,759 INFO 2024-04-24 07:11:22,759 INFO ================================================================================ 2024-04-24 07:11:22,759 INFO 2024-04-24 08:09:22 1825s elapsed 2024-04-24 07:11:22,759 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,759 INFO > Global (OK=54349 KO=0 ) 2024-04-24 07:11:22,759 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:11:22,759 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:11:22,759 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,759 INFO > ReadManagedUser (OK=10679 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryBySn (OK=11046 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryStartsWith (OK=10975 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryPartial (OK=10792 KO=0 ) 2024-04-24 07:11:22,759 INFO > queryFull (OK=10854 KO=0 ) 2024-04-24 07:11:22,759 INFO 2024-04-24 07:11:22,759 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,759 INFO [#########################################################################-] 99% 2024-04-24 07:11:22,759 INFO waiting: 123 / active: 3 / done: 54398 2024-04-24 07:11:22,759 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,759 INFO [##########################################################################]100% 2024-04-24 07:11:22,759 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:11:22,759 INFO ================================================================================ 2024-04-24 07:11:22,759 INFO 2024-04-24 07:11:22,759 INFO 2024-04-24 07:11:22,759 INFO ================================================================================ 2024-04-24 07:11:22,759 INFO 2024-04-24 08:09:27 1830s elapsed 2024-04-24 07:11:22,760 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:11:22,760 INFO > Global (OK=54475 KO=0 ) 2024-04-24 07:11:22,760 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:11:22,760 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:11:22,760 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:11:22,760 INFO > ReadManagedUser (OK=10703 KO=0 ) 2024-04-24 07:11:22,760 INFO > queryBySn (OK=11071 KO=0 ) 2024-04-24 07:11:22,760 INFO > queryStartsWith (OK=11001 KO=0 ) 2024-04-24 07:11:22,760 INFO > queryPartial (OK=10816 KO=0 ) 2024-04-24 07:11:22,760 INFO > queryFull (OK=10881 KO=0 ) 2024-04-24 07:11:22,760 INFO 2024-04-24 07:11:22,760 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:11:22,760 INFO [##########################################################################]100% 2024-04-24 07:11:22,760 INFO waiting: 0 / active: 0 / done: 54524 2024-04-24 07:11:22,760 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:11:22,760 INFO [##########################################################################]100% 2024-04-24 07:11:22,760 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:11:22,760 INFO ================================================================================ 2024-04-24 07:11:22,760 INFO 2024-04-24 07:11:22,760 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-24 07:11:22,760 INFO 2024-04-24 07:11:22,760 INFO ---------------------- 2024-04-24 07:11:22,760 INFO [24/Apr/2024 08:09:27] Create tar file mixedquery-30.tar.gz 2024-04-24 07:11:22,760 INFO ---------------------- 2024-04-24 07:11:22,760 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 07:11:22,760 INFO RESULT_FOLDER=mixedquery-30 2024-04-24 07:11:22,760 INFO SIMULATION_PATH=mixedquery-30/loadtest-20240424063856741 2024-04-24 07:11:22,760 INFO PWD=/results/gatling/mixedquery-30/loadtest-20240424063856741 2024-04-24 07:11:22,760 INFO [cmd] tar -czvf /results/gatling//mixedquery-30.tar.gz --exclude=simulation.log * 2024-04-24 07:11:22,760 INFO /results/gatling 2024-04-24 07:11:22,760 INFO 2024-04-24 07:11:22,760 INFO ---------------------- 2024-04-24 07:11:22,760 INFO [24/Apr/2024 08:09:27] Update /results/gatling//latest 2024-04-24 07:11:22,760 INFO ---------------------- 2024-04-24 07:11:22,760 INFO 2024-04-24 07:11:22,760 INFO ---------------------- 2024-04-24 07:11:22,760 INFO [24/Apr/2024 08:09:27] Update /results/gatling//latest.tar.gz 2024-04-24 07:11:22,760 INFO ---------------------- 2024-04-24 07:11:22,760 INFO ________________________________________________________________________________ [2024-04-24 07:11:22] mixedquery-30 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 07:11:22,761 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/03_mixedquery-30/stderr.txt is small (0 lines), printing all lines: 2024-04-24 07:11:22,761 DEBUG ----- output ----- 2024-04-24 07:11:22,761 DEBUG 2024-04-24 07:11:22,761 INFO ________________________________________________________________________________ [2024-04-24 07:11:22] mixedquery-30 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 07:11:22] mixedquery-30 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 07:11:22] mixedquery-30 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped