--Task-- name: mixedquery-80 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-70'] preceding_task: mixedquery-70 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 80} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 09:21:42] mixedquery-80 pre : N/A ________________________________________________________________________________ [2024-04-24 09:21:42] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 09:21:42] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-80/stdout.txt Pod log : /results/orders/mixedquery-80 2024-04-24 09:21:42,372 INFO 2024-04-24 09:21:42,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:42,372 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:42,699 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:42,699 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:42,699 DEBUG --- stdout --- 2024-04-24 09:21:42,700 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:42,700 DEBUG --- stderr --- 2024-04-24 09:21:42,700 DEBUG ________________________________________________________________________________ [2024-04-24 09:21:42] mixedquery-80 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 09:21:42,700 INFO 2024-04-24 09:21:42,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:42,700 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:43,023 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:43,025 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:43,025 DEBUG --- stdout --- 2024-04-24 09:21:43,025 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:43,025 DEBUG --- stderr --- 2024-04-24 09:21:43,025 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-80 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 09:21:43] mixedquery-80 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 09:21:43,026 INFO 2024-04-24 09:21:43,026 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:21:43,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:43,345 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:43,345 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:43,345 DEBUG --- stdout --- 2024-04-24 09:21:43,346 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:43,346 DEBUG --- stderr --- 2024-04-24 09:21:43,346 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/order.json { "name": "mixedquery-80", "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-80 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-80", "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=80 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 09:21:43] mixedquery-80 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 09:21:43,347 INFO 2024-04-24 09:21:43,347 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 09:21:43,347 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 09:21:43,586 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:21:43,587 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:43,587 DEBUG --- stdout --- 2024-04-24 09:21:43,587 DEBUG WAITING 2024-04-24 09:21:43,587 DEBUG --- stderr --- 2024-04-24 09:21:43,587 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-24 09:21:43] mixedquery-80 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 09:21:43,588 INFO 2024-04-24 09:21:43,588 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:43,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:43,918 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:43,919 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:43,919 DEBUG --- stdout --- 2024-04-24 09:21:43,919 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:43,919 DEBUG --- stderr --- 2024-04-24 09:21:43,919 DEBUG 2024-04-24 09:21:43,919 INFO 2024-04-24 09:21:43,920 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/08_mixedquery-80/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 09:21:43,920 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:21:45,355 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:45,355 DEBUG --- stdout --- 2024-04-24 09:21:45,355 DEBUG 2024-04-24 09:21:45,355 DEBUG --- stderr --- 2024-04-24 09:21:45,355 DEBUG Order has been successfully forwarded to overseer [2024-04-24 09:21:45] - INFO: Check if Gatling simulation started successfully 2024-04-24 09:21:45,356 INFO 2024-04-24 09:21:45,356 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:45,356 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:21:45,701 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:21:45,701 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:45,701 DEBUG --- stdout --- 2024-04-24 09:21:45,701 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:21:45,701 DEBUG --- stderr --- 2024-04-24 09:21:45,701 DEBUG 2024-04-24 09:21:45,702 INFO 2024-04-24 09:21:45,702 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-80/stdout.txt | grep "started..." 2024-04-24 09:21:45,702 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 09:21:46,522 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 09:21:52,302 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-24 09:21:52,302 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:21:52,302 DEBUG --- stdout --- 2024-04-24 09:21:52,302 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 09:21:52,302 DEBUG --- stderr --- 2024-04-24 09:21:52,302 DEBUG ________________________________________________________________________________ [2024-04-24 09:21:52] mixedquery-80 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 09:53:57] mixedquery-80 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 09:53:57,834 INFO 2024-04-24 09:53:57,834 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:53:57,834 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:53:58,191 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:53:58,191 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:53:58,191 DEBUG --- stdout --- 2024-04-24 09:53:58,191 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:53:58,191 DEBUG --- stderr --- 2024-04-24 09:53:58,192 DEBUG 2024-04-24 09:53:58,192 INFO 2024-04-24 09:53:58,192 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:53:58,192 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:53:59,010 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:53:59,011 DEBUG --- stdout --- 2024-04-24 09:53:59,011 DEBUG 2024-04-24 09:53:59,011 DEBUG --- stderr --- 2024-04-24 09:53:59,011 DEBUG Order ran during 32m 13s ________________________________________________________________________________ [2024-04-24 09:53:59] mixedquery-80 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 09:53:59] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/order.json 2024-04-24 09:53:59,012 INFO 2024-04-24 09:53:59,012 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:53:59,012 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:53:59,330 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:53:59,331 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:53:59,331 DEBUG --- stdout --- 2024-04-24 09:53:59,331 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:53:59,331 DEBUG --- stderr --- 2024-04-24 09:53:59,331 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:53:59,331 INFO 2024-04-24 09:53:59,332 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:53:59,332 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:53:59,664 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:53:59,665 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:53:59,665 DEBUG --- stdout --- 2024-04-24 09:53:59,665 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:53:59,665 DEBUG --- stderr --- 2024-04-24 09:53:59,665 DEBUG 2024-04-24 09:53:59,666 INFO 2024-04-24 09:53:59,666 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-80/rc.txt" ]; then echo "/results/orders/mixedquery-80/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-80/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-80/rc.txt file found" 2024-04-24 09:53:59,666 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:54:00,527 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:54:00,527 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:00,527 DEBUG --- stdout --- 2024-04-24 09:54:00,528 DEBUG /results/orders/mixedquery-80/rc.txt file found 2024-04-24 09:54:00,529 DEBUG --- stderr --- 2024-04-24 09:54:00,529 DEBUG 2024-04-24 09:54:00,529 INFO 2024-04-24 09:54:00,529 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:54:00,529 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:00,870 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:00,870 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:00,870 DEBUG --- stdout --- 2024-04-24 09:54:00,870 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:00,870 DEBUG --- stderr --- 2024-04-24 09:54:00,871 DEBUG 2024-04-24 09:54:00,871 INFO 2024-04-24 09:54:00,871 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-80/rc.txt 2024-04-24 09:54:01,728 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:54:01,730 DEBUG --- stdout --- 2024-04-24 09:54:01,730 DEBUG 4 /results/orders/mixedquery-80/rc.txt 2024-04-24 09:54:01,730 DEBUG --- stderr --- 2024-04-24 09:54:01,730 DEBUG 2024-04-24 09:54:01,730 INFO - Download file (size 4k) 2024-04-24 09:54:01,731 INFO 2024-04-24 09:54:01,731 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:54:01,731 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:02,071 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:02,071 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:02,071 DEBUG --- stdout --- 2024-04-24 09:54:02,071 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:02,072 DEBUG --- stderr --- 2024-04-24 09:54:02,072 DEBUG source : /results/orders/mixedquery-80/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/rc.txt 2024-04-24 09:54:02,072 INFO 2024-04-24 09:54:02,072 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:54:02,072 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:02,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:02,390 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:02,390 DEBUG --- stdout --- 2024-04-24 09:54:02,391 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:02,391 DEBUG --- stderr --- 2024-04-24 09:54:02,391 DEBUG 2024-04-24 09:54:02,391 INFO 2024-04-24 09:54:02,391 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-80/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/rc.txt 2024-04-24 09:54:02,391 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:54:03,165 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:03,165 DEBUG --- stdout --- 2024-04-24 09:54:03,165 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:54:03,165 DEBUG --- stderr --- 2024-04-24 09:54:03,165 DEBUG 2024-04-24 09:54:03,166 INFO 2024-04-24 09:54:03,166 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:54:03,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:03,493 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:03,493 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:03,493 DEBUG --- stdout --- 2024-04-24 09:54:03,493 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:03,493 DEBUG --- stderr --- 2024-04-24 09:54:03,493 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:54:03,494 INFO 2024-04-24 09:54:03,494 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:54:03,494 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:03,807 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:03,808 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:03,808 DEBUG --- stdout --- 2024-04-24 09:54:03,808 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:03,808 DEBUG --- stderr --- 2024-04-24 09:54:03,808 DEBUG 2024-04-24 09:54:03,808 INFO 2024-04-24 09:54:03,808 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-80/stderr.txt" ]; then echo "/results/orders/mixedquery-80/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-80/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-80/stderr.txt file found" 2024-04-24 09:54:03,808 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:54:04,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:54:04,650 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:04,650 DEBUG --- stdout --- 2024-04-24 09:54:04,650 DEBUG /results/orders/mixedquery-80/stderr.txt file found 2024-04-24 09:54:04,651 DEBUG --- stderr --- 2024-04-24 09:54:04,651 DEBUG 2024-04-24 09:54:04,651 INFO 2024-04-24 09:54:04,651 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:54:04,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:05,011 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:05,012 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:05,012 DEBUG --- stdout --- 2024-04-24 09:54:05,012 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:05,012 DEBUG --- stderr --- 2024-04-24 09:54:05,012 DEBUG 2024-04-24 09:54:05,012 INFO 2024-04-24 09:54:05,012 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-80/stderr.txt 2024-04-24 09:54:05,862 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:54:05,862 DEBUG --- stdout --- 2024-04-24 09:54:05,862 DEBUG 0 /results/orders/mixedquery-80/stderr.txt 2024-04-24 09:54:05,862 DEBUG --- stderr --- 2024-04-24 09:54:05,862 DEBUG 2024-04-24 09:54:05,862 INFO - Download file (size 0k) 2024-04-24 09:54:05,863 INFO 2024-04-24 09:54:05,863 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:54:05,863 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:06,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:06,173 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:06,173 DEBUG --- stdout --- 2024-04-24 09:54:06,173 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:06,173 DEBUG --- stderr --- 2024-04-24 09:54:06,174 DEBUG source : /results/orders/mixedquery-80/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/stderr.txt 2024-04-24 09:54:06,174 INFO 2024-04-24 09:54:06,174 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:54:06,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:06,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:06,523 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:06,523 DEBUG --- stdout --- 2024-04-24 09:54:06,523 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:06,523 DEBUG --- stderr --- 2024-04-24 09:54:06,523 DEBUG 2024-04-24 09:54:06,524 INFO 2024-04-24 09:54:06,524 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-80/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/stderr.txt 2024-04-24 09:54:06,525 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:54:07,354 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:07,354 DEBUG --- stdout --- 2024-04-24 09:54:07,354 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:54:07,354 DEBUG --- stderr --- 2024-04-24 09:54:07,354 DEBUG 2024-04-24 09:54:07,355 INFO 2024-04-24 09:54:07,355 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 09:54:07,355 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:07,692 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:07,692 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:07,692 DEBUG --- stdout --- 2024-04-24 09:54:07,692 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:07,692 DEBUG --- stderr --- 2024-04-24 09:54:07,692 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:54:07,692 INFO 2024-04-24 09:54:07,692 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:54:07,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:08,015 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:08,015 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:08,015 DEBUG --- stdout --- 2024-04-24 09:54:08,015 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:08,015 DEBUG --- stderr --- 2024-04-24 09:54:08,015 DEBUG 2024-04-24 09:54:08,016 INFO 2024-04-24 09:54:08,016 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-80/stdout.txt" ]; then echo "/results/orders/mixedquery-80/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-80/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-80/stdout.txt file found" 2024-04-24 09:54:08,016 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:54:08,859 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:54:08,859 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:08,859 DEBUG --- stdout --- 2024-04-24 09:54:08,859 DEBUG /results/orders/mixedquery-80/stdout.txt file found 2024-04-24 09:54:08,859 DEBUG --- stderr --- 2024-04-24 09:54:08,859 DEBUG 2024-04-24 09:54:08,859 INFO 2024-04-24 09:54:08,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 09:54:08,859 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:09,191 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:09,191 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:09,191 DEBUG --- stdout --- 2024-04-24 09:54:09,191 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:09,191 DEBUG --- stderr --- 2024-04-24 09:54:09,191 DEBUG 2024-04-24 09:54:09,192 INFO 2024-04-24 09:54:09,192 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-80/stdout.txt 2024-04-24 09:54:10,036 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:54:10,036 DEBUG --- stdout --- 2024-04-24 09:54:10,036 DEBUG 21340 /results/orders/mixedquery-80/stdout.txt 2024-04-24 09:54:10,036 DEBUG --- stderr --- 2024-04-24 09:54:10,036 DEBUG 2024-04-24 09:54:10,036 INFO - Download file (size 21340k) 2024-04-24 09:54:10,037 INFO 2024-04-24 09:54:10,037 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:54:10,037 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:10,362 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:10,363 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:10,363 DEBUG --- stdout --- 2024-04-24 09:54:10,363 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:10,363 DEBUG --- stderr --- 2024-04-24 09:54:10,363 DEBUG source : /results/orders/mixedquery-80/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/stdout.txt 2024-04-24 09:54:10,363 INFO 2024-04-24 09:54:10,364 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:54:10,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:10,686 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:10,686 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:10,686 DEBUG --- stdout --- 2024-04-24 09:54:10,686 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:10,686 DEBUG --- stderr --- 2024-04-24 09:54:10,686 DEBUG 2024-04-24 09:54:10,687 INFO 2024-04-24 09:54:10,687 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-80/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/stdout.txt 2024-04-24 09:54:10,687 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:54:12,361 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:12,361 DEBUG --- stdout --- 2024-04-24 09:54:12,362 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:54:12,362 DEBUG --- stderr --- 2024-04-24 09:54:12,362 DEBUG ________________________________________________________________________________ [2024-04-24 09:54:12] mixedquery-80 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 09:54:12,363 INFO 2024-04-24 09:54:12,363 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:54:12,363 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:12,749 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:12,749 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:12,749 DEBUG --- stdout --- 2024-04-24 09:54:12,749 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:12,749 DEBUG --- stderr --- 2024-04-24 09:54:12,749 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 09:54:12,750 INFO 2024-04-24 09:54:12,750 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:54:12,750 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:13,090 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:13,090 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:13,090 DEBUG --- stdout --- 2024-04-24 09:54:13,091 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:13,091 DEBUG --- stderr --- 2024-04-24 09:54:13,091 DEBUG 2024-04-24 09:54:13,091 INFO 2024-04-24 09:54:13,091 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-80/cmd.txt" ]; then echo "/results/orders/mixedquery-80/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-80/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-80/cmd.txt file found" 2024-04-24 09:54:13,091 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 09:54:13,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 09:54:13,929 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:13,929 DEBUG --- stdout --- 2024-04-24 09:54:13,929 DEBUG /results/orders/mixedquery-80/cmd.txt file found 2024-04-24 09:54:13,929 DEBUG --- stderr --- 2024-04-24 09:54:13,929 DEBUG 2024-04-24 09:54:13,930 INFO 2024-04-24 09:54:13,930 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:54:13,930 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:14,352 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:14,353 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:14,353 DEBUG --- stdout --- 2024-04-24 09:54:14,358 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:14,359 DEBUG --- stderr --- 2024-04-24 09:54:14,360 DEBUG 2024-04-24 09:54:14,360 INFO 2024-04-24 09:54:14,361 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-80/cmd.txt 2024-04-24 09:54:15,257 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 09:54:15,257 DEBUG --- stdout --- 2024-04-24 09:54:15,257 DEBUG 4 /results/orders/mixedquery-80/cmd.txt 2024-04-24 09:54:15,257 DEBUG --- stderr --- 2024-04-24 09:54:15,257 DEBUG 2024-04-24 09:54:15,257 INFO - Download file (size 4k) 2024-04-24 09:54:15,257 INFO 2024-04-24 09:54:15,257 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:54:15,257 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:15,596 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:15,597 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:15,597 DEBUG --- stdout --- 2024-04-24 09:54:15,597 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:15,597 DEBUG --- stderr --- 2024-04-24 09:54:15,597 DEBUG source : /results/orders/mixedquery-80/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/cmd.txt 2024-04-24 09:54:15,598 INFO 2024-04-24 09:54:15,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 09:54:15,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 09:54:15,932 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 09:54:15,932 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:15,933 DEBUG --- stdout --- 2024-04-24 09:54:15,933 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 09:54:15,933 DEBUG --- stderr --- 2024-04-24 09:54:15,933 DEBUG 2024-04-24 09:54:15,933 INFO 2024-04-24 09:54:15,934 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-80/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/cmd.txt 2024-04-24 09:54:15,934 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 09:54:16,733 INFO [loop_until]: OK (rc = 0) 2024-04-24 09:54:16,733 DEBUG --- stdout --- 2024-04-24 09:54:16,733 DEBUG tar: Removing leading `/' from member names 2024-04-24 09:54:16,733 DEBUG --- stderr --- 2024-04-24 09:54:16,733 DEBUG 2024-04-24 09:54:16,733 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/cmd.txt is small (1 lines), printing all lines: 2024-04-24 09:54:16,733 DEBUG ----- output ----- 2024-04-24 09:54:16,733 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-80 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 09:54:16,733 INFO ________________________________________________________________________________ [2024-04-24 09:54:16] mixedquery-80 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 09:54:16,734 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/rc.txt is small (1 lines), printing all lines: 2024-04-24 09:54:16,734 DEBUG ----- output ----- 2024-04-24 09:54:16,734 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 09:54:16,734 INFO ________________________________________________________________________________ [2024-04-24 09:54:16] mixedquery-80 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 09:54:16,817 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/08_mixedquery-80/stdout.txt is 362322 lines): 2024-04-24 09:54:16,817 DEBUG ----- output ----- 2024-04-24 09:54:16,817 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 09:54:16,817 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 09:54:16,817 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 09:54:16,817 INFO 2024-04-24 09:54:16,817 INFO ================================================================================ 2024-04-24 09:54:16,817 INFO 2024-04-24 10:21:56 5s elapsed 2024-04-24 09:54:16,817 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO > Global (OK=0 KO=0 ) 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO [- ] 0% 2024-04-24 09:54:16,818 INFO waiting: 145404 / active: 12 / done: 22 2024-04-24 09:54:16,818 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:54:16,818 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:54:16,818 INFO ================================================================================ 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO ================================================================================ 2024-04-24 09:54:16,818 INFO 2024-04-24 10:22:01 10s elapsed 2024-04-24 09:54:16,818 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO > Global (OK=0 KO=0 ) 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO [- ] 0% 2024-04-24 09:54:16,818 INFO waiting: 145304 / active: 25 / done: 109 2024-04-24 09:54:16,818 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:54:16,818 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:54:16,818 INFO ================================================================================ 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO 2024-04-24 09:54:16,818 INFO ================================================================================ 2024-04-24 09:54:16,818 INFO 2024-04-24 10:22:06 15s elapsed 2024-04-24 09:54:16,818 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,818 INFO > Global (OK=160 KO=0 ) 2024-04-24 09:54:16,818 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:54:16,818 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:54:16,818 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:54:16,818 INFO > ReadManagedUser (OK=30 KO=0 ) 2024-04-24 09:54:16,818 INFO > queryFull (OK=37 KO=0 ) 2024-04-24 09:54:16,818 INFO > queryBySn (OK=35 KO=0 ) 2024-04-24 09:54:16,818 INFO > queryStartsWith (OK=24 KO=0 ) 2024-04-24 09:54:16,819 INFO > queryPartial (OK=31 KO=0 ) 2024-04-24 09:54:16,819 INFO 2024-04-24 09:54:16,819 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,819 INFO [- ] 0% 2024-04-24 09:54:16,819 INFO waiting: 145137 / active: 6 / done: 295 2024-04-24 09:54:16,819 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,819 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:54:16,819 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:54:16,819 INFO ================================================================================ 2024-04-24 09:54:16,819 INFO 2024-04-24 09:54:16,819 INFO 2024-04-24 09:54:16,819 INFO ================================================================================ 2024-04-24 09:54:16,819 INFO 2024-04-24 10:22:11 20s elapsed 2024-04-24 09:54:16,819 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,819 INFO > Global (OK=394 KO=0 ) 2024-04-24 09:54:16,819 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:54:16,819 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:54:16,819 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:54:16,819 INFO > ReadManagedUser (OK=79 KO=0 ) 2024-04-24 09:54:16,819 INFO > queryFull (OK=92 KO=0 ) 2024-04-24 09:54:16,819 INFO > queryBySn (OK=92 KO=0 ) 2024-04-24 09:54:16,819 INFO > queryStartsWith (OK=54 KO=0 ) 2024-04-24 09:54:16,819 INFO > queryPartial (OK=74 KO=0 ) 2024-04-24 09:54:16,819 INFO 2024-04-24 09:54:16,819 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,819 INFO [- ] 0% 2024-04-24 09:54:16,819 INFO waiting: 144904 / active: 5 / done: 529 2024-04-24 09:54:16,819 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,819 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:54:16,819 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:54:16,819 INFO ================================================================================ 2024-04-24 09:54:16,819 INFO 2024-04-24 09:54:16,819 INFO 2024-04-24 09:54:16,819 INFO ================================================================================ 2024-04-24 09:54:16,819 INFO 2024-04-24 10:22:16 25s elapsed 2024-04-24 09:54:16,819 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,819 INFO > Global (OK=688 KO=0 ) 2024-04-24 09:54:16,819 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:54:16,819 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:54:16,819 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:54:16,820 INFO > ReadManagedUser (OK=144 KO=0 ) 2024-04-24 09:54:16,820 INFO > queryFull (OK=148 KO=0 ) 2024-04-24 09:54:16,820 INFO > queryBySn (OK=148 KO=0 ) 2024-04-24 09:54:16,820 INFO > queryStartsWith (OK=113 KO=0 ) 2024-04-24 09:54:16,820 INFO > queryPartial (OK=132 KO=0 ) 2024-04-24 09:54:16,820 INFO 2024-04-24 09:54:16,820 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,820 INFO [- ] 0% 2024-04-24 09:54:16,820 INFO waiting: 144604 / active: 11 / done: 823 2024-04-24 09:54:16,820 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,820 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 09:54:16,820 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 09:54:16,820 INFO ================================================================================ 2024-04-24 09:54:16,820 INFO 2024-04-24 09:54:16,820 INFO 2024-04-24 09:54:16,820 INFO [...] 2024-04-24 09:54:16,947 INFO 2024-04-24 09:54:16,947 INFO ================================================================================ 2024-04-24 09:54:16,947 INFO 2024-04-24 10:52:16 1825s elapsed 2024-04-24 09:54:16,947 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,947 INFO > Global (OK=136362 KO=8478 ) 2024-04-24 09:54:16,947 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:54:16,947 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:54:16,947 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:54:16,947 INFO > ReadManagedUser (OK=27354 KO=1703 ) 2024-04-24 09:54:16,947 INFO > queryFull (OK=27329 KO=1636 ) 2024-04-24 09:54:16,947 INFO > queryBySn (OK=27370 KO=1707 ) 2024-04-24 09:54:16,947 INFO > queryStartsWith (OK=26959 KO=1711 ) 2024-04-24 09:54:16,947 INFO > queryPartial (OK=27347 KO=1721 ) 2024-04-24 09:54:16,947 INFO ---- Errors -------------------------------------------------------------------- 2024-04-24 09:54:16,947 INFO > status.find.in(200,401), but actually found 503 6882 (81.17%) 2024-04-24 09:54:16,947 INFO > status.find.in(200,401), but actually found 404 900 (10.62%) 2024-04-24 09:54:16,947 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 696 ( 8.21%) 2024-04-24 09:54:16,947 INFO .47.242:443 after 60000 ms 2024-04-24 09:54:16,947 INFO 2024-04-24 09:54:16,947 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,947 INFO [#########################################################################-] 99% 2024-04-24 09:54:16,947 INFO waiting: 428 / active: 35 / done: 144975 2024-04-24 09:54:16,948 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,948 INFO [##########################################################################]100% 2024-04-24 09:54:16,948 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:54:16,948 INFO ================================================================================ 2024-04-24 09:54:16,948 INFO 2024-04-24 09:54:16,948 INFO 2024-04-24 09:54:16,948 INFO ================================================================================ 2024-04-24 09:54:16,948 INFO 2024-04-24 10:52:21 1830s elapsed 2024-04-24 09:54:16,948 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,948 INFO > Global (OK=136755 KO=8478 ) 2024-04-24 09:54:16,948 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:54:16,948 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:54:16,948 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:54:16,948 INFO > ReadManagedUser (OK=27436 KO=1703 ) 2024-04-24 09:54:16,948 INFO > queryFull (OK=27405 KO=1636 ) 2024-04-24 09:54:16,948 INFO > queryBySn (OK=27447 KO=1707 ) 2024-04-24 09:54:16,948 INFO > queryStartsWith (OK=27050 KO=1711 ) 2024-04-24 09:54:16,948 INFO > queryPartial (OK=27414 KO=1721 ) 2024-04-24 09:54:16,948 INFO ---- Errors -------------------------------------------------------------------- 2024-04-24 09:54:16,948 INFO > status.find.in(200,401), but actually found 503 6882 (81.17%) 2024-04-24 09:54:16,948 INFO > status.find.in(200,401), but actually found 404 900 (10.62%) 2024-04-24 09:54:16,948 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 696 ( 8.21%) 2024-04-24 09:54:16,948 INFO .47.242:443 after 60000 ms 2024-04-24 09:54:16,948 INFO 2024-04-24 09:54:16,948 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,948 INFO [#########################################################################-] 99% 2024-04-24 09:54:16,948 INFO waiting: 0 / active: 70 / done: 145368 2024-04-24 09:54:16,948 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,948 INFO [##########################################################################]100% 2024-04-24 09:54:16,948 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:54:16,948 INFO ================================================================================ 2024-04-24 09:54:16,948 INFO 2024-04-24 09:54:16,948 INFO 2024-04-24 09:54:16,948 INFO ================================================================================ 2024-04-24 09:54:16,948 INFO 2024-04-24 10:52:22 1831s elapsed 2024-04-24 09:54:16,948 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 09:54:16,948 INFO > Global (OK=136825 KO=8478 ) 2024-04-24 09:54:16,948 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 09:54:16,948 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 09:54:16,948 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 09:54:16,948 INFO > ReadManagedUser (OK=27443 KO=1703 ) 2024-04-24 09:54:16,949 INFO > queryFull (OK=27414 KO=1636 ) 2024-04-24 09:54:16,949 INFO > queryBySn (OK=27455 KO=1707 ) 2024-04-24 09:54:16,949 INFO > queryStartsWith (OK=27075 KO=1711 ) 2024-04-24 09:54:16,949 INFO > queryPartial (OK=27435 KO=1721 ) 2024-04-24 09:54:16,949 INFO ---- Errors -------------------------------------------------------------------- 2024-04-24 09:54:16,949 INFO > status.find.in(200,401), but actually found 503 6882 (81.17%) 2024-04-24 09:54:16,949 INFO > status.find.in(200,401), but actually found 404 900 (10.62%) 2024-04-24 09:54:16,949 INFO > Request timeout to xlou.iam.xlou-cdm.engineeringpit.com/34.148 696 ( 8.21%) 2024-04-24 09:54:16,949 INFO .47.242:443 after 60000 ms 2024-04-24 09:54:16,949 INFO 2024-04-24 09:54:16,949 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 09:54:16,949 INFO [##########################################################################]100% 2024-04-24 09:54:16,949 INFO waiting: 0 / active: 0 / done: 145438 2024-04-24 09:54:16,949 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 09:54:16,949 INFO [##########################################################################]100% 2024-04-24 09:54:16,949 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 09:54:16,949 INFO ================================================================================ 2024-04-24 09:54:16,949 INFO 2024-04-24 09:54:16,949 INFO Simulation com.forgerock.gatling.Loadtest completed in 1831 seconds 2024-04-24 09:54:16,949 INFO 2024-04-24 09:54:16,949 INFO ---------------------- 2024-04-24 09:54:16,949 INFO [24/Apr/2024 10:52:22] Create tar file mixedquery-80.tar.gz 2024-04-24 09:54:16,949 INFO ---------------------- 2024-04-24 09:54:16,949 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 09:54:16,949 INFO RESULT_FOLDER=mixedquery-80 2024-04-24 09:54:16,949 INFO SIMULATION_PATH=mixedquery-80/loadtest-20240424092150407 2024-04-24 09:54:16,949 INFO PWD=/results/gatling/mixedquery-80/loadtest-20240424092150407 2024-04-24 09:54:16,949 INFO [cmd] tar -czvf /results/gatling//mixedquery-80.tar.gz --exclude=simulation.log * 2024-04-24 09:54:16,949 INFO /results/gatling 2024-04-24 09:54:16,949 INFO 2024-04-24 09:54:16,949 INFO ---------------------- 2024-04-24 09:54:16,949 INFO [24/Apr/2024 10:52:22] Update /results/gatling//latest 2024-04-24 09:54:16,949 INFO ---------------------- 2024-04-24 09:54:16,949 INFO 2024-04-24 09:54:16,949 INFO ---------------------- 2024-04-24 09:54:16,949 INFO [24/Apr/2024 10:52:22] Update /results/gatling//latest.tar.gz 2024-04-24 09:54:16,949 INFO ---------------------- 2024-04-24 09:54:16,949 INFO ________________________________________________________________________________ [2024-04-24 09:54:16] mixedquery-80 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 09:54:16,950 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/08_mixedquery-80/stderr.txt is small (0 lines), printing all lines: 2024-04-24 09:54:16,950 DEBUG ----- output ----- 2024-04-24 09:54:16,950 DEBUG 2024-04-24 09:54:16,950 INFO ________________________________________________________________________________ [2024-04-24 09:54:16] mixedquery-80 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:54:17] mixedquery-80 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 09:54:17] mixedquery-80 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped