--Task-- name: mixedquery-10 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: [] preceding_task: None options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 10} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 05:33:16] mixedquery-10 pre : N/A ________________________________________________________________________________ [2024-04-24 05:33:16] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 05:33:16] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-10/stdout.txt Pod log : /results/orders/mixedquery-10 2024-04-24 05:33:16,132 INFO 2024-04-24 05:33:16,132 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 05:33:16,132 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 05:33:16,463 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 05:33:16,463 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:16,463 DEBUG --- stdout --- 2024-04-24 05:33:16,463 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 05:33:16,463 DEBUG --- stderr --- 2024-04-24 05:33:16,463 DEBUG [2024-04-24 05:33:16] - INFO: Get overseer pod resources requests memory 2024-04-24 05:33:16,464 INFO 2024-04-24 05:33:16,464 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-55d5975bd7-gp92m --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-24 05:33:16,464 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 05:33:16,777 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:16,777 DEBUG --- stdout --- 2024-04-24 05:33:16,777 DEBUG 4Gi 2024-04-24 05:33:16,777 DEBUG --- stderr --- 2024-04-24 05:33:16,777 DEBUG ________________________________________________________________________________ [2024-04-24 05:33:16] mixedquery-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 05:33:16,778 INFO 2024-04-24 05:33:16,778 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 05:33:16,778 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 05:33:17,117 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 05:33:17,117 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:17,117 DEBUG --- stdout --- 2024-04-24 05:33:17,117 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 05:33:17,117 DEBUG --- stderr --- 2024-04-24 05:33:17,117 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-10 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 05:33:17] mixedquery-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 05:33:17,118 INFO 2024-04-24 05:33:17,119 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 05:33:17,119 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 05:33:17,487 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 05:33:17,488 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:17,488 DEBUG --- stdout --- 2024-04-24 05:33:17,488 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 05:33:17,488 DEBUG --- stderr --- 2024-04-24 05:33:17,488 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/order.json { "name": "mixedquery-10", "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-10 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-10", "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=10 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 05:33:17] mixedquery-10 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 05:33:17,490 INFO 2024-04-24 05:33:17,491 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 05:33:17,491 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 05:33:17,743 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 05:33:17,744 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:17,744 DEBUG --- stdout --- 2024-04-24 05:33:17,744 DEBUG WAITING 2024-04-24 05:33:17,744 DEBUG --- stderr --- 2024-04-24 05:33:17,744 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-24 05:33:17] mixedquery-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 05:33:17,745 INFO 2024-04-24 05:33:17,745 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 05:33:17,745 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 05:33:18,099 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 05:33:18,099 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:18,099 DEBUG --- stdout --- 2024-04-24 05:33:18,099 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 05:33:18,100 DEBUG --- stderr --- 2024-04-24 05:33:18,100 DEBUG 2024-04-24 05:33:18,100 INFO 2024-04-24 05:33:18,100 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/01_mixedquery-10/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 05:33:18,100 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 05:33:19,530 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:19,530 DEBUG --- stdout --- 2024-04-24 05:33:19,530 DEBUG 2024-04-24 05:33:19,530 DEBUG --- stderr --- 2024-04-24 05:33:19,530 DEBUG Order has been successfully forwarded to overseer [2024-04-24 05:33:19] - INFO: Check if Gatling simulation started successfully 2024-04-24 05:33:19,531 INFO 2024-04-24 05:33:19,531 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 05:33:19,531 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 05:33:19,851 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 05:33:19,851 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:19,851 DEBUG --- stdout --- 2024-04-24 05:33:19,851 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 05:33:19,851 DEBUG --- stderr --- 2024-04-24 05:33:19,851 DEBUG 2024-04-24 05:33:19,852 INFO 2024-04-24 05:33:19,852 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-10/stdout.txt | grep "started..." 2024-04-24 05:33:19,852 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 05:33:20,616 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 05:33:26,385 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-24 05:33:32,291 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-24 05:33:38,061 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-24 05:33:38,061 INFO [loop_until]: OK (rc = 0) 2024-04-24 05:33:38,061 DEBUG --- stdout --- 2024-04-24 05:33:38,061 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 06:33:22.488 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 06:33:28.713 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 06:33:28.716 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 06:33:31.080 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 05:33:38,061 DEBUG --- stderr --- 2024-04-24 05:33:38,061 DEBUG ________________________________________________________________________________ [2024-04-24 05:33:38] mixedquery-10 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, 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 06:05:43] mixedquery-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 06:05:43,775 INFO 2024-04-24 06:05:43,776 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:43,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:44,113 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:44,113 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:44,113 DEBUG --- stdout --- 2024-04-24 06:05:44,113 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:44,113 DEBUG --- stderr --- 2024-04-24 06:05:44,113 DEBUG 2024-04-24 06:05:44,114 INFO 2024-04-24 06:05:44,114 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 06:05:44,114 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:05:44,943 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:44,943 DEBUG --- stdout --- 2024-04-24 06:05:44,943 DEBUG 2024-04-24 06:05:44,943 DEBUG --- stderr --- 2024-04-24 06:05:44,943 DEBUG Order ran during 32m 25s ________________________________________________________________________________ [2024-04-24 06:05:44] mixedquery-10 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 06:05:44] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/order.json 2024-04-24 06:05:44,944 INFO 2024-04-24 06:05:44,944 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:44,944 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:45,261 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:45,261 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:45,261 DEBUG --- stdout --- 2024-04-24 06:05:45,261 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:45,261 DEBUG --- stderr --- 2024-04-24 06:05:45,261 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:05:45,262 INFO 2024-04-24 06:05:45,262 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:45,262 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:45,578 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:45,579 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:45,579 DEBUG --- stdout --- 2024-04-24 06:05:45,579 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:45,579 DEBUG --- stderr --- 2024-04-24 06:05:45,579 DEBUG 2024-04-24 06:05:45,579 INFO 2024-04-24 06:05:45,579 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-10/rc.txt" ]; then echo "/results/orders/mixedquery-10/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-10/rc.txt file found" 2024-04-24 06:05:45,579 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:05:46,425 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:05:46,426 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:46,426 DEBUG --- stdout --- 2024-04-24 06:05:46,426 DEBUG /results/orders/mixedquery-10/rc.txt file found 2024-04-24 06:05:46,426 DEBUG --- stderr --- 2024-04-24 06:05:46,426 DEBUG 2024-04-24 06:05:46,426 INFO 2024-04-24 06:05:46,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 06:05:46,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:46,747 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:46,748 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:46,748 DEBUG --- stdout --- 2024-04-24 06:05:46,748 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:46,748 DEBUG --- stderr --- 2024-04-24 06:05:46,748 DEBUG 2024-04-24 06:05:46,748 INFO 2024-04-24 06:05:46,748 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-10/rc.txt 2024-04-24 06:05:47,620 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:05:47,621 DEBUG --- stdout --- 2024-04-24 06:05:47,621 DEBUG 4 /results/orders/mixedquery-10/rc.txt 2024-04-24 06:05:47,621 DEBUG --- stderr --- 2024-04-24 06:05:47,621 DEBUG 2024-04-24 06:05:47,621 INFO - Download file (size 4k) 2024-04-24 06:05:47,621 INFO 2024-04-24 06:05:47,621 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:47,621 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:47,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:47,937 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:47,937 DEBUG --- stdout --- 2024-04-24 06:05:47,937 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:47,937 DEBUG --- stderr --- 2024-04-24 06:05:47,937 DEBUG source : /results/orders/mixedquery-10/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/rc.txt 2024-04-24 06:05:47,938 INFO 2024-04-24 06:05:47,938 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:47,938 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:48,260 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:48,260 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:48,260 DEBUG --- stdout --- 2024-04-24 06:05:48,260 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:48,260 DEBUG --- stderr --- 2024-04-24 06:05:48,260 DEBUG 2024-04-24 06:05:48,260 INFO 2024-04-24 06:05:48,260 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/rc.txt 2024-04-24 06:05:48,261 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:05:49,015 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:49,015 DEBUG --- stdout --- 2024-04-24 06:05:49,015 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:05:49,015 DEBUG --- stderr --- 2024-04-24 06:05:49,015 DEBUG 2024-04-24 06:05:49,016 INFO 2024-04-24 06:05:49,016 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:49,016 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:49,329 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:49,329 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:49,329 DEBUG --- stdout --- 2024-04-24 06:05:49,330 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:49,330 DEBUG --- stderr --- 2024-04-24 06:05:49,330 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:05:49,330 INFO 2024-04-24 06:05:49,330 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:49,330 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:49,642 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:49,643 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:49,643 DEBUG --- stdout --- 2024-04-24 06:05:49,643 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:49,643 DEBUG --- stderr --- 2024-04-24 06:05:49,643 DEBUG 2024-04-24 06:05:49,643 INFO 2024-04-24 06:05:49,644 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-10/stderr.txt" ]; then echo "/results/orders/mixedquery-10/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-10/stderr.txt file found" 2024-04-24 06:05:49,644 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:05:50,476 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:05:50,477 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:50,477 DEBUG --- stdout --- 2024-04-24 06:05:50,477 DEBUG /results/orders/mixedquery-10/stderr.txt file found 2024-04-24 06:05:50,477 DEBUG --- stderr --- 2024-04-24 06:05:50,477 DEBUG 2024-04-24 06:05:50,477 INFO 2024-04-24 06:05:50,477 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:50,477 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:50,793 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:50,793 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:50,793 DEBUG --- stdout --- 2024-04-24 06:05:50,793 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:50,793 DEBUG --- stderr --- 2024-04-24 06:05:50,793 DEBUG 2024-04-24 06:05:50,793 INFO 2024-04-24 06:05:50,793 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-10/stderr.txt 2024-04-24 06:05:51,631 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:05:51,631 DEBUG --- stdout --- 2024-04-24 06:05:51,631 DEBUG 0 /results/orders/mixedquery-10/stderr.txt 2024-04-24 06:05:51,631 DEBUG --- stderr --- 2024-04-24 06:05:51,632 DEBUG 2024-04-24 06:05:51,632 INFO - Download file (size 0k) 2024-04-24 06:05:51,632 INFO 2024-04-24 06:05:51,632 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:51,632 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:51,948 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:51,948 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:51,948 DEBUG --- stdout --- 2024-04-24 06:05:51,948 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:51,948 DEBUG --- stderr --- 2024-04-24 06:05:51,948 DEBUG source : /results/orders/mixedquery-10/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/stderr.txt 2024-04-24 06:05:51,948 INFO 2024-04-24 06:05:51,948 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:51,948 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:52,261 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:52,261 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:52,261 DEBUG --- stdout --- 2024-04-24 06:05:52,262 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:52,262 DEBUG --- stderr --- 2024-04-24 06:05:52,262 DEBUG 2024-04-24 06:05:52,262 INFO 2024-04-24 06:05:52,262 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/stderr.txt 2024-04-24 06:05:52,262 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:05:53,048 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:53,049 DEBUG --- stdout --- 2024-04-24 06:05:53,049 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:05:53,049 DEBUG --- stderr --- 2024-04-24 06:05:53,049 DEBUG 2024-04-24 06:05:53,049 INFO 2024-04-24 06:05:53,049 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:53,049 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:53,369 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:53,369 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:53,369 DEBUG --- stdout --- 2024-04-24 06:05:53,369 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:53,369 DEBUG --- stderr --- 2024-04-24 06:05:53,370 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:05:53,370 INFO 2024-04-24 06:05:53,370 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:53,370 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:53,689 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:53,689 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:53,689 DEBUG --- stdout --- 2024-04-24 06:05:53,689 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:53,689 DEBUG --- stderr --- 2024-04-24 06:05:53,689 DEBUG 2024-04-24 06:05:53,690 INFO 2024-04-24 06:05:53,690 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-10/stdout.txt" ]; then echo "/results/orders/mixedquery-10/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-10/stdout.txt file found" 2024-04-24 06:05:53,690 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:05:54,523 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:05:54,524 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:54,524 DEBUG --- stdout --- 2024-04-24 06:05:54,524 DEBUG /results/orders/mixedquery-10/stdout.txt file found 2024-04-24 06:05:54,524 DEBUG --- stderr --- 2024-04-24 06:05:54,524 DEBUG 2024-04-24 06:05:54,524 INFO 2024-04-24 06:05:54,524 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:54,525 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:54,842 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:54,842 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:54,842 DEBUG --- stdout --- 2024-04-24 06:05:54,842 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:54,843 DEBUG --- stderr --- 2024-04-24 06:05:54,843 DEBUG 2024-04-24 06:05:54,843 INFO 2024-04-24 06:05:54,843 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-10/stdout.txt 2024-04-24 06:05:55,679 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:05:55,680 DEBUG --- stdout --- 2024-04-24 06:05:55,680 DEBUG 540 /results/orders/mixedquery-10/stdout.txt 2024-04-24 06:05:55,680 DEBUG --- stderr --- 2024-04-24 06:05:55,680 DEBUG 2024-04-24 06:05:55,680 INFO - Download file (size 540k) 2024-04-24 06:05:55,680 INFO 2024-04-24 06:05:55,680 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:55,680 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:55,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:55,997 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:55,997 DEBUG --- stdout --- 2024-04-24 06:05:55,997 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:55,998 DEBUG --- stderr --- 2024-04-24 06:05:55,998 DEBUG source : /results/orders/mixedquery-10/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/stdout.txt 2024-04-24 06:05:55,998 INFO 2024-04-24 06:05:55,998 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:55,998 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:56,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:56,312 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:56,312 DEBUG --- stdout --- 2024-04-24 06:05:56,312 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:56,312 DEBUG --- stderr --- 2024-04-24 06:05:56,312 DEBUG 2024-04-24 06:05:56,313 INFO 2024-04-24 06:05:56,313 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/stdout.txt 2024-04-24 06:05:56,313 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:05:57,392 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:57,393 DEBUG --- stdout --- 2024-04-24 06:05:57,393 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:05:57,393 DEBUG --- stderr --- 2024-04-24 06:05:57,393 DEBUG ________________________________________________________________________________ [2024-04-24 06:05:57] mixedquery-10 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 06:05:57,393 INFO 2024-04-24 06:05:57,394 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:57,394 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:57,707 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:57,707 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:57,707 DEBUG --- stdout --- 2024-04-24 06:05:57,707 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:57,707 DEBUG --- stderr --- 2024-04-24 06:05:57,707 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:05:57,708 INFO 2024-04-24 06:05:57,708 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:57,708 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:58,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:58,029 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:58,029 DEBUG --- stdout --- 2024-04-24 06:05:58,029 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:58,029 DEBUG --- stderr --- 2024-04-24 06:05:58,029 DEBUG 2024-04-24 06:05:58,030 INFO 2024-04-24 06:05:58,030 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-10/cmd.txt" ]; then echo "/results/orders/mixedquery-10/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-10/cmd.txt file found" 2024-04-24 06:05:58,030 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:05:58,876 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:05:58,876 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:58,876 DEBUG --- stdout --- 2024-04-24 06:05:58,876 DEBUG /results/orders/mixedquery-10/cmd.txt file found 2024-04-24 06:05:58,876 DEBUG --- stderr --- 2024-04-24 06:05:58,876 DEBUG 2024-04-24 06:05:58,876 INFO 2024-04-24 06:05:58,876 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:05:58,876 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:05:59,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:05:59,190 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:05:59,190 DEBUG --- stdout --- 2024-04-24 06:05:59,190 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:05:59,190 DEBUG --- stderr --- 2024-04-24 06:05:59,190 DEBUG 2024-04-24 06:05:59,190 INFO 2024-04-24 06:05:59,190 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-55d5975bd7-gp92m --stdin -- du -k /results/orders/mixedquery-10/cmd.txt 2024-04-24 06:06:00,019 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:06:00,019 DEBUG --- stdout --- 2024-04-24 06:06:00,019 DEBUG 4 /results/orders/mixedquery-10/cmd.txt 2024-04-24 06:06:00,020 DEBUG --- stderr --- 2024-04-24 06:06:00,020 DEBUG 2024-04-24 06:06:00,020 INFO - Download file (size 4k) 2024-04-24 06:06:00,020 INFO 2024-04-24 06:06:00,020 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:06:00,020 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:00,390 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:00,390 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:00,390 DEBUG --- stdout --- 2024-04-24 06:06:00,390 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:00,390 DEBUG --- stderr --- 2024-04-24 06:06:00,390 DEBUG source : /results/orders/mixedquery-10/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/cmd.txt 2024-04-24 06:06:00,391 INFO 2024-04-24 06:06:00,391 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:06:00,391 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:00,709 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:00,710 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:00,710 DEBUG --- stdout --- 2024-04-24 06:06:00,710 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:00,710 DEBUG --- stderr --- 2024-04-24 06:06:00,710 DEBUG 2024-04-24 06:06:00,710 INFO 2024-04-24 06:06:00,710 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-10/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/cmd.txt 2024-04-24 06:06:00,711 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:06:01,472 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:01,472 DEBUG --- stdout --- 2024-04-24 06:06:01,472 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:06:01,472 DEBUG --- stderr --- 2024-04-24 06:06:01,472 DEBUG 2024-04-24 06:06:01,473 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/cmd.txt is small (1 lines), printing all lines: 2024-04-24 06:06:01,473 DEBUG ----- output ----- 2024-04-24 06:06:01,473 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-10 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 06:06:01,473 INFO ________________________________________________________________________________ [2024-04-24 06:06:01] mixedquery-10 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 06:06:01,473 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/rc.txt is small (1 lines), printing all lines: 2024-04-24 06:06:01,473 DEBUG ----- output ----- 2024-04-24 06:06:01,473 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 06:06:01,473 INFO ________________________________________________________________________________ [2024-04-24 06:06:01] mixedquery-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 06:06:01,475 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/01_mixedquery-10/stdout.txt is 8068 lines): 2024-04-24 06:06:01,475 DEBUG ----- output ----- 2024-04-24 06:06:01,475 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 06:06:01,475 INFO 06:33:22.488 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-24 06:06:01,475 INFO 06:33:28.713 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-24 06:06:01,475 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-24 06:06:01,475 INFO ^ 2024-04-24 06:06:01,475 INFO 06:33:28.716 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-24 06:06:01,475 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-24 06:06:01,475 INFO ^ 2024-04-24 06:06:01,475 INFO 06:33:31.080 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-24 06:06:01,475 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 06:06:01,475 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 06:06:01,475 INFO 2024-04-24 06:06:01,475 INFO ================================================================================ 2024-04-24 06:06:01,475 INFO 2024-04-24 06:33:39 5s elapsed 2024-04-24 06:06:01,475 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,475 INFO > Global (OK=0 KO=0 ) 2024-04-24 06:06:01,475 INFO 2024-04-24 06:06:01,475 INFO 2024-04-24 06:06:01,475 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,475 INFO [- ] 0% 2024-04-24 06:06:01,475 INFO waiting: 17996 / active: 2 / done: 3 2024-04-24 06:06:01,475 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:06:01,476 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:06:01,476 INFO ================================================================================ 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO ================================================================================ 2024-04-24 06:06:01,476 INFO 2024-04-24 06:33:44 10s elapsed 2024-04-24 06:06:01,476 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO > Global (OK=0 KO=0 ) 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO [- ] 0% 2024-04-24 06:06:01,476 INFO waiting: 17984 / active: 3 / done: 14 2024-04-24 06:06:01,476 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:06:01,476 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:06:01,476 INFO ================================================================================ 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO ================================================================================ 2024-04-24 06:06:01,476 INFO 2024-04-24 06:33:49 15s elapsed 2024-04-24 06:06:01,476 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO > Global (OK=23 KO=0 ) 2024-04-24 06:06:01,476 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:06:01,476 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:06:01,476 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,476 INFO > ReadManagedUser (OK=8 KO=0 ) 2024-04-24 06:06:01,476 INFO > queryFull (OK=4 KO=0 ) 2024-04-24 06:06:01,476 INFO > queryStartsWith (OK=4 KO=0 ) 2024-04-24 06:06:01,476 INFO > queryBySn (OK=3 KO=0 ) 2024-04-24 06:06:01,476 INFO > queryPartial (OK=1 KO=0 ) 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO [- ] 0% 2024-04-24 06:06:01,476 INFO waiting: 17963 / active: 1 / done: 37 2024-04-24 06:06:01,476 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,476 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:06:01,476 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:06:01,476 INFO ================================================================================ 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,476 INFO 2024-04-24 06:06:01,477 INFO ================================================================================ 2024-04-24 06:06:01,477 INFO 2024-04-24 06:33:54 20s elapsed 2024-04-24 06:06:01,477 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,477 INFO > Global (OK=51 KO=0 ) 2024-04-24 06:06:01,477 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:06:01,477 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:06:01,477 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,477 INFO > ReadManagedUser (OK=15 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryFull (OK=8 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryStartsWith (OK=13 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryBySn (OK=8 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryPartial (OK=4 KO=0 ) 2024-04-24 06:06:01,477 INFO 2024-04-24 06:06:01,477 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,477 INFO [- ] 0% 2024-04-24 06:06:01,477 INFO waiting: 17934 / active: 2 / done: 65 2024-04-24 06:06:01,477 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,477 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:06:01,477 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:06:01,477 INFO ================================================================================ 2024-04-24 06:06:01,477 INFO 2024-04-24 06:06:01,477 INFO 2024-04-24 06:06:01,477 INFO ================================================================================ 2024-04-24 06:06:01,477 INFO 2024-04-24 06:33:59 25s elapsed 2024-04-24 06:06:01,477 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,477 INFO > Global (OK=89 KO=0 ) 2024-04-24 06:06:01,477 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:06:01,477 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:06:01,477 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,477 INFO > ReadManagedUser (OK=23 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryFull (OK=13 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryStartsWith (OK=22 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryBySn (OK=14 KO=0 ) 2024-04-24 06:06:01,477 INFO > queryPartial (OK=14 KO=0 ) 2024-04-24 06:06:01,477 INFO 2024-04-24 06:06:01,477 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,477 INFO [...] 2024-04-24 06:06:01,480 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,480 INFO > ReadManagedUser (OK=3619 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryFull (OK=3614 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryStartsWith (OK=3516 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryBySn (OK=3493 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryPartial (OK=3601 KO=0 ) 2024-04-24 06:06:01,480 INFO 2024-04-24 06:06:01,480 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,480 INFO [######################################################################### ] 99% 2024-04-24 06:06:01,480 INFO waiting: 141 / active: 0 / done: 17860 2024-04-24 06:06:01,480 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,480 INFO [##########################################################################]100% 2024-04-24 06:06:01,480 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:06:01,480 INFO ================================================================================ 2024-04-24 06:06:01,480 INFO 2024-04-24 06:06:01,480 INFO 2024-04-24 06:06:01,480 INFO ================================================================================ 2024-04-24 06:06:01,480 INFO 2024-04-24 07:03:54 1820s elapsed 2024-04-24 06:06:01,480 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,480 INFO > Global (OK=17883 KO=0 ) 2024-04-24 06:06:01,480 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:06:01,480 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:06:01,480 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,480 INFO > ReadManagedUser (OK=3628 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryFull (OK=3623 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryStartsWith (OK=3522 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryBySn (OK=3500 KO=0 ) 2024-04-24 06:06:01,480 INFO > queryPartial (OK=3607 KO=0 ) 2024-04-24 06:06:01,480 INFO 2024-04-24 06:06:01,480 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,480 INFO [#########################################################################-] 99% 2024-04-24 06:06:01,480 INFO waiting: 103 / active: 1 / done: 17897 2024-04-24 06:06:01,480 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,480 INFO [##########################################################################]100% 2024-04-24 06:06:01,480 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:06:01,480 INFO ================================================================================ 2024-04-24 06:06:01,481 INFO 2024-04-24 06:06:01,481 INFO 2024-04-24 06:06:01,481 INFO ================================================================================ 2024-04-24 06:06:01,481 INFO 2024-04-24 07:03:59 1825s elapsed 2024-04-24 06:06:01,481 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,481 INFO > Global (OK=17930 KO=0 ) 2024-04-24 06:06:01,481 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:06:01,481 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:06:01,481 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,481 INFO > ReadManagedUser (OK=3642 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryFull (OK=3629 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryStartsWith (OK=3526 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryBySn (OK=3513 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryPartial (OK=3617 KO=0 ) 2024-04-24 06:06:01,481 INFO 2024-04-24 06:06:01,481 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,481 INFO [#########################################################################-] 99% 2024-04-24 06:06:01,481 INFO waiting: 54 / active: 3 / done: 17944 2024-04-24 06:06:01,481 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,481 INFO [##########################################################################]100% 2024-04-24 06:06:01,481 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:06:01,481 INFO ================================================================================ 2024-04-24 06:06:01,481 INFO 2024-04-24 06:06:01,481 INFO 2024-04-24 06:06:01,481 INFO ================================================================================ 2024-04-24 06:06:01,481 INFO 2024-04-24 07:04:04 1829s elapsed 2024-04-24 06:06:01,481 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:06:01,481 INFO > Global (OK=17987 KO=0 ) 2024-04-24 06:06:01,481 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:06:01,481 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:06:01,481 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:06:01,481 INFO > ReadManagedUser (OK=3648 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryFull (OK=3640 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryStartsWith (OK=3539 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryBySn (OK=3529 KO=0 ) 2024-04-24 06:06:01,481 INFO > queryPartial (OK=3628 KO=0 ) 2024-04-24 06:06:01,481 INFO 2024-04-24 06:06:01,481 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:06:01,481 INFO [##########################################################################]100% 2024-04-24 06:06:01,481 INFO waiting: 0 / active: 0 / done: 18001 2024-04-24 06:06:01,481 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:06:01,482 INFO [##########################################################################]100% 2024-04-24 06:06:01,482 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:06:01,482 INFO ================================================================================ 2024-04-24 06:06:01,482 INFO 2024-04-24 06:06:01,482 INFO Simulation com.forgerock.gatling.Loadtest completed in 1829 seconds 2024-04-24 06:06:01,482 INFO 2024-04-24 06:06:01,482 INFO ---------------------- 2024-04-24 06:06:01,482 INFO [24/Apr/2024 07:04:04] Create tar file mixedquery-10.tar.gz 2024-04-24 06:06:01,482 INFO ---------------------- 2024-04-24 06:06:01,482 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 06:06:01,482 INFO RESULT_FOLDER=mixedquery-10 2024-04-24 06:06:01,482 INFO SIMULATION_PATH=mixedquery-10/loadtest-20240424053334179 2024-04-24 06:06:01,482 INFO PWD=/results/gatling/mixedquery-10/loadtest-20240424053334179 2024-04-24 06:06:01,482 INFO [cmd] tar -czvf /results/gatling//mixedquery-10.tar.gz --exclude=simulation.log * 2024-04-24 06:06:01,482 INFO /results/gatling 2024-04-24 06:06:01,482 INFO 2024-04-24 06:06:01,482 INFO ---------------------- 2024-04-24 06:06:01,482 INFO [24/Apr/2024 07:04:04] Update /results/gatling//latest 2024-04-24 06:06:01,482 INFO ---------------------- 2024-04-24 06:06:01,482 INFO 2024-04-24 06:06:01,482 INFO ---------------------- 2024-04-24 06:06:01,482 INFO [24/Apr/2024 07:04:04] Update /results/gatling//latest.tar.gz 2024-04-24 06:06:01,482 INFO ---------------------- 2024-04-24 06:06:01,482 INFO ________________________________________________________________________________ [2024-04-24 06:06:01] mixedquery-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 06:06:01,483 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/01_mixedquery-10/stderr.txt is small (0 lines), printing all lines: 2024-04-24 06:06:01,483 DEBUG ----- output ----- 2024-04-24 06:06:01,483 DEBUG 2024-04-24 06:06:01,483 INFO ________________________________________________________________________________ [2024-04-24 06:06:01] mixedquery-10 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 06:06:01] mixedquery-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 06:06:01] mixedquery-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped