--Task-- name: mixedquery-60 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-50'] preceding_task: mixedquery-50 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 60} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 08:16:31] mixedquery-60 pre : N/A ________________________________________________________________________________ [2024-04-24 08:16:31] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 08:16:31] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-60/stdout.txt Pod log : /results/orders/mixedquery-60 2024-04-24 08:16:31,833 INFO 2024-04-24 08:16:31,833 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:16:31,834 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:32,184 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:32,184 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:32,184 DEBUG --- stdout --- 2024-04-24 08:16:32,184 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:32,184 DEBUG --- stderr --- 2024-04-24 08:16:32,184 DEBUG ________________________________________________________________________________ [2024-04-24 08:16:32] mixedquery-60 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 08:16:32,185 INFO 2024-04-24 08:16:32,185 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:16:32,185 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:32,512 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:32,512 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:32,512 DEBUG --- stdout --- 2024-04-24 08:16:32,512 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:32,512 DEBUG --- stderr --- 2024-04-24 08:16:32,512 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-60 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 08:16:32] mixedquery-60 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 08:16:32,513 INFO 2024-04-24 08:16:32,513 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:16:32,513 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:32,823 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:32,823 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:32,823 DEBUG --- stdout --- 2024-04-24 08:16:32,824 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:32,824 DEBUG --- stderr --- 2024-04-24 08:16:32,824 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/order.json { "name": "mixedquery-60", "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-60 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-60", "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=60 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 08:16:32] mixedquery-60 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:16:32,825 INFO 2024-04-24 08:16:32,825 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 08:16:32,825 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 08:16:33,062 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:16:33,062 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:33,062 DEBUG --- stdout --- 2024-04-24 08:16:33,062 DEBUG WAITING 2024-04-24 08:16:33,062 DEBUG --- stderr --- 2024-04-24 08:16:33,062 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 08:16:33] mixedquery-60 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 08:16:33,063 INFO 2024-04-24 08:16:33,063 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:16:33,063 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:33,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:33,380 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:33,380 DEBUG --- stdout --- 2024-04-24 08:16:33,381 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:33,381 DEBUG --- stderr --- 2024-04-24 08:16:33,381 DEBUG 2024-04-24 08:16:33,381 INFO 2024-04-24 08:16:33,381 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/06_mixedquery-60/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 08:16:33,381 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:16:34,766 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:34,766 DEBUG --- stdout --- 2024-04-24 08:16:34,766 DEBUG 2024-04-24 08:16:34,767 DEBUG --- stderr --- 2024-04-24 08:16:34,767 DEBUG Order has been successfully forwarded to overseer [2024-04-24 08:16:34] - INFO: Check if Gatling simulation started successfully 2024-04-24 08:16:34,767 INFO 2024-04-24 08:16:34,767 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:16:34,767 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:16:35,082 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:16:35,083 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:35,083 DEBUG --- stdout --- 2024-04-24 08:16:35,083 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:16:35,083 DEBUG --- stderr --- 2024-04-24 08:16:35,083 DEBUG 2024-04-24 08:16:35,083 INFO 2024-04-24 08:16:35,083 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-60/stdout.txt | grep "started..." 2024-04-24 08:16:35,083 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 08:16:35,852 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 08:16:41,622 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-24 08:16:41,624 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:16:41,625 DEBUG --- stdout --- 2024-04-24 08:16:41,625 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:16:41,625 DEBUG --- stderr --- 2024-04-24 08:16:41,625 DEBUG ________________________________________________________________________________ [2024-04-24 08:16:41] mixedquery-60 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 36m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry PASS : Order stopped by itself after 32m 05s and before timeout (36m) ________________________________________________________________________________ [2024-04-24 08:48:47] mixedquery-60 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 08:48:47,361 INFO 2024-04-24 08:48:47,361 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:48:47,361 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:47,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:47,705 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:47,705 DEBUG --- stdout --- 2024-04-24 08:48:47,705 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:47,705 DEBUG --- stderr --- 2024-04-24 08:48:47,705 DEBUG 2024-04-24 08:48:47,706 INFO 2024-04-24 08:48:47,706 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m -- rm -f /results/orders/order.json 2024-04-24 08:48:47,706 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:48:48,532 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:48,533 DEBUG --- stdout --- 2024-04-24 08:48:48,533 DEBUG 2024-04-24 08:48:48,533 DEBUG --- stderr --- 2024-04-24 08:48:48,533 DEBUG Order ran during 32m 13s ________________________________________________________________________________ [2024-04-24 08:48:48] mixedquery-60 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 08:48:48] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/order.json 2024-04-24 08:48:48,534 INFO 2024-04-24 08:48:48,534 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:48:48,534 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:48,858 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:48,858 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:48,858 DEBUG --- stdout --- 2024-04-24 08:48:48,858 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:48,858 DEBUG --- stderr --- 2024-04-24 08:48:48,858 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:48:48,858 INFO 2024-04-24 08:48:48,859 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:48:48,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:49,197 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:49,197 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:49,198 DEBUG --- stdout --- 2024-04-24 08:48:49,198 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:49,198 DEBUG --- stderr --- 2024-04-24 08:48:49,198 DEBUG 2024-04-24 08:48:49,199 INFO 2024-04-24 08:48:49,199 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-60/rc.txt" ]; then echo "/results/orders/mixedquery-60/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-60/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-60/rc.txt file found" 2024-04-24 08:48:49,199 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:48:50,075 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:48:50,082 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:50,083 DEBUG --- stdout --- 2024-04-24 08:48:50,084 DEBUG /results/orders/mixedquery-60/rc.txt file found 2024-04-24 08:48:50,084 DEBUG --- stderr --- 2024-04-24 08:48:50,084 DEBUG 2024-04-24 08:48:50,085 INFO 2024-04-24 08:48:50,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 08:48:50,086 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:50,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:50,492 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:50,492 DEBUG --- stdout --- 2024-04-24 08:48:50,492 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:50,492 DEBUG --- stderr --- 2024-04-24 08:48:50,492 DEBUG 2024-04-24 08:48:50,493 INFO 2024-04-24 08:48:50,493 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-60/rc.txt 2024-04-24 08:48:51,352 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:48:51,352 DEBUG --- stdout --- 2024-04-24 08:48:51,352 DEBUG 4 /results/orders/mixedquery-60/rc.txt 2024-04-24 08:48:51,352 DEBUG --- stderr --- 2024-04-24 08:48:51,352 DEBUG 2024-04-24 08:48:51,352 INFO - Download file (size 4k) 2024-04-24 08:48:51,353 INFO 2024-04-24 08:48:51,353 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:48:51,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:51,683 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:51,683 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:51,683 DEBUG --- stdout --- 2024-04-24 08:48:51,684 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:51,684 DEBUG --- stderr --- 2024-04-24 08:48:51,684 DEBUG source : /results/orders/mixedquery-60/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/rc.txt 2024-04-24 08:48:51,684 INFO 2024-04-24 08:48:51,684 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:48:51,684 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:52,011 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:52,011 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:52,011 DEBUG --- stdout --- 2024-04-24 08:48:52,011 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:52,011 DEBUG --- stderr --- 2024-04-24 08:48:52,011 DEBUG 2024-04-24 08:48:52,012 INFO 2024-04-24 08:48:52,012 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-60/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/rc.txt 2024-04-24 08:48:52,012 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:48:52,782 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:52,782 DEBUG --- stdout --- 2024-04-24 08:48:52,782 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:48:52,782 DEBUG --- stderr --- 2024-04-24 08:48:52,782 DEBUG 2024-04-24 08:48:52,783 INFO 2024-04-24 08:48:52,783 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:48:52,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:53,113 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:53,113 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:53,113 DEBUG --- stdout --- 2024-04-24 08:48:53,113 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:53,113 DEBUG --- stderr --- 2024-04-24 08:48:53,113 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:48:53,114 INFO 2024-04-24 08:48:53,114 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:48:53,114 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:53,440 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:53,441 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:53,441 DEBUG --- stdout --- 2024-04-24 08:48:53,441 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:53,441 DEBUG --- stderr --- 2024-04-24 08:48:53,441 DEBUG 2024-04-24 08:48:53,442 INFO 2024-04-24 08:48:53,442 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-60/stderr.txt" ]; then echo "/results/orders/mixedquery-60/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-60/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-60/stderr.txt file found" 2024-04-24 08:48:53,442 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:48:54,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:48:54,318 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:54,318 DEBUG --- stdout --- 2024-04-24 08:48:54,318 DEBUG /results/orders/mixedquery-60/stderr.txt file found 2024-04-24 08:48:54,318 DEBUG --- stderr --- 2024-04-24 08:48:54,318 DEBUG 2024-04-24 08:48:54,319 INFO 2024-04-24 08:48:54,319 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:48:54,319 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:54,721 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:54,722 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:54,722 DEBUG --- stdout --- 2024-04-24 08:48:54,722 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:54,722 DEBUG --- stderr --- 2024-04-24 08:48:54,722 DEBUG 2024-04-24 08:48:54,722 INFO 2024-04-24 08:48:54,722 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-60/stderr.txt 2024-04-24 08:48:55,605 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:48:55,605 DEBUG --- stdout --- 2024-04-24 08:48:55,605 DEBUG 0 /results/orders/mixedquery-60/stderr.txt 2024-04-24 08:48:55,605 DEBUG --- stderr --- 2024-04-24 08:48:55,606 DEBUG 2024-04-24 08:48:55,606 INFO - Download file (size 0k) 2024-04-24 08:48:55,606 INFO 2024-04-24 08:48:55,606 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:48:55,606 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:55,982 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:55,982 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:55,982 DEBUG --- stdout --- 2024-04-24 08:48:55,983 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:55,983 DEBUG --- stderr --- 2024-04-24 08:48:55,983 DEBUG source : /results/orders/mixedquery-60/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/stderr.txt 2024-04-24 08:48:55,983 INFO 2024-04-24 08:48:55,983 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:48:55,983 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:56,335 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:56,335 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:56,335 DEBUG --- stdout --- 2024-04-24 08:48:56,336 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:56,336 DEBUG --- stderr --- 2024-04-24 08:48:56,336 DEBUG 2024-04-24 08:48:56,336 INFO 2024-04-24 08:48:56,337 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-60/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/stderr.txt 2024-04-24 08:48:56,337 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:48:57,136 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:57,136 DEBUG --- stdout --- 2024-04-24 08:48:57,136 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:48:57,136 DEBUG --- stderr --- 2024-04-24 08:48:57,136 DEBUG 2024-04-24 08:48:57,137 INFO 2024-04-24 08:48:57,137 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:48:57,137 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:57,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:57,477 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:57,477 DEBUG --- stdout --- 2024-04-24 08:48:57,478 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:57,478 DEBUG --- stderr --- 2024-04-24 08:48:57,478 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:48:57,478 INFO 2024-04-24 08:48:57,478 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:48:57,478 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:57,815 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:57,815 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:57,815 DEBUG --- stdout --- 2024-04-24 08:48:57,815 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:57,815 DEBUG --- stderr --- 2024-04-24 08:48:57,815 DEBUG 2024-04-24 08:48:57,816 INFO 2024-04-24 08:48:57,816 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-60/stdout.txt" ]; then echo "/results/orders/mixedquery-60/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-60/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-60/stdout.txt file found" 2024-04-24 08:48:57,816 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:48:58,663 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:48:58,663 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:58,663 DEBUG --- stdout --- 2024-04-24 08:48:58,663 DEBUG /results/orders/mixedquery-60/stdout.txt file found 2024-04-24 08:48:58,663 DEBUG --- stderr --- 2024-04-24 08:48:58,663 DEBUG 2024-04-24 08:48:58,664 INFO 2024-04-24 08:48:58,664 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:48:58,664 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:48:58,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:48:58,994 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:48:58,994 DEBUG --- stdout --- 2024-04-24 08:48:58,994 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:48:58,994 DEBUG --- stderr --- 2024-04-24 08:48:58,994 DEBUG 2024-04-24 08:48:58,995 INFO 2024-04-24 08:48:58,995 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-60/stdout.txt 2024-04-24 08:48:59,841 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:48:59,841 DEBUG --- stdout --- 2024-04-24 08:48:59,841 DEBUG 536 /results/orders/mixedquery-60/stdout.txt 2024-04-24 08:48:59,841 DEBUG --- stderr --- 2024-04-24 08:48:59,841 DEBUG 2024-04-24 08:48:59,841 INFO - Download file (size 536k) 2024-04-24 08:48:59,842 INFO 2024-04-24 08:48:59,842 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:48:59,842 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:00,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:00,182 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:00,182 DEBUG --- stdout --- 2024-04-24 08:49:00,182 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:00,182 DEBUG --- stderr --- 2024-04-24 08:49:00,182 DEBUG source : /results/orders/mixedquery-60/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/stdout.txt 2024-04-24 08:49:00,183 INFO 2024-04-24 08:49:00,183 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:00,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:00,519 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:00,520 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:00,520 DEBUG --- stdout --- 2024-04-24 08:49:00,520 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:00,520 DEBUG --- stderr --- 2024-04-24 08:49:00,520 DEBUG 2024-04-24 08:49:00,520 INFO 2024-04-24 08:49:00,520 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-60/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/stdout.txt 2024-04-24 08:49:00,520 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:49:01,679 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:01,680 DEBUG --- stdout --- 2024-04-24 08:49:01,680 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:49:01,680 DEBUG --- stderr --- 2024-04-24 08:49:01,680 DEBUG ________________________________________________________________________________ [2024-04-24 08:49:01] mixedquery-60 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 08:49:01,682 INFO 2024-04-24 08:49:01,682 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:01,682 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:02,100 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:02,101 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:02,101 DEBUG --- stdout --- 2024-04-24 08:49:02,108 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:02,108 DEBUG --- stderr --- 2024-04-24 08:49:02,109 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 08:49:02,110 INFO 2024-04-24 08:49:02,110 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:49:02,110 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:02,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:02,517 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:02,517 DEBUG --- stdout --- 2024-04-24 08:49:02,517 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:02,517 DEBUG --- stderr --- 2024-04-24 08:49:02,517 DEBUG 2024-04-24 08:49:02,518 INFO 2024-04-24 08:49:02,518 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-60/cmd.txt" ]; then echo "/results/orders/mixedquery-60/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-60/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-60/cmd.txt file found" 2024-04-24 08:49:02,518 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 08:49:03,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 08:49:03,426 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:03,426 DEBUG --- stdout --- 2024-04-24 08:49:03,426 DEBUG /results/orders/mixedquery-60/cmd.txt file found 2024-04-24 08:49:03,426 DEBUG --- stderr --- 2024-04-24 08:49:03,426 DEBUG 2024-04-24 08:49:03,426 INFO 2024-04-24 08:49:03,426 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:03,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:03,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:03,752 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:03,752 DEBUG --- stdout --- 2024-04-24 08:49:03,752 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:03,752 DEBUG --- stderr --- 2024-04-24 08:49:03,752 DEBUG 2024-04-24 08:49:03,752 INFO 2024-04-24 08:49:03,753 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-60/cmd.txt 2024-04-24 08:49:04,597 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 08:49:04,597 DEBUG --- stdout --- 2024-04-24 08:49:04,597 DEBUG 4 /results/orders/mixedquery-60/cmd.txt 2024-04-24 08:49:04,597 DEBUG --- stderr --- 2024-04-24 08:49:04,597 DEBUG 2024-04-24 08:49:04,597 INFO - Download file (size 4k) 2024-04-24 08:49:04,598 INFO 2024-04-24 08:49:04,598 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 08:49:04,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:04,925 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:04,925 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:04,925 DEBUG --- stdout --- 2024-04-24 08:49:04,925 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:04,925 DEBUG --- stderr --- 2024-04-24 08:49:04,925 DEBUG source : /results/orders/mixedquery-60/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/cmd.txt 2024-04-24 08:49:04,926 INFO 2024-04-24 08:49:04,926 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:04,926 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 08:49:05,246 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 08:49:05,246 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:05,246 DEBUG --- stdout --- 2024-04-24 08:49:05,247 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 08:49:05,247 DEBUG --- stderr --- 2024-04-24 08:49:05,247 DEBUG 2024-04-24 08:49:05,247 INFO 2024-04-24 08:49:05,247 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-60/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/cmd.txt 2024-04-24 08:49:05,247 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 08:49:06,007 INFO [loop_until]: OK (rc = 0) 2024-04-24 08:49:06,008 DEBUG --- stdout --- 2024-04-24 08:49:06,008 DEBUG tar: Removing leading `/' from member names 2024-04-24 08:49:06,008 DEBUG --- stderr --- 2024-04-24 08:49:06,008 DEBUG 2024-04-24 08:49:06,009 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/cmd.txt is small (1 lines), printing all lines: 2024-04-24 08:49:06,009 DEBUG ----- output ----- 2024-04-24 08:49:06,009 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-60 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 08:49:06,009 INFO ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-60 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 08:49:06,009 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/rc.txt is small (1 lines), printing all lines: 2024-04-24 08:49:06,009 DEBUG ----- output ----- 2024-04-24 08:49:06,009 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 08:49:06,009 INFO ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-60 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 08:49:06,013 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/06_mixedquery-60/stdout.txt is 8082 lines): 2024-04-24 08:49:06,013 DEBUG ----- output ----- 2024-04-24 08:49:06,013 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 08:49:06,013 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 08:49:06,013 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 08:49:06,013 INFO 2024-04-24 08:49:06,013 INFO ================================================================================ 2024-04-24 08:49:06,013 INFO 2024-04-24 09:16:45 5s elapsed 2024-04-24 08:49:06,013 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,013 INFO > Global (OK=0 KO=0 ) 2024-04-24 08:49:06,013 INFO 2024-04-24 08:49:06,013 INFO 2024-04-24 08:49:06,013 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,013 INFO [- ] 0% 2024-04-24 08:49:06,013 INFO waiting: 108480 / active: 9 / done: 17 2024-04-24 08:49:06,013 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,013 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:49:06,013 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:49:06,013 INFO ================================================================================ 2024-04-24 08:49:06,013 INFO 2024-04-24 08:49:06,013 INFO 2024-04-24 08:49:06,014 INFO ================================================================================ 2024-04-24 08:49:06,014 INFO 2024-04-24 09:16:50 10s elapsed 2024-04-24 08:49:06,014 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,014 INFO > Global (OK=0 KO=0 ) 2024-04-24 08:49:06,014 INFO 2024-04-24 08:49:06,014 INFO 2024-04-24 08:49:06,014 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,014 INFO [- ] 0% 2024-04-24 08:49:06,014 INFO waiting: 108405 / active: 19 / done: 82 2024-04-24 08:49:06,014 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,014 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:49:06,014 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:49:06,014 INFO ================================================================================ 2024-04-24 08:49:06,014 INFO 2024-04-24 08:49:06,014 INFO 2024-04-24 08:49:06,014 INFO ================================================================================ 2024-04-24 08:49:06,014 INFO 2024-04-24 09:16:55 15s elapsed 2024-04-24 08:49:06,014 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,014 INFO > Global (OK=123 KO=0 ) 2024-04-24 08:49:06,014 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:49:06,014 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:49:06,014 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,014 INFO > ReadManagedUser (OK=22 KO=0 ) 2024-04-24 08:49:06,014 INFO > queryPartial (OK=19 KO=0 ) 2024-04-24 08:49:06,014 INFO > queryBySn (OK=28 KO=0 ) 2024-04-24 08:49:06,015 INFO > queryStartsWith (OK=27 KO=0 ) 2024-04-24 08:49:06,015 INFO > queryFull (OK=24 KO=0 ) 2024-04-24 08:49:06,015 INFO 2024-04-24 08:49:06,015 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,015 INFO [- ] 0% 2024-04-24 08:49:06,015 INFO waiting: 108280 / active: 2 / done: 224 2024-04-24 08:49:06,015 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,015 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:49:06,015 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:49:06,015 INFO ================================================================================ 2024-04-24 08:49:06,015 INFO 2024-04-24 08:49:06,015 INFO 2024-04-24 08:49:06,015 INFO ================================================================================ 2024-04-24 08:49:06,015 INFO 2024-04-24 09:17:00 20s elapsed 2024-04-24 08:49:06,015 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,015 INFO > Global (OK=296 KO=0 ) 2024-04-24 08:49:06,015 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:49:06,015 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:49:06,015 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,015 INFO > ReadManagedUser (OK=56 KO=0 ) 2024-04-24 08:49:06,015 INFO > queryPartial (OK=50 KO=0 ) 2024-04-24 08:49:06,015 INFO > queryBySn (OK=76 KO=0 ) 2024-04-24 08:49:06,015 INFO > queryStartsWith (OK=52 KO=0 ) 2024-04-24 08:49:06,015 INFO > queryFull (OK=59 KO=0 ) 2024-04-24 08:49:06,015 INFO 2024-04-24 08:49:06,015 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,016 INFO [- ] 0% 2024-04-24 08:49:06,016 INFO waiting: 108105 / active: 4 / done: 397 2024-04-24 08:49:06,016 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,016 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:49:06,016 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:49:06,016 INFO ================================================================================ 2024-04-24 08:49:06,016 INFO 2024-04-24 08:49:06,016 INFO 2024-04-24 08:49:06,016 INFO ================================================================================ 2024-04-24 08:49:06,016 INFO 2024-04-24 09:17:05 25s elapsed 2024-04-24 08:49:06,016 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,016 INFO > Global (OK=523 KO=0 ) 2024-04-24 08:49:06,016 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:49:06,016 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:49:06,016 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,016 INFO > ReadManagedUser (OK=110 KO=0 ) 2024-04-24 08:49:06,016 INFO > queryPartial (OK=89 KO=0 ) 2024-04-24 08:49:06,016 INFO > queryBySn (OK=117 KO=0 ) 2024-04-24 08:49:06,016 INFO > queryStartsWith (OK=99 KO=0 ) 2024-04-24 08:49:06,016 INFO > queryFull (OK=105 KO=0 ) 2024-04-24 08:49:06,016 INFO 2024-04-24 08:49:06,016 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,016 INFO [- ] 0% 2024-04-24 08:49:06,016 INFO waiting: 107880 / active: 2 / done: 624 2024-04-24 08:49:06,016 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,016 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 08:49:06,017 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 08:49:06,017 INFO ================================================================================ 2024-04-24 08:49:06,017 INFO 2024-04-24 08:49:06,017 INFO 2024-04-24 08:49:06,017 INFO [...] 2024-04-24 08:49:06,021 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,021 INFO > ReadManagedUser (OK=21199 KO=0 ) 2024-04-24 08:49:06,021 INFO > queryPartial (OK=21723 KO=0 ) 2024-04-24 08:49:06,021 INFO > queryBySn (OK=21578 KO=0 ) 2024-04-24 08:49:06,021 INFO > queryStartsWith (OK=21698 KO=0 ) 2024-04-24 08:49:06,021 INFO > queryFull (OK=21577 KO=0 ) 2024-04-24 08:49:06,021 INFO 2024-04-24 08:49:06,021 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,021 INFO [#########################################################################-] 99% 2024-04-24 08:49:06,021 INFO waiting: 625 / active: 2 / done: 107879 2024-04-24 08:49:06,021 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,021 INFO [##########################################################################]100% 2024-04-24 08:49:06,021 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:49:06,021 INFO ================================================================================ 2024-04-24 08:49:06,022 INFO 2024-04-24 08:49:06,022 INFO 2024-04-24 08:49:06,022 INFO ================================================================================ 2024-04-24 08:49:06,022 INFO 2024-04-24 09:47:05 1825s elapsed 2024-04-24 08:49:06,022 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,022 INFO > Global (OK=108084 KO=0 ) 2024-04-24 08:49:06,022 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:49:06,022 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:49:06,022 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,022 INFO > ReadManagedUser (OK=21246 KO=0 ) 2024-04-24 08:49:06,022 INFO > queryPartial (OK=21783 KO=0 ) 2024-04-24 08:49:06,022 INFO > queryBySn (OK=21641 KO=0 ) 2024-04-24 08:49:06,022 INFO > queryStartsWith (OK=21765 KO=0 ) 2024-04-24 08:49:06,022 INFO > queryFull (OK=21646 KO=0 ) 2024-04-24 08:49:06,022 INFO 2024-04-24 08:49:06,022 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,022 INFO [#########################################################################-] 99% 2024-04-24 08:49:06,022 INFO waiting: 317 / active: 4 / done: 108185 2024-04-24 08:49:06,022 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,022 INFO [##########################################################################]100% 2024-04-24 08:49:06,023 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:49:06,023 INFO ================================================================================ 2024-04-24 08:49:06,023 INFO 2024-04-24 08:49:06,023 INFO 2024-04-24 08:49:06,023 INFO ================================================================================ 2024-04-24 08:49:06,023 INFO 2024-04-24 09:47:10 1830s elapsed 2024-04-24 08:49:06,023 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,023 INFO > Global (OK=108394 KO=0 ) 2024-04-24 08:49:06,023 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:49:06,023 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:49:06,023 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,023 INFO > ReadManagedUser (OK=21309 KO=0 ) 2024-04-24 08:49:06,023 INFO > queryPartial (OK=21842 KO=0 ) 2024-04-24 08:49:06,023 INFO > queryBySn (OK=21706 KO=0 ) 2024-04-24 08:49:06,023 INFO > queryStartsWith (OK=21823 KO=0 ) 2024-04-24 08:49:06,023 INFO > queryFull (OK=21711 KO=0 ) 2024-04-24 08:49:06,023 INFO 2024-04-24 08:49:06,023 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,023 INFO [#########################################################################-] 99% 2024-04-24 08:49:06,023 INFO waiting: 0 / active: 11 / done: 108495 2024-04-24 08:49:06,023 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,023 INFO [##########################################################################]100% 2024-04-24 08:49:06,023 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:49:06,024 INFO ================================================================================ 2024-04-24 08:49:06,024 INFO 2024-04-24 08:49:06,024 INFO 2024-04-24 08:49:06,024 INFO ================================================================================ 2024-04-24 08:49:06,024 INFO 2024-04-24 09:47:10 1830s elapsed 2024-04-24 08:49:06,024 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 08:49:06,024 INFO > Global (OK=108405 KO=0 ) 2024-04-24 08:49:06,024 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 08:49:06,024 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 08:49:06,024 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 08:49:06,024 INFO > ReadManagedUser (OK=21309 KO=0 ) 2024-04-24 08:49:06,024 INFO > queryPartial (OK=21848 KO=0 ) 2024-04-24 08:49:06,024 INFO > queryBySn (OK=21706 KO=0 ) 2024-04-24 08:49:06,024 INFO > queryStartsWith (OK=21828 KO=0 ) 2024-04-24 08:49:06,024 INFO > queryFull (OK=21711 KO=0 ) 2024-04-24 08:49:06,024 INFO 2024-04-24 08:49:06,024 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 08:49:06,024 INFO [##########################################################################]100% 2024-04-24 08:49:06,024 INFO waiting: 0 / active: 0 / done: 108506 2024-04-24 08:49:06,024 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 08:49:06,024 INFO [##########################################################################]100% 2024-04-24 08:49:06,025 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 08:49:06,025 INFO ================================================================================ 2024-04-24 08:49:06,025 INFO 2024-04-24 08:49:06,025 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-24 08:49:06,025 INFO 2024-04-24 08:49:06,025 INFO ---------------------- 2024-04-24 08:49:06,025 INFO [24/Apr/2024 09:47:10] Create tar file mixedquery-60.tar.gz 2024-04-24 08:49:06,025 INFO ---------------------- 2024-04-24 08:49:06,025 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 08:49:06,025 INFO RESULT_FOLDER=mixedquery-60 2024-04-24 08:49:06,025 INFO SIMULATION_PATH=mixedquery-60/loadtest-20240424081639946 2024-04-24 08:49:06,025 INFO PWD=/results/gatling/mixedquery-60/loadtest-20240424081639946 2024-04-24 08:49:06,025 INFO [cmd] tar -czvf /results/gatling//mixedquery-60.tar.gz --exclude=simulation.log * 2024-04-24 08:49:06,025 INFO /results/gatling 2024-04-24 08:49:06,025 INFO 2024-04-24 08:49:06,025 INFO ---------------------- 2024-04-24 08:49:06,025 INFO [24/Apr/2024 09:47:10] Update /results/gatling//latest 2024-04-24 08:49:06,025 INFO ---------------------- 2024-04-24 08:49:06,025 INFO 2024-04-24 08:49:06,025 INFO ---------------------- 2024-04-24 08:49:06,025 INFO [24/Apr/2024 09:47:10] Update /results/gatling//latest.tar.gz 2024-04-24 08:49:06,026 INFO ---------------------- 2024-04-24 08:49:06,026 INFO ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-60 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 08:49:06,026 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/06_mixedquery-60/stderr.txt is small (0 lines), printing all lines: 2024-04-24 08:49:06,026 DEBUG ----- output ----- 2024-04-24 08:49:06,026 DEBUG 2024-04-24 08:49:06,026 INFO ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-60 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-60 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 08:49:06] mixedquery-60 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped