--Task-- name: mixedquery-20 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-10'] preceding_task: mixedquery-10 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 20} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 06:06:02] mixedquery-20 pre : N/A ________________________________________________________________________________ [2024-04-24 06:06:02] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 06:06:02] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-20/stdout.txt Pod log : /results/orders/mixedquery-20 2024-04-24 06:06:02,459 INFO 2024-04-24 06:06:02,459 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:02,459 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:02,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:02,775 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:02,775 DEBUG --- stdout --- 2024-04-24 06:06:02,775 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:02,775 DEBUG --- stderr --- 2024-04-24 06:06:02,775 DEBUG ________________________________________________________________________________ [2024-04-24 06:06:02] mixedquery-20 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 06:06:02,776 INFO 2024-04-24 06:06:02,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:06:02,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:03,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:03,096 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:03,096 DEBUG --- stdout --- 2024-04-24 06:06:03,097 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:03,097 DEBUG --- stderr --- 2024-04-24 06:06:03,097 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-20 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 06:06:03] mixedquery-20 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 06:06:03,097 INFO 2024-04-24 06:06:03,097 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:03,097 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:03,411 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:03,411 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:03,411 DEBUG --- stdout --- 2024-04-24 06:06:03,411 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:03,411 DEBUG --- stderr --- 2024-04-24 06:06:03,411 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/order.json { "name": "mixedquery-20", "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-20 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-20", "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=20 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 06:06:03] mixedquery-20 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-04-24 06:06:03,413 INFO 2024-04-24 06:06:03,413 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 06:06:03,413 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 06:06:03,662 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:06:03,662 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:03,662 DEBUG --- stdout --- 2024-04-24 06:06:03,662 DEBUG WAITING 2024-04-24 06:06:03,662 DEBUG --- stderr --- 2024-04-24 06:06:03,662 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 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-04-24 06:06:03] mixedquery-20 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 06:06:03,663 INFO 2024-04-24 06:06:03,663 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:03,663 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:03,978 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:03,980 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:03,980 DEBUG --- stdout --- 2024-04-24 06:06:03,980 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:03,980 DEBUG --- stderr --- 2024-04-24 06:06:03,980 DEBUG 2024-04-24 06:06:03,981 INFO 2024-04-24 06:06:03,981 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/02_mixedquery-20/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 06:06:03,981 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:06:05,392 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:05,393 DEBUG --- stdout --- 2024-04-24 06:06:05,393 DEBUG 2024-04-24 06:06:05,393 DEBUG --- stderr --- 2024-04-24 06:06:05,393 DEBUG Order has been successfully forwarded to overseer [2024-04-24 06:06:05] - INFO: Check if Gatling simulation started successfully 2024-04-24 06:06:05,393 INFO 2024-04-24 06:06:05,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:06:05,394 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:06:05,737 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:06:05,737 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:05,737 DEBUG --- stdout --- 2024-04-24 06:06:05,737 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:06:05,737 DEBUG --- stderr --- 2024-04-24 06:06:05,737 DEBUG 2024-04-24 06:06:05,738 INFO 2024-04-24 06:06:05,738 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-20/stdout.txt | grep "started..." 2024-04-24 06:06:05,738 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 06:06:06,548 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 06:06:12,370 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-24 06:06:18,203 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-24 06:06:24,012 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-24 06:06:24,013 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:06:24,013 DEBUG --- stdout --- 2024-04-24 06:06:24,013 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 07:06:08.931 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 07:06:15.113 [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"); ^ 07:06:15.116 [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"); ^ 07:06:17.512 [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 06:06:24,013 DEBUG --- stderr --- 2024-04-24 06:06:24,013 DEBUG ________________________________________________________________________________ [2024-04-24 06:06:24] mixedquery-20 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 06:38:29] mixedquery-20 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 06:38:29,491 INFO 2024-04-24 06:38:29,491 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:29,491 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:29,828 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:29,828 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:29,828 DEBUG --- stdout --- 2024-04-24 06:38:29,828 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:29,828 DEBUG --- stderr --- 2024-04-24 06:38:29,828 DEBUG 2024-04-24 06:38:29,829 INFO 2024-04-24 06:38:29,829 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:38:29,829 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:38:30,649 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:30,650 DEBUG --- stdout --- 2024-04-24 06:38:30,650 DEBUG 2024-04-24 06:38:30,650 DEBUG --- stderr --- 2024-04-24 06:38:30,650 DEBUG Order ran during 32m 25s ________________________________________________________________________________ [2024-04-24 06:38:30] mixedquery-20 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 06:38:30] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/order.json 2024-04-24 06:38:30,651 INFO 2024-04-24 06:38:30,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 06:38:30,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:30,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:30,970 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:30,970 DEBUG --- stdout --- 2024-04-24 06:38:30,971 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:30,971 DEBUG --- stderr --- 2024-04-24 06:38:30,971 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:38:30,971 INFO 2024-04-24 06:38:30,971 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:30,971 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:31,286 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:31,286 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:31,286 DEBUG --- stdout --- 2024-04-24 06:38:31,286 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:31,286 DEBUG --- stderr --- 2024-04-24 06:38:31,286 DEBUG 2024-04-24 06:38:31,287 INFO 2024-04-24 06:38:31,287 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-20/rc.txt" ]; then echo "/results/orders/mixedquery-20/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-20/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-20/rc.txt file found" 2024-04-24 06:38:31,287 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:38:32,363 INFO [loop_until]: Function succeeded after 1s (rc=0) - expected pattern found 2024-04-24 06:38:32,363 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:32,363 DEBUG --- stdout --- 2024-04-24 06:38:32,363 DEBUG /results/orders/mixedquery-20/rc.txt file found 2024-04-24 06:38:32,363 DEBUG --- stderr --- 2024-04-24 06:38:32,363 DEBUG 2024-04-24 06:38:32,363 INFO 2024-04-24 06:38:32,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 06:38:32,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:32,686 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:32,687 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:32,687 DEBUG --- stdout --- 2024-04-24 06:38:32,687 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:32,687 DEBUG --- stderr --- 2024-04-24 06:38:32,687 DEBUG 2024-04-24 06:38:32,687 INFO 2024-04-24 06:38:32,688 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-20/rc.txt 2024-04-24 06:38:33,535 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:38:33,536 DEBUG --- stdout --- 2024-04-24 06:38:33,536 DEBUG 4 /results/orders/mixedquery-20/rc.txt 2024-04-24 06:38:33,536 DEBUG --- stderr --- 2024-04-24 06:38:33,536 DEBUG 2024-04-24 06:38:33,536 INFO - Download file (size 4k) 2024-04-24 06:38:33,537 INFO 2024-04-24 06:38:33,537 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:33,537 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:33,871 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:33,871 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:33,871 DEBUG --- stdout --- 2024-04-24 06:38:33,871 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:33,871 DEBUG --- stderr --- 2024-04-24 06:38:33,871 DEBUG source : /results/orders/mixedquery-20/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/rc.txt 2024-04-24 06:38:33,872 INFO 2024-04-24 06:38:33,872 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:33,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:34,207 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:34,208 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:34,208 DEBUG --- stdout --- 2024-04-24 06:38:34,208 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:34,208 DEBUG --- stderr --- 2024-04-24 06:38:34,208 DEBUG 2024-04-24 06:38:34,208 INFO 2024-04-24 06:38:34,208 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-20/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/rc.txt 2024-04-24 06:38:34,208 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:38:35,014 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:35,015 DEBUG --- stdout --- 2024-04-24 06:38:35,015 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:38:35,015 DEBUG --- stderr --- 2024-04-24 06:38:35,015 DEBUG 2024-04-24 06:38:35,015 INFO 2024-04-24 06:38:35,015 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:35,015 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:35,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:35,334 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:35,334 DEBUG --- stdout --- 2024-04-24 06:38:35,334 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:35,334 DEBUG --- stderr --- 2024-04-24 06:38:35,335 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:38:35,335 INFO 2024-04-24 06:38:35,335 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:35,335 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:35,654 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:35,655 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:35,655 DEBUG --- stdout --- 2024-04-24 06:38:35,655 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:35,655 DEBUG --- stderr --- 2024-04-24 06:38:35,655 DEBUG 2024-04-24 06:38:35,655 INFO 2024-04-24 06:38:35,655 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-20/stderr.txt" ]; then echo "/results/orders/mixedquery-20/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-20/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-20/stderr.txt file found" 2024-04-24 06:38:35,655 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:38:36,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:38:36,510 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:36,510 DEBUG --- stdout --- 2024-04-24 06:38:36,510 DEBUG /results/orders/mixedquery-20/stderr.txt file found 2024-04-24 06:38:36,510 DEBUG --- stderr --- 2024-04-24 06:38:36,510 DEBUG 2024-04-24 06:38:36,511 INFO 2024-04-24 06:38:36,511 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:36,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:36,835 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:36,835 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:36,835 DEBUG --- stdout --- 2024-04-24 06:38:36,835 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:36,835 DEBUG --- stderr --- 2024-04-24 06:38:36,835 DEBUG 2024-04-24 06:38:36,835 INFO 2024-04-24 06:38:36,836 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-20/stderr.txt 2024-04-24 06:38:37,676 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:38:37,677 DEBUG --- stdout --- 2024-04-24 06:38:37,677 DEBUG 0 /results/orders/mixedquery-20/stderr.txt 2024-04-24 06:38:37,677 DEBUG --- stderr --- 2024-04-24 06:38:37,677 DEBUG 2024-04-24 06:38:37,677 INFO - Download file (size 0k) 2024-04-24 06:38:37,678 INFO 2024-04-24 06:38:37,678 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:37,678 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:38,053 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:38,054 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:38,054 DEBUG --- stdout --- 2024-04-24 06:38:38,054 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:38,054 DEBUG --- stderr --- 2024-04-24 06:38:38,054 DEBUG source : /results/orders/mixedquery-20/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/stderr.txt 2024-04-24 06:38:38,054 INFO 2024-04-24 06:38:38,054 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:38,054 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:38,393 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:38,393 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:38,393 DEBUG --- stdout --- 2024-04-24 06:38:38,394 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:38,394 DEBUG --- stderr --- 2024-04-24 06:38:38,394 DEBUG 2024-04-24 06:38:38,394 INFO 2024-04-24 06:38:38,394 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-20/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/stderr.txt 2024-04-24 06:38:38,394 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:38:39,169 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:39,169 DEBUG --- stdout --- 2024-04-24 06:38:39,169 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:38:39,170 DEBUG --- stderr --- 2024-04-24 06:38:39,170 DEBUG 2024-04-24 06:38:39,170 INFO 2024-04-24 06:38:39,170 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:39,170 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:39,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:39,510 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:39,510 DEBUG --- stdout --- 2024-04-24 06:38:39,510 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:39,510 DEBUG --- stderr --- 2024-04-24 06:38:39,510 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:38:39,511 INFO 2024-04-24 06:38:39,511 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:39,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:39,845 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:39,846 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:39,846 DEBUG --- stdout --- 2024-04-24 06:38:39,846 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:39,846 DEBUG --- stderr --- 2024-04-24 06:38:39,846 DEBUG 2024-04-24 06:38:39,847 INFO 2024-04-24 06:38:39,847 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-20/stdout.txt" ]; then echo "/results/orders/mixedquery-20/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-20/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-20/stdout.txt file found" 2024-04-24 06:38:39,847 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:38:40,772 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:38:40,772 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:40,773 DEBUG --- stdout --- 2024-04-24 06:38:40,773 DEBUG /results/orders/mixedquery-20/stdout.txt file found 2024-04-24 06:38:40,773 DEBUG --- stderr --- 2024-04-24 06:38:40,773 DEBUG 2024-04-24 06:38:40,773 INFO 2024-04-24 06:38:40,773 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:40,773 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:41,105 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:41,105 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:41,105 DEBUG --- stdout --- 2024-04-24 06:38:41,106 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:41,106 DEBUG --- stderr --- 2024-04-24 06:38:41,106 DEBUG 2024-04-24 06:38:41,106 INFO 2024-04-24 06:38:41,106 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-20/stdout.txt 2024-04-24 06:38:41,950 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:38:41,950 DEBUG --- stdout --- 2024-04-24 06:38:41,950 DEBUG 540 /results/orders/mixedquery-20/stdout.txt 2024-04-24 06:38:41,950 DEBUG --- stderr --- 2024-04-24 06:38:41,950 DEBUG 2024-04-24 06:38:41,950 INFO - Download file (size 540k) 2024-04-24 06:38:41,951 INFO 2024-04-24 06:38:41,951 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:41,951 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:42,270 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:42,270 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:42,270 DEBUG --- stdout --- 2024-04-24 06:38:42,271 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:42,271 DEBUG --- stderr --- 2024-04-24 06:38:42,271 DEBUG source : /results/orders/mixedquery-20/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/stdout.txt 2024-04-24 06:38:42,271 INFO 2024-04-24 06:38:42,271 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:42,271 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:42,588 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:42,588 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:42,588 DEBUG --- stdout --- 2024-04-24 06:38:42,588 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:42,588 DEBUG --- stderr --- 2024-04-24 06:38:42,588 DEBUG 2024-04-24 06:38:42,589 INFO 2024-04-24 06:38:42,589 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-20/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/stdout.txt 2024-04-24 06:38:42,589 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:38:43,689 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:43,690 DEBUG --- stdout --- 2024-04-24 06:38:43,691 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:38:43,691 DEBUG --- stderr --- 2024-04-24 06:38:43,691 DEBUG ________________________________________________________________________________ [2024-04-24 06:38:43] mixedquery-20 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 06:38:43,692 INFO 2024-04-24 06:38:43,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 06:38:43,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:44,056 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:44,057 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:44,057 DEBUG --- stdout --- 2024-04-24 06:38:44,057 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:44,057 DEBUG --- stderr --- 2024-04-24 06:38:44,057 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 06:38:44,057 INFO 2024-04-24 06:38:44,057 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:44,057 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:44,396 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:44,396 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:44,398 DEBUG --- stdout --- 2024-04-24 06:38:44,398 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:44,398 DEBUG --- stderr --- 2024-04-24 06:38:44,398 DEBUG 2024-04-24 06:38:44,399 INFO 2024-04-24 06:38:44,399 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-20/cmd.txt" ]; then echo "/results/orders/mixedquery-20/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-20/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-20/cmd.txt file found" 2024-04-24 06:38:44,399 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 06:38:45,231 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 06:38:45,231 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:45,231 DEBUG --- stdout --- 2024-04-24 06:38:45,232 DEBUG /results/orders/mixedquery-20/cmd.txt file found 2024-04-24 06:38:45,232 DEBUG --- stderr --- 2024-04-24 06:38:45,232 DEBUG 2024-04-24 06:38:45,232 INFO 2024-04-24 06:38:45,232 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:45,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:45,619 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:45,619 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:45,619 DEBUG --- stdout --- 2024-04-24 06:38:45,620 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:45,620 DEBUG --- stderr --- 2024-04-24 06:38:45,620 DEBUG 2024-04-24 06:38:45,620 INFO 2024-04-24 06:38:45,620 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-20/cmd.txt 2024-04-24 06:38:46,525 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 06:38:46,526 DEBUG --- stdout --- 2024-04-24 06:38:46,526 DEBUG 4 /results/orders/mixedquery-20/cmd.txt 2024-04-24 06:38:46,526 DEBUG --- stderr --- 2024-04-24 06:38:46,526 DEBUG 2024-04-24 06:38:46,526 INFO - Download file (size 4k) 2024-04-24 06:38:46,526 INFO 2024-04-24 06:38:46,526 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:46,526 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:46,886 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:46,886 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:46,886 DEBUG --- stdout --- 2024-04-24 06:38:46,886 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:46,886 DEBUG --- stderr --- 2024-04-24 06:38:46,886 DEBUG source : /results/orders/mixedquery-20/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/cmd.txt 2024-04-24 06:38:46,887 INFO 2024-04-24 06:38:46,887 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 06:38:46,887 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 06:38:47,208 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 06:38:47,209 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:47,209 DEBUG --- stdout --- 2024-04-24 06:38:47,209 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 06:38:47,209 DEBUG --- stderr --- 2024-04-24 06:38:47,209 DEBUG 2024-04-24 06:38:47,209 INFO 2024-04-24 06:38:47,209 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-20/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/cmd.txt 2024-04-24 06:38:47,209 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 06:38:47,996 INFO [loop_until]: OK (rc = 0) 2024-04-24 06:38:47,997 DEBUG --- stdout --- 2024-04-24 06:38:47,997 DEBUG tar: Removing leading `/' from member names 2024-04-24 06:38:47,997 DEBUG --- stderr --- 2024-04-24 06:38:47,997 DEBUG 2024-04-24 06:38:47,997 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/cmd.txt is small (1 lines), printing all lines: 2024-04-24 06:38:47,997 DEBUG ----- output ----- 2024-04-24 06:38:47,997 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-20 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 06:38:47,997 INFO ________________________________________________________________________________ [2024-04-24 06:38:47] mixedquery-20 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 06:38:47,998 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/rc.txt is small (1 lines), printing all lines: 2024-04-24 06:38:47,998 DEBUG ----- output ----- 2024-04-24 06:38:47,998 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 06:38:47,998 INFO ________________________________________________________________________________ [2024-04-24 06:38:47] mixedquery-20 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 06:38:47,999 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/02_mixedquery-20/stdout.txt is 8090 lines): 2024-04-24 06:38:47,999 DEBUG ----- output ----- 2024-04-24 06:38:47,999 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 06:38:47,999 INFO 07:06:08.931 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-24 06:38:47,999 INFO 07:06:15.113 [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:38:47,999 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-24 06:38:47,999 INFO ^ 2024-04-24 06:38:47,999 INFO 07:06:15.116 [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:38:47,999 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-24 06:38:47,999 INFO ^ 2024-04-24 06:38:47,999 INFO 07:06:17.512 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-24 06:38:47,999 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 06:38:48,000 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO ================================================================================ 2024-04-24 06:38:48,000 INFO 2024-04-24 07:06:26 5s elapsed 2024-04-24 06:38:48,000 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO > Global (OK=0 KO=0 ) 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO [- ] 0% 2024-04-24 06:38:48,000 INFO waiting: 36163 / active: 3 / done: 6 2024-04-24 06:38:48,000 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:38:48,000 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:38:48,000 INFO ================================================================================ 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO ================================================================================ 2024-04-24 06:38:48,000 INFO 2024-04-24 07:06:31 10s elapsed 2024-04-24 06:38:48,000 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO > Global (OK=0 KO=0 ) 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO [- ] 0% 2024-04-24 06:38:48,000 INFO waiting: 36138 / active: 6 / done: 28 2024-04-24 06:38:48,000 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:38:48,000 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:38:48,000 INFO ================================================================================ 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO 2024-04-24 06:38:48,000 INFO ================================================================================ 2024-04-24 06:38:48,000 INFO 2024-04-24 07:06:36 15s elapsed 2024-04-24 06:38:48,000 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,000 INFO > Global (OK=42 KO=0 ) 2024-04-24 06:38:48,000 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:38:48,000 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:38:48,000 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,000 INFO > queryFull (OK=10 KO=0 ) 2024-04-24 06:38:48,000 INFO > queryBySn (OK=5 KO=0 ) 2024-04-24 06:38:48,000 INFO > queryStartsWith (OK=10 KO=0 ) 2024-04-24 06:38:48,001 INFO > queryPartial (OK=8 KO=0 ) 2024-04-24 06:38:48,001 INFO > ReadManagedUser (OK=6 KO=0 ) 2024-04-24 06:38:48,001 INFO 2024-04-24 06:38:48,001 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,001 INFO [- ] 0% 2024-04-24 06:38:48,001 INFO waiting: 36096 / active: 1 / done: 75 2024-04-24 06:38:48,001 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,001 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:38:48,001 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:38:48,001 INFO ================================================================================ 2024-04-24 06:38:48,001 INFO 2024-04-24 06:38:48,001 INFO 2024-04-24 06:38:48,001 INFO ================================================================================ 2024-04-24 06:38:48,001 INFO 2024-04-24 07:06:41 20s elapsed 2024-04-24 06:38:48,001 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,001 INFO > Global (OK=100 KO=0 ) 2024-04-24 06:38:48,001 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:38:48,001 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:38:48,001 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,001 INFO > queryFull (OK=24 KO=0 ) 2024-04-24 06:38:48,001 INFO > queryBySn (OK=19 KO=0 ) 2024-04-24 06:38:48,001 INFO > queryStartsWith (OK=19 KO=0 ) 2024-04-24 06:38:48,001 INFO > queryPartial (OK=14 KO=0 ) 2024-04-24 06:38:48,001 INFO > ReadManagedUser (OK=21 KO=0 ) 2024-04-24 06:38:48,001 INFO 2024-04-24 06:38:48,001 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,001 INFO [- ] 0% 2024-04-24 06:38:48,001 INFO waiting: 36038 / active: 1 / done: 133 2024-04-24 06:38:48,001 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,001 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 06:38:48,001 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 06:38:48,001 INFO ================================================================================ 2024-04-24 06:38:48,001 INFO 2024-04-24 06:38:48,001 INFO 2024-04-24 06:38:48,001 INFO ================================================================================ 2024-04-24 06:38:48,001 INFO 2024-04-24 07:06:46 25s elapsed 2024-04-24 06:38:48,001 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,001 INFO > Global (OK=171 KO=0 ) 2024-04-24 06:38:48,001 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:38:48,001 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:38:48,001 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,001 INFO > queryFull (OK=38 KO=0 ) 2024-04-24 06:38:48,002 INFO > queryBySn (OK=33 KO=0 ) 2024-04-24 06:38:48,002 INFO > queryStartsWith (OK=32 KO=0 ) 2024-04-24 06:38:48,002 INFO > queryPartial (OK=30 KO=0 ) 2024-04-24 06:38:48,002 INFO > ReadManagedUser (OK=35 KO=0 ) 2024-04-24 06:38:48,002 INFO 2024-04-24 06:38:48,002 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,002 INFO [...] 2024-04-24 06:38:48,004 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,004 INFO > queryFull (OK=7261 KO=0 ) 2024-04-24 06:38:48,004 INFO > queryBySn (OK=6988 KO=0 ) 2024-04-24 06:38:48,004 INFO > queryStartsWith (OK=7094 KO=0 ) 2024-04-24 06:38:48,004 INFO > queryPartial (OK=7059 KO=0 ) 2024-04-24 06:38:48,004 INFO > ReadManagedUser (OK=7100 KO=0 ) 2024-04-24 06:38:48,004 INFO 2024-04-24 06:38:48,004 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,004 INFO [#########################################################################-] 99% 2024-04-24 06:38:48,004 INFO waiting: 194 / active: 20 / done: 35958 2024-04-24 06:38:48,004 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,004 INFO [##########################################################################]100% 2024-04-24 06:38:48,004 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:38:48,004 INFO ================================================================================ 2024-04-24 06:38:48,004 INFO 2024-04-24 06:38:48,004 INFO 2024-04-24 06:38:48,004 INFO ================================================================================ 2024-04-24 06:38:48,004 INFO 2024-04-24 07:36:46 1825s elapsed 2024-04-24 06:38:48,004 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,004 INFO > Global (OK=35505 KO=0 ) 2024-04-24 06:38:48,004 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:38:48,004 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:38:48,005 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryFull (OK=7261 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryBySn (OK=6988 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryStartsWith (OK=7094 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryPartial (OK=7059 KO=0 ) 2024-04-24 06:38:48,005 INFO > ReadManagedUser (OK=7100 KO=0 ) 2024-04-24 06:38:48,005 INFO 2024-04-24 06:38:48,005 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,005 INFO [#########################################################################-] 99% 2024-04-24 06:38:48,005 INFO waiting: 86 / active: 21 / done: 36065 2024-04-24 06:38:48,005 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,005 INFO [##########################################################################]100% 2024-04-24 06:38:48,005 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:38:48,005 INFO ================================================================================ 2024-04-24 06:38:48,005 INFO 2024-04-24 06:38:48,005 INFO 2024-04-24 06:38:48,005 INFO ================================================================================ 2024-04-24 06:38:48,005 INFO 2024-04-24 07:36:51 1830s elapsed 2024-04-24 06:38:48,005 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,005 INFO > Global (OK=35505 KO=0 ) 2024-04-24 06:38:48,005 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:38:48,005 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:38:48,005 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryFull (OK=7261 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryBySn (OK=6988 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryStartsWith (OK=7094 KO=0 ) 2024-04-24 06:38:48,005 INFO > queryPartial (OK=7059 KO=0 ) 2024-04-24 06:38:48,005 INFO > ReadManagedUser (OK=7100 KO=0 ) 2024-04-24 06:38:48,005 INFO 2024-04-24 06:38:48,005 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,005 INFO [#########################################################################-] 99% 2024-04-24 06:38:48,005 INFO waiting: 0 / active: 18 / done: 36154 2024-04-24 06:38:48,005 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,005 INFO [##########################################################################]100% 2024-04-24 06:38:48,005 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:38:48,005 INFO ================================================================================ 2024-04-24 06:38:48,005 INFO 2024-04-24 06:38:48,005 INFO 2024-04-24 06:38:48,005 INFO ================================================================================ 2024-04-24 06:38:48,005 INFO 2024-04-24 07:36:52 1830s elapsed 2024-04-24 06:38:48,005 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 06:38:48,005 INFO > Global (OK=35505 KO=0 ) 2024-04-24 06:38:48,006 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 06:38:48,006 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 06:38:48,006 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 06:38:48,006 INFO > queryFull (OK=7261 KO=0 ) 2024-04-24 06:38:48,006 INFO > queryBySn (OK=6988 KO=0 ) 2024-04-24 06:38:48,006 INFO > queryStartsWith (OK=7094 KO=0 ) 2024-04-24 06:38:48,006 INFO > queryPartial (OK=7059 KO=0 ) 2024-04-24 06:38:48,006 INFO > ReadManagedUser (OK=7100 KO=0 ) 2024-04-24 06:38:48,006 INFO 2024-04-24 06:38:48,006 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 06:38:48,006 INFO [##########################################################################]100% 2024-04-24 06:38:48,006 INFO waiting: 0 / active: 0 / done: 36172 2024-04-24 06:38:48,006 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 06:38:48,006 INFO [##########################################################################]100% 2024-04-24 06:38:48,006 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 06:38:48,006 INFO ================================================================================ 2024-04-24 06:38:48,006 INFO 2024-04-24 06:38:48,006 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-24 06:38:48,006 INFO 2024-04-24 06:38:48,006 INFO ---------------------- 2024-04-24 06:38:48,006 INFO [24/Apr/2024 07:36:52] Create tar file mixedquery-20.tar.gz 2024-04-24 06:38:48,006 INFO ---------------------- 2024-04-24 06:38:48,006 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 06:38:48,006 INFO RESULT_FOLDER=mixedquery-20 2024-04-24 06:38:48,006 INFO SIMULATION_PATH=mixedquery-20/loadtest-20240424060620549 2024-04-24 06:38:48,006 INFO PWD=/results/gatling/mixedquery-20/loadtest-20240424060620549 2024-04-24 06:38:48,006 INFO [cmd] tar -czvf /results/gatling//mixedquery-20.tar.gz --exclude=simulation.log * 2024-04-24 06:38:48,006 INFO /results/gatling 2024-04-24 06:38:48,006 INFO 2024-04-24 06:38:48,006 INFO ---------------------- 2024-04-24 06:38:48,006 INFO [24/Apr/2024 07:36:52] Update /results/gatling//latest 2024-04-24 06:38:48,006 INFO ---------------------- 2024-04-24 06:38:48,006 INFO 2024-04-24 06:38:48,006 INFO ---------------------- 2024-04-24 06:38:48,006 INFO [24/Apr/2024 07:36:52] Update /results/gatling//latest.tar.gz 2024-04-24 06:38:48,006 INFO ---------------------- 2024-04-24 06:38:48,006 INFO ________________________________________________________________________________ [2024-04-24 06:38:48] mixedquery-20 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 06:38:48,007 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/02_mixedquery-20/stderr.txt is small (0 lines), printing all lines: 2024-04-24 06:38:48,007 DEBUG ----- output ----- 2024-04-24 06:38:48,007 DEBUG 2024-04-24 06:38:48,007 INFO ________________________________________________________________________________ [2024-04-24 06:38:48] mixedquery-20 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:38:48] mixedquery-20 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 06:38:48] mixedquery-20 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped