--Task-- name: mixedquery-70 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-60'] preceding_task: mixedquery-60 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 70} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-70 pre : N/A ________________________________________________________________________________ [2024-04-24 08:49:06] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 08:49:06] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-70/stdout.txt Pod log : /results/orders/mixedquery-70 2024-04-24 08:49:06,948 INFO 2024-04-24 08:49:06,949 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:49:06,949 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:07,284 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:07,284 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:07,284 DEBUG --- stdout --- 2024-04-24 08:49:07,284 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:07,284 DEBUG --- stderr --- 2024-04-24 08:49:07,284 DEBUG ________________________________________________________________________________ [2024-04-24 08:49:07] mixedquery-70 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 08:49:07,285 INFO 2024-04-24 08:49:07,285 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:49:07,285 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:07,625 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:07,625 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:07,625 DEBUG --- stdout --- 2024-04-24 08:49:07,625 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:07,626 DEBUG --- stderr --- 2024-04-24 08:49:07,626 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-70 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 08:49:07] mixedquery-70 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 08:49:07,627 INFO 2024-04-24 08:49:07,627 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:49:07,627 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:07,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:07,984 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:07,984 DEBUG --- stdout --- 2024-04-24 08:49:07,984 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:07,984 DEBUG --- stderr --- 2024-04-24 08:49:07,984 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/order.json { "name": "mixedquery-70", "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-70 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-70", "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=70 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 08:49:07] mixedquery-70 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 08:49:07,987 INFO 2024-04-24 08:49:07,987 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 08:49:07,987 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 08:49:08,251 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:49:08,252 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:08,252 DEBUG --- stdout --- 2024-04-24 08:49:08,252 DEBUG WAITING 2024-04-24 08:49:08,252 DEBUG --- stderr --- 2024-04-24 08:49:08,253 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-24 08:49:08] mixedquery-70 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 08:49:08,254 INFO 2024-04-24 08:49:08,254 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:49:08,254 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:08,652 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:08,652 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:08,652 DEBUG --- stdout --- 2024-04-24 08:49:08,653 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:08,653 DEBUG --- stderr --- 2024-04-24 08:49:08,653 DEBUG 2024-04-24 08:49:08,653 INFO 2024-04-24 08:49:08,653 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/07_mixedquery-70/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 08:49:08,654 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:49:10,117 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:10,118 DEBUG --- stdout --- 2024-04-24 08:49:10,118 DEBUG 2024-04-24 08:49:10,118 DEBUG --- stderr --- 2024-04-24 08:49:10,118 DEBUG Order has been successfully forwarded to overseer [2024-04-24 08:49:10] - INFO: Check if Gatling simulation started successfully 2024-04-24 08:49:10,118 INFO 2024-04-24 08:49:10,118 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:49:10,119 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:10,450 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:10,451 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:10,451 DEBUG --- stdout --- 2024-04-24 08:49:10,451 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:10,451 DEBUG --- stderr --- 2024-04-24 08:49:10,451 DEBUG 2024-04-24 08:49:10,451 INFO 2024-04-24 08:49:10,451 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-70/stdout.txt | grep "started..." 2024-04-24 08:49:10,451 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 08:49:11,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 08:49:17,081 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-24 08:49:17,082 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:17,082 DEBUG --- stdout --- 2024-04-24 08:49:17,082 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 08:49:17,082 DEBUG --- stderr --- 2024-04-24 08:49:17,082 DEBUG ________________________________________________________________________________ [2024-04-24 08:49:17] mixedquery-70 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 05s, retry Order is running since 30m 05s, retry PASS : Order stopped by itself after 32m 05s and before timeout (36m) ________________________________________________________________________________ [2024-04-24 09:21:22] mixedquery-70 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 09:21:22,881 INFO 2024-04-24 09:21:22,882 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 09:21:22,882 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:23,254 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:23,254 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:23,254 DEBUG --- stdout --- 2024-04-24 09:21:23,255 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:23,255 DEBUG --- stderr --- 2024-04-24 09:21:23,255 DEBUG 2024-04-24 09:21:23,255 INFO 2024-04-24 09:21:23,255 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 09:21:23,255 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:21:24,085 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:24,085 DEBUG --- stdout --- 2024-04-24 09:21:24,085 DEBUG 2024-04-24 09:21:24,085 DEBUG --- stderr --- 2024-04-24 09:21:24,085 DEBUG Order ran during 32m 13s ________________________________________________________________________________ [2024-04-24 09:21:24] mixedquery-70 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 09:21:24] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/order.json 2024-04-24 09:21:24,086 INFO 2024-04-24 09:21:24,086 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 09:21:24,086 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:24,406 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:24,406 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:24,407 DEBUG --- stdout --- 2024-04-24 09:21:24,407 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:24,407 DEBUG --- stderr --- 2024-04-24 09:21:24,407 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:21:24,407 INFO 2024-04-24 09:21:24,407 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:21:24,407 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:24,722 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:24,722 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:24,722 DEBUG --- stdout --- 2024-04-24 09:21:24,723 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:24,723 DEBUG --- stderr --- 2024-04-24 09:21:24,723 DEBUG 2024-04-24 09:21:24,723 INFO 2024-04-24 09:21:24,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-70/rc.txt" ]; then echo "/results/orders/mixedquery-70/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-70/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-70/rc.txt file found" 2024-04-24 09:21:24,723 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:21:25,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:21:25,561 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:25,561 DEBUG --- stdout --- 2024-04-24 09:21:25,561 DEBUG /results/orders/mixedquery-70/rc.txt file found 2024-04-24 09:21:25,561 DEBUG --- stderr --- 2024-04-24 09:21:25,562 DEBUG 2024-04-24 09:21:25,562 INFO 2024-04-24 09:21:25,562 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 09:21:25,562 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:25,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:25,943 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:25,943 DEBUG --- stdout --- 2024-04-24 09:21:25,943 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:25,943 DEBUG --- stderr --- 2024-04-24 09:21:25,943 DEBUG 2024-04-24 09:21:25,943 INFO 2024-04-24 09:21:25,943 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-70/rc.txt 2024-04-24 09:21:26,774 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:21:26,774 DEBUG --- stdout --- 2024-04-24 09:21:26,775 DEBUG 4 /results/orders/mixedquery-70/rc.txt 2024-04-24 09:21:26,775 DEBUG --- stderr --- 2024-04-24 09:21:26,775 DEBUG 2024-04-24 09:21:26,775 INFO - Download file (size 4k) 2024-04-24 09:21:26,775 INFO 2024-04-24 09:21:26,775 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 09:21:26,775 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:27,150 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:27,150 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:27,150 DEBUG --- stdout --- 2024-04-24 09:21:27,152 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:27,152 DEBUG --- stderr --- 2024-04-24 09:21:27,152 DEBUG source : /results/orders/mixedquery-70/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/rc.txt 2024-04-24 09:21:27,154 INFO 2024-04-24 09:21:27,154 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 09:21:27,155 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:27,550 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:27,550 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:27,550 DEBUG --- stdout --- 2024-04-24 09:21:27,550 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:27,550 DEBUG --- stderr --- 2024-04-24 09:21:27,550 DEBUG 2024-04-24 09:21:27,551 INFO 2024-04-24 09:21:27,551 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-70/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/rc.txt 2024-04-24 09:21:27,551 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:21:28,366 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:28,367 DEBUG --- stdout --- 2024-04-24 09:21:28,367 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:21:28,367 DEBUG --- stderr --- 2024-04-24 09:21:28,367 DEBUG 2024-04-24 09:21:28,367 INFO 2024-04-24 09:21:28,367 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 09:21:28,367 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:28,687 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:28,687 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:28,687 DEBUG --- stdout --- 2024-04-24 09:21:28,687 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:28,687 DEBUG --- stderr --- 2024-04-24 09:21:28,687 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:21:28,688 INFO 2024-04-24 09:21:28,688 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 09:21:28,688 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:29,013 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:29,013 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:29,013 DEBUG --- stdout --- 2024-04-24 09:21:29,013 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:29,013 DEBUG --- stderr --- 2024-04-24 09:21:29,013 DEBUG 2024-04-24 09:21:29,014 INFO 2024-04-24 09:21:29,014 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-70/stderr.txt" ]; then echo "/results/orders/mixedquery-70/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-70/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-70/stderr.txt file found" 2024-04-24 09:21:29,014 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:21:29,865 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:21:29,866 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:29,866 DEBUG --- stdout --- 2024-04-24 09:21:29,866 DEBUG /results/orders/mixedquery-70/stderr.txt file found 2024-04-24 09:21:29,866 DEBUG --- stderr --- 2024-04-24 09:21:29,866 DEBUG 2024-04-24 09:21:29,866 INFO 2024-04-24 09:21:29,866 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 09:21:29,866 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:30,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:30,190 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:30,190 DEBUG --- stdout --- 2024-04-24 09:21:30,190 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:30,190 DEBUG --- stderr --- 2024-04-24 09:21:30,190 DEBUG 2024-04-24 09:21:30,190 INFO 2024-04-24 09:21:30,190 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-70/stderr.txt 2024-04-24 09:21:31,039 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:21:31,040 DEBUG --- stdout --- 2024-04-24 09:21:31,040 DEBUG 0 /results/orders/mixedquery-70/stderr.txt 2024-04-24 09:21:31,040 DEBUG --- stderr --- 2024-04-24 09:21:31,040 DEBUG 2024-04-24 09:21:31,040 INFO - Download file (size 0k) 2024-04-24 09:21:31,041 INFO 2024-04-24 09:21:31,041 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 09:21:31,041 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:31,371 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:31,371 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:31,371 DEBUG --- stdout --- 2024-04-24 09:21:31,372 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:31,372 DEBUG --- stderr --- 2024-04-24 09:21:31,372 DEBUG source : /results/orders/mixedquery-70/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/stderr.txt 2024-04-24 09:21:31,372 INFO 2024-04-24 09:21:31,372 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 09:21:31,372 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:31,703 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:31,704 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:31,704 DEBUG --- stdout --- 2024-04-24 09:21:31,704 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:31,704 DEBUG --- stderr --- 2024-04-24 09:21:31,704 DEBUG 2024-04-24 09:21:31,705 INFO 2024-04-24 09:21:31,705 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-70/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/stderr.txt 2024-04-24 09:21:31,705 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:21:32,548 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:32,549 DEBUG --- stdout --- 2024-04-24 09:21:32,550 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:21:32,550 DEBUG --- stderr --- 2024-04-24 09:21:32,550 DEBUG 2024-04-24 09:21:32,552 INFO 2024-04-24 09:21:32,552 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:21:32,552 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:32,940 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:32,940 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:32,940 DEBUG --- stdout --- 2024-04-24 09:21:32,940 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:32,940 DEBUG --- stderr --- 2024-04-24 09:21:32,940 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:21:32,941 INFO 2024-04-24 09:21:32,941 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 09:21:32,941 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:33,304 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:33,304 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:33,304 DEBUG --- stdout --- 2024-04-24 09:21:33,304 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:33,304 DEBUG --- stderr --- 2024-04-24 09:21:33,304 DEBUG 2024-04-24 09:21:33,305 INFO 2024-04-24 09:21:33,305 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-70/stdout.txt" ]; then echo "/results/orders/mixedquery-70/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-70/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-70/stdout.txt file found" 2024-04-24 09:21:33,305 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:21:34,186 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:21:34,187 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:34,187 DEBUG --- stdout --- 2024-04-24 09:21:34,187 DEBUG /results/orders/mixedquery-70/stdout.txt file found 2024-04-24 09:21:34,187 DEBUG --- stderr --- 2024-04-24 09:21:34,187 DEBUG 2024-04-24 09:21:34,187 INFO 2024-04-24 09:21:34,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 09:21:34,187 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:34,540 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:34,540 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:34,540 DEBUG --- stdout --- 2024-04-24 09:21:34,540 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:34,540 DEBUG --- stderr --- 2024-04-24 09:21:34,540 DEBUG 2024-04-24 09:21:34,540 INFO 2024-04-24 09:21:34,541 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-70/stdout.txt 2024-04-24 09:21:35,381 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:21:35,381 DEBUG --- stdout --- 2024-04-24 09:21:35,381 DEBUG 540 /results/orders/mixedquery-70/stdout.txt 2024-04-24 09:21:35,381 DEBUG --- stderr --- 2024-04-24 09:21:35,381 DEBUG 2024-04-24 09:21:35,381 INFO - Download file (size 540k) 2024-04-24 09:21:35,382 INFO 2024-04-24 09:21:35,382 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 09:21:35,382 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:35,711 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:35,712 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:35,712 DEBUG --- stdout --- 2024-04-24 09:21:35,712 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:35,712 DEBUG --- stderr --- 2024-04-24 09:21:35,712 DEBUG source : /results/orders/mixedquery-70/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/stdout.txt 2024-04-24 09:21:35,712 INFO 2024-04-24 09:21:35,712 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 09:21:35,712 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:36,038 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:36,039 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:36,039 DEBUG --- stdout --- 2024-04-24 09:21:36,039 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:36,039 DEBUG --- stderr --- 2024-04-24 09:21:36,039 DEBUG 2024-04-24 09:21:36,040 INFO 2024-04-24 09:21:36,040 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-70/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/stdout.txt 2024-04-24 09:21:36,040 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:21:37,129 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:37,129 DEBUG --- stdout --- 2024-04-24 09:21:37,129 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:21:37,130 DEBUG --- stderr --- 2024-04-24 09:21:37,130 DEBUG ________________________________________________________________________________ [2024-04-24 09:21:37] mixedquery-70 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 09:21:37,130 INFO 2024-04-24 09:21:37,131 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 09:21:37,131 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:37,455 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:37,455 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:37,455 DEBUG --- stdout --- 2024-04-24 09:21:37,455 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:37,455 DEBUG --- stderr --- 2024-04-24 09:21:37,455 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:21:37,456 INFO 2024-04-24 09:21:37,456 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 09:21:37,456 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:37,806 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:37,807 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:37,808 DEBUG --- stdout --- 2024-04-24 09:21:37,808 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:37,808 DEBUG --- stderr --- 2024-04-24 09:21:37,808 DEBUG 2024-04-24 09:21:37,809 INFO 2024-04-24 09:21:37,809 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-70/cmd.txt" ]; then echo "/results/orders/mixedquery-70/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-70/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-70/cmd.txt file found" 2024-04-24 09:21:37,809 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:21:38,699 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:21:38,699 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:38,699 DEBUG --- stdout --- 2024-04-24 09:21:38,699 DEBUG /results/orders/mixedquery-70/cmd.txt file found 2024-04-24 09:21:38,699 DEBUG --- stderr --- 2024-04-24 09:21:38,699 DEBUG 2024-04-24 09:21:38,700 INFO 2024-04-24 09:21:38,700 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 09:21:38,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:39,077 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:39,078 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:39,078 DEBUG --- stdout --- 2024-04-24 09:21:39,078 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:39,078 DEBUG --- stderr --- 2024-04-24 09:21:39,078 DEBUG 2024-04-24 09:21:39,078 INFO 2024-04-24 09:21:39,079 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-70/cmd.txt 2024-04-24 09:21:39,988 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:21:39,988 DEBUG --- stdout --- 2024-04-24 09:21:39,988 DEBUG 4 /results/orders/mixedquery-70/cmd.txt 2024-04-24 09:21:39,988 DEBUG --- stderr --- 2024-04-24 09:21:39,988 DEBUG 2024-04-24 09:21:39,988 INFO - Download file (size 4k) 2024-04-24 09:21:39,989 INFO 2024-04-24 09:21:39,989 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 09:21:39,989 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:40,347 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:40,348 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:40,348 DEBUG --- stdout --- 2024-04-24 09:21:40,348 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:40,348 DEBUG --- stderr --- 2024-04-24 09:21:40,348 DEBUG source : /results/orders/mixedquery-70/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/cmd.txt 2024-04-24 09:21:40,349 INFO 2024-04-24 09:21:40,349 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 09:21:40,349 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:40,683 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:40,683 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:40,683 DEBUG --- stdout --- 2024-04-24 09:21:40,683 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:40,683 DEBUG --- stderr --- 2024-04-24 09:21:40,683 DEBUG 2024-04-24 09:21:40,683 INFO 2024-04-24 09:21:40,683 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-70/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/cmd.txt 2024-04-24 09:21:40,684 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:21:41,445 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:41,445 DEBUG --- stdout --- 2024-04-24 09:21:41,446 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:21:41,446 DEBUG --- stderr --- 2024-04-24 09:21:41,446 DEBUG 2024-04-24 09:21:41,446 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/cmd.txt is small (1 lines), printing all lines: 2024-04-24 09:21:41,446 DEBUG ----- output ----- 2024-04-24 09:21:41,446 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-70 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 09:21:41,446 INFO ________________________________________________________________________________ [2024-04-24 09:21:41] mixedquery-70 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 09:21:41,447 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:21:41,447 DEBUG ----- output ----- 2024-04-24 09:21:41,447 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:21:41,447 INFO ________________________________________________________________________________ [2024-04-24 09:21:41] mixedquery-70 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 09:21:41,448 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/07_mixedquery-70/stdout.txt is 8082 lines): 2024-04-24 09:21:41,448 DEBUG ----- output ----- 2024-04-24 09:21:41,448 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 09:21:41,448 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 09:21:41,448 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 09:21:41,448 INFO 2024-04-24 09:21:41,448 INFO ================================================================================ 2024-04-24 09:21:41,448 INFO 2024-04-24 09:49:20 5s elapsed 2024-04-24 09:21:41,448 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO > Global (OK=0 KO=0 ) 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO [- ] 0% 2024-04-24 09:21:41,449 INFO waiting: 127292 / active: 11 / done: 19 2024-04-24 09:21:41,449 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:21:41,449 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:21:41,449 INFO ================================================================================ 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO ================================================================================ 2024-04-24 09:21:41,449 INFO 2024-04-24 09:49:25 10s elapsed 2024-04-24 09:21:41,449 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO > Global (OK=0 KO=0 ) 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO [- ] 0% 2024-04-24 09:21:41,449 INFO waiting: 127205 / active: 22 / done: 95 2024-04-24 09:21:41,449 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:21:41,449 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:21:41,449 INFO ================================================================================ 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO 2024-04-24 09:21:41,449 INFO ================================================================================ 2024-04-24 09:21:41,449 INFO 2024-04-24 09:49:30 15s elapsed 2024-04-24 09:21:41,449 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,449 INFO > Global (OK=140 KO=0 ) 2024-04-24 09:21:41,449 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:21:41,449 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:21:41,449 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,449 INFO > queryFull (OK=21 KO=0 ) 2024-04-24 09:21:41,449 INFO > queryBySn (OK=32 KO=0 ) 2024-04-24 09:21:41,449 INFO > queryStartsWith (OK=29 KO=0 ) 2024-04-24 09:21:41,449 INFO > ReadManagedUser (OK=23 KO=0 ) 2024-04-24 09:21:41,449 INFO > queryPartial (OK=32 KO=0 ) 2024-04-24 09:21:41,450 INFO 2024-04-24 09:21:41,450 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,450 INFO [- ] 0% 2024-04-24 09:21:41,450 INFO waiting: 127059 / active: 5 / done: 258 2024-04-24 09:21:41,450 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,450 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:21:41,450 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:21:41,450 INFO ================================================================================ 2024-04-24 09:21:41,450 INFO 2024-04-24 09:21:41,450 INFO 2024-04-24 09:21:41,450 INFO ================================================================================ 2024-04-24 09:21:41,450 INFO 2024-04-24 09:49:35 20s elapsed 2024-04-24 09:21:41,450 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,450 INFO > Global (OK=343 KO=0 ) 2024-04-24 09:21:41,450 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:21:41,450 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:21:41,450 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,450 INFO > queryFull (OK=58 KO=0 ) 2024-04-24 09:21:41,451 INFO > queryBySn (OK=77 KO=0 ) 2024-04-24 09:21:41,451 INFO > queryStartsWith (OK=65 KO=0 ) 2024-04-24 09:21:41,451 INFO > ReadManagedUser (OK=59 KO=0 ) 2024-04-24 09:21:41,451 INFO > queryPartial (OK=81 KO=0 ) 2024-04-24 09:21:41,451 INFO 2024-04-24 09:21:41,451 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,451 INFO [- ] 0% 2024-04-24 09:21:41,451 INFO waiting: 126855 / active: 6 / done: 461 2024-04-24 09:21:41,451 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,451 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:21:41,451 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:21:41,451 INFO ================================================================================ 2024-04-24 09:21:41,451 INFO 2024-04-24 09:21:41,451 INFO 2024-04-24 09:21:41,451 INFO ================================================================================ 2024-04-24 09:21:41,451 INFO 2024-04-24 09:49:40 25s elapsed 2024-04-24 09:21:41,451 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,451 INFO > Global (OK=603 KO=0 ) 2024-04-24 09:21:41,451 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:21:41,451 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:21:41,451 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,451 INFO > queryFull (OK=115 KO=0 ) 2024-04-24 09:21:41,451 INFO > queryBySn (OK=113 KO=0 ) 2024-04-24 09:21:41,451 INFO > queryStartsWith (OK=113 KO=0 ) 2024-04-24 09:21:41,451 INFO > ReadManagedUser (OK=119 KO=0 ) 2024-04-24 09:21:41,452 INFO > queryPartial (OK=140 KO=0 ) 2024-04-24 09:21:41,452 INFO 2024-04-24 09:21:41,452 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,452 INFO [- ] 0% 2024-04-24 09:21:41,452 INFO waiting: 126592 / active: 9 / done: 721 2024-04-24 09:21:41,452 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,452 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:21:41,452 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:21:41,452 INFO ================================================================================ 2024-04-24 09:21:41,452 INFO 2024-04-24 09:21:41,452 INFO 2024-04-24 09:21:41,452 INFO [...] 2024-04-24 09:21:41,455 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryFull (OK=25163 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryBySn (OK=25186 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryStartsWith (OK=25473 KO=0 ) 2024-04-24 09:21:41,455 INFO > ReadManagedUser (OK=25439 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryPartial (OK=25218 KO=0 ) 2024-04-24 09:21:41,455 INFO 2024-04-24 09:21:41,455 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,455 INFO [#########################################################################-] 99% 2024-04-24 09:21:41,455 INFO waiting: 711 / active: 11 / done: 126600 2024-04-24 09:21:41,455 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,455 INFO [##########################################################################]100% 2024-04-24 09:21:41,455 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:21:41,455 INFO ================================================================================ 2024-04-24 09:21:41,455 INFO 2024-04-24 09:21:41,455 INFO 2024-04-24 09:21:41,455 INFO ================================================================================ 2024-04-24 09:21:41,455 INFO 2024-04-24 10:19:40 1825s elapsed 2024-04-24 09:21:41,455 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,455 INFO > Global (OK=126851 KO=0 ) 2024-04-24 09:21:41,455 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:21:41,455 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:21:41,455 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryFull (OK=25220 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryBySn (OK=25261 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryStartsWith (OK=25561 KO=0 ) 2024-04-24 09:21:41,455 INFO > ReadManagedUser (OK=25509 KO=0 ) 2024-04-24 09:21:41,455 INFO > queryPartial (OK=25297 KO=0 ) 2024-04-24 09:21:41,455 INFO 2024-04-24 09:21:41,456 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,456 INFO [#########################################################################-] 99% 2024-04-24 09:21:41,456 INFO waiting: 350 / active: 3 / done: 126969 2024-04-24 09:21:41,456 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,456 INFO [##########################################################################]100% 2024-04-24 09:21:41,456 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:21:41,456 INFO ================================================================================ 2024-04-24 09:21:41,456 INFO 2024-04-24 09:21:41,456 INFO 2024-04-24 09:21:41,456 INFO ================================================================================ 2024-04-24 09:21:41,456 INFO 2024-04-24 10:19:45 1830s elapsed 2024-04-24 09:21:41,456 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,456 INFO > Global (OK=127199 KO=0 ) 2024-04-24 09:21:41,456 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:21:41,456 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:21:41,456 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,456 INFO > queryFull (OK=25285 KO=0 ) 2024-04-24 09:21:41,456 INFO > queryBySn (OK=25330 KO=0 ) 2024-04-24 09:21:41,456 INFO > queryStartsWith (OK=25622 KO=0 ) 2024-04-24 09:21:41,456 INFO > ReadManagedUser (OK=25589 KO=0 ) 2024-04-24 09:21:41,456 INFO > queryPartial (OK=25370 KO=0 ) 2024-04-24 09:21:41,456 INFO 2024-04-24 09:21:41,456 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,456 INFO [#########################################################################-] 99% 2024-04-24 09:21:41,456 INFO waiting: 0 / active: 5 / done: 127317 2024-04-24 09:21:41,456 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,456 INFO [##########################################################################]100% 2024-04-24 09:21:41,456 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:21:41,456 INFO ================================================================================ 2024-04-24 09:21:41,456 INFO 2024-04-24 09:21:41,456 INFO 2024-04-24 09:21:41,456 INFO ================================================================================ 2024-04-24 09:21:41,456 INFO 2024-04-24 10:19:45 1830s elapsed 2024-04-24 09:21:41,456 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:21:41,456 INFO > Global (OK=127204 KO=0 ) 2024-04-24 09:21:41,456 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:21:41,456 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:21:41,456 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:21:41,456 INFO > queryFull (OK=25285 KO=0 ) 2024-04-24 09:21:41,457 INFO > queryBySn (OK=25330 KO=0 ) 2024-04-24 09:21:41,457 INFO > queryStartsWith (OK=25625 KO=0 ) 2024-04-24 09:21:41,457 INFO > ReadManagedUser (OK=25589 KO=0 ) 2024-04-24 09:21:41,457 INFO > queryPartial (OK=25372 KO=0 ) 2024-04-24 09:21:41,457 INFO 2024-04-24 09:21:41,457 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:21:41,457 INFO [##########################################################################]100% 2024-04-24 09:21:41,457 INFO waiting: 0 / active: 0 / done: 127322 2024-04-24 09:21:41,457 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:21:41,457 INFO [##########################################################################]100% 2024-04-24 09:21:41,457 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:21:41,457 INFO ================================================================================ 2024-04-24 09:21:41,457 INFO 2024-04-24 09:21:41,457 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-24 09:21:41,457 INFO 2024-04-24 09:21:41,457 INFO ---------------------- 2024-04-24 09:21:41,457 INFO [24/Apr/2024 10:19:45] Create tar file mixedquery-70.tar.gz 2024-04-24 09:21:41,457 INFO ---------------------- 2024-04-24 09:21:41,457 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 09:21:41,457 INFO RESULT_FOLDER=mixedquery-70 2024-04-24 09:21:41,457 INFO SIMULATION_PATH=mixedquery-70/loadtest-20240424084914970 2024-04-24 09:21:41,457 INFO PWD=/results/gatling/mixedquery-70/loadtest-20240424084914970 2024-04-24 09:21:41,457 INFO [cmd] tar -czvf /results/gatling//mixedquery-70.tar.gz --exclude=simulation.log * 2024-04-24 09:21:41,457 INFO /results/gatling 2024-04-24 09:21:41,457 INFO 2024-04-24 09:21:41,457 INFO ---------------------- 2024-04-24 09:21:41,457 INFO [24/Apr/2024 10:19:45] Update /results/gatling//latest 2024-04-24 09:21:41,457 INFO ---------------------- 2024-04-24 09:21:41,457 INFO 2024-04-24 09:21:41,457 INFO ---------------------- 2024-04-24 09:21:41,457 INFO [24/Apr/2024 10:19:45] Update /results/gatling//latest.tar.gz 2024-04-24 09:21:41,457 INFO ---------------------- 2024-04-24 09:21:41,457 INFO ________________________________________________________________________________ [2024-04-24 09:21:41] mixedquery-70 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 09:21:41,459 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/07_mixedquery-70/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:21:41,459 DEBUG ----- output ----- 2024-04-24 09:21:41,459 DEBUG 2024-04-24 09:21:41,459 INFO ________________________________________________________________________________ [2024-04-24 09:21:41] mixedquery-70 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 09:21:41] mixedquery-70 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 09:21:41] mixedquery-70 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped