--Task-- name: mixedquery-40 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-30'] preceding_task: mixedquery-30 options: {'duration': 1800, 'java-opts': '-Dcsvfile=/results/idm/user_feeder.csv', 'simulation': 'com.forgerock.gatling.Loadtest', 'testCase': 'idm.MixedQuery', 'testMode': 'open', 'throughput': 40} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-24 07:11:23] mixedquery-40 pre : N/A ________________________________________________________________________________ [2024-04-24 07:11:23] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-24 07:11:23] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/mixedquery-40/stdout.txt Pod log : /results/orders/mixedquery-40 2024-04-24 07:11:23,610 INFO 2024-04-24 07:11:23,611 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 07:11:23,611 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:23,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:23,935 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:23,935 DEBUG --- stdout --- 2024-04-24 07:11:23,935 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:23,935 DEBUG --- stderr --- 2024-04-24 07:11:23,935 DEBUG ________________________________________________________________________________ [2024-04-24 07:11:23] mixedquery-40 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-24 07:11:23,936 INFO 2024-04-24 07:11:23,936 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 07:11:23,936 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:24,266 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:24,266 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:24,266 DEBUG --- stdout --- 2024-04-24 07:11:24,266 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:24,267 DEBUG --- stderr --- 2024-04-24 07:11:24,267 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-40 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-04-24 07:11:24] mixedquery-40 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-24 07:11:24,267 INFO 2024-04-24 07:11:24,267 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 07:11:24,267 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:24,644 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:24,644 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:24,644 DEBUG --- stdout --- 2024-04-24 07:11:24,644 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:24,644 DEBUG --- stderr --- 2024-04-24 07:11:24,644 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/order.json { "name": "mixedquery-40", "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-40 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/mixedquery-40", "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=40 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-24 07:11:24] mixedquery-40 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 07:11:24,646 INFO 2024-04-24 07:11:24,646 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-24 07:11:24,646 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 07:11:24,883 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:11:24,883 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:24,883 DEBUG --- stdout --- 2024-04-24 07:11:24,883 DEBUG WAITING 2024-04-24 07:11:24,883 DEBUG --- stderr --- 2024-04-24 07:11:24,883 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-04-24 07:11:24] mixedquery-40 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-24 07:11:24,883 INFO 2024-04-24 07:11:24,884 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 07:11:24,884 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:25,202 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:25,203 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:25,203 DEBUG --- stdout --- 2024-04-24 07:11:25,203 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:25,203 DEBUG --- stderr --- 2024-04-24 07:11:25,203 DEBUG 2024-04-24 07:11:25,203 INFO 2024-04-24 07:11:25,203 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/04_mixedquery-40/order.json overseer-0-55d5975bd7-gp92m:/results/orders/order.json 2024-04-24 07:11:25,203 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:11:26,594 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:26,594 DEBUG --- stdout --- 2024-04-24 07:11:26,594 DEBUG 2024-04-24 07:11:26,594 DEBUG --- stderr --- 2024-04-24 07:11:26,594 DEBUG Order has been successfully forwarded to overseer [2024-04-24 07:11:26] - INFO: Check if Gatling simulation started successfully 2024-04-24 07:11:26,595 INFO 2024-04-24 07:11:26,595 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 07:11:26,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:11:26,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:11:26,906 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:26,906 DEBUG --- stdout --- 2024-04-24 07:11:26,907 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:11:26,907 DEBUG --- stderr --- 2024-04-24 07:11:26,907 DEBUG 2024-04-24 07:11:26,907 INFO 2024-04-24 07:11:26,907 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-40/stdout.txt | grep "started..." 2024-04-24 07:11:26,907 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-24 07:11:27,681 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-24 07:11:33,454 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-04-24 07:11:33,454 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:11:33,455 DEBUG --- stdout --- 2024-04-24 07:11:33,456 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 07:11:33,456 DEBUG --- stderr --- 2024-04-24 07:11:33,456 DEBUG ________________________________________________________________________________ [2024-04-24 07:11:33] mixedquery-40 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 07:43:38] mixedquery-40 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-24 07:43:38,976 INFO 2024-04-24 07:43:38,976 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 07:43:38,977 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:39,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:39,317 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:39,317 DEBUG --- stdout --- 2024-04-24 07:43:39,317 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:39,317 DEBUG --- stderr --- 2024-04-24 07:43:39,317 DEBUG 2024-04-24 07:43:39,317 INFO 2024-04-24 07:43:39,317 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 07:43:39,318 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:43:40,130 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:40,130 DEBUG --- stdout --- 2024-04-24 07:43:40,131 DEBUG 2024-04-24 07:43:40,131 DEBUG --- stderr --- 2024-04-24 07:43:40,131 DEBUG Order ran during 32m 13s ________________________________________________________________________________ [2024-04-24 07:43:40] mixedquery-40 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-24 07:43:40] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/order.json 2024-04-24 07:43:40,131 INFO 2024-04-24 07:43:40,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 07:43:40,132 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:40,446 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:40,446 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:40,446 DEBUG --- stdout --- 2024-04-24 07:43:40,447 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:40,447 DEBUG --- stderr --- 2024-04-24 07:43:40,447 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:43:40,447 INFO 2024-04-24 07:43:40,447 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 07:43:40,447 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:40,799 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:40,799 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:40,800 DEBUG --- stdout --- 2024-04-24 07:43:40,800 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:40,800 DEBUG --- stderr --- 2024-04-24 07:43:40,800 DEBUG 2024-04-24 07:43:40,801 INFO 2024-04-24 07:43:40,801 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-40/rc.txt" ]; then echo "/results/orders/mixedquery-40/rc.txt file found"; exit 0; else echo "/results/orders/mixedquery-40/rc.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-40/rc.txt file found" 2024-04-24 07:43:40,801 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:43:41,694 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:43:41,694 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:41,694 DEBUG --- stdout --- 2024-04-24 07:43:41,694 DEBUG /results/orders/mixedquery-40/rc.txt file found 2024-04-24 07:43:41,694 DEBUG --- stderr --- 2024-04-24 07:43:41,694 DEBUG 2024-04-24 07:43:41,695 INFO 2024-04-24 07:43:41,695 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 07:43:41,695 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:42,017 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:42,017 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:42,017 DEBUG --- stdout --- 2024-04-24 07:43:42,017 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:42,017 DEBUG --- stderr --- 2024-04-24 07:43:42,017 DEBUG 2024-04-24 07:43:42,017 INFO 2024-04-24 07:43:42,018 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-40/rc.txt 2024-04-24 07:43:42,857 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:43:42,857 DEBUG --- stdout --- 2024-04-24 07:43:42,857 DEBUG 4 /results/orders/mixedquery-40/rc.txt 2024-04-24 07:43:42,857 DEBUG --- stderr --- 2024-04-24 07:43:42,857 DEBUG 2024-04-24 07:43:42,857 INFO - Download file (size 4k) 2024-04-24 07:43:42,857 INFO 2024-04-24 07:43:42,857 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 07:43:42,857 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:43,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:43,174 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:43,174 DEBUG --- stdout --- 2024-04-24 07:43:43,174 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:43,174 DEBUG --- stderr --- 2024-04-24 07:43:43,174 DEBUG source : /results/orders/mixedquery-40/rc.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/rc.txt 2024-04-24 07:43:43,174 INFO 2024-04-24 07:43:43,174 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-24 07:43:43,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:43,495 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:43,496 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:43,496 DEBUG --- stdout --- 2024-04-24 07:43:43,496 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:43,496 DEBUG --- stderr --- 2024-04-24 07:43:43,496 DEBUG 2024-04-24 07:43:43,496 INFO 2024-04-24 07:43:43,496 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-40/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/rc.txt 2024-04-24 07:43:43,496 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:43:44,248 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:44,248 DEBUG --- stdout --- 2024-04-24 07:43:44,248 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:43:44,248 DEBUG --- stderr --- 2024-04-24 07:43:44,248 DEBUG 2024-04-24 07:43:44,248 INFO 2024-04-24 07:43:44,249 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 07:43:44,249 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:44,564 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:44,564 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:44,564 DEBUG --- stdout --- 2024-04-24 07:43:44,564 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:44,564 DEBUG --- stderr --- 2024-04-24 07:43:44,564 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:43:44,564 INFO 2024-04-24 07:43:44,564 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 07:43:44,564 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:44,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:44,879 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:44,879 DEBUG --- stdout --- 2024-04-24 07:43:44,879 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:44,879 DEBUG --- stderr --- 2024-04-24 07:43:44,879 DEBUG 2024-04-24 07:43:44,879 INFO 2024-04-24 07:43:44,880 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-40/stderr.txt" ]; then echo "/results/orders/mixedquery-40/stderr.txt file found"; exit 0; else echo "/results/orders/mixedquery-40/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-40/stderr.txt file found" 2024-04-24 07:43:44,880 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:43:45,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:43:45,717 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:45,717 DEBUG --- stdout --- 2024-04-24 07:43:45,717 DEBUG /results/orders/mixedquery-40/stderr.txt file found 2024-04-24 07:43:45,717 DEBUG --- stderr --- 2024-04-24 07:43:45,717 DEBUG 2024-04-24 07:43:45,717 INFO 2024-04-24 07:43:45,717 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 07:43:45,717 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:46,034 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:46,034 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:46,034 DEBUG --- stdout --- 2024-04-24 07:43:46,034 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:46,035 DEBUG --- stderr --- 2024-04-24 07:43:46,035 DEBUG 2024-04-24 07:43:46,035 INFO 2024-04-24 07:43:46,035 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-40/stderr.txt 2024-04-24 07:43:46,941 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:43:46,943 DEBUG --- stdout --- 2024-04-24 07:43:46,943 DEBUG 0 /results/orders/mixedquery-40/stderr.txt 2024-04-24 07:43:46,943 DEBUG --- stderr --- 2024-04-24 07:43:46,943 DEBUG 2024-04-24 07:43:46,943 INFO - Download file (size 0k) 2024-04-24 07:43:46,944 INFO 2024-04-24 07:43:46,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 07:43:46,944 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:47,263 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:47,263 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:47,263 DEBUG --- stdout --- 2024-04-24 07:43:47,263 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:47,264 DEBUG --- stderr --- 2024-04-24 07:43:47,264 DEBUG source : /results/orders/mixedquery-40/stderr.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/stderr.txt 2024-04-24 07:43:47,264 INFO 2024-04-24 07:43:47,264 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 07:43:47,264 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:47,584 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:47,585 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:47,585 DEBUG --- stdout --- 2024-04-24 07:43:47,585 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:47,585 DEBUG --- stderr --- 2024-04-24 07:43:47,585 DEBUG 2024-04-24 07:43:47,585 INFO 2024-04-24 07:43:47,585 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-40/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/stderr.txt 2024-04-24 07:43:47,585 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:43:48,340 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:48,340 DEBUG --- stdout --- 2024-04-24 07:43:48,340 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:43:48,340 DEBUG --- stderr --- 2024-04-24 07:43:48,340 DEBUG 2024-04-24 07:43:48,341 INFO 2024-04-24 07:43:48,341 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 07:43:48,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:48,667 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:48,667 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:48,667 DEBUG --- stdout --- 2024-04-24 07:43:48,667 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:48,667 DEBUG --- stderr --- 2024-04-24 07:43:48,668 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:43:48,668 INFO 2024-04-24 07:43:48,668 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 07:43:48,668 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:48,986 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:48,987 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:48,987 DEBUG --- stdout --- 2024-04-24 07:43:48,987 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:48,987 DEBUG --- stderr --- 2024-04-24 07:43:48,987 DEBUG 2024-04-24 07:43:48,987 INFO 2024-04-24 07:43:48,987 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-40/stdout.txt" ]; then echo "/results/orders/mixedquery-40/stdout.txt file found"; exit 0; else echo "/results/orders/mixedquery-40/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-40/stdout.txt file found" 2024-04-24 07:43:48,987 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:43:49,826 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:43:49,826 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:49,826 DEBUG --- stdout --- 2024-04-24 07:43:49,826 DEBUG /results/orders/mixedquery-40/stdout.txt file found 2024-04-24 07:43:49,826 DEBUG --- stderr --- 2024-04-24 07:43:49,826 DEBUG 2024-04-24 07:43:49,826 INFO 2024-04-24 07:43:49,827 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 07:43:49,827 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:50,139 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:50,139 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:50,139 DEBUG --- stdout --- 2024-04-24 07:43:50,140 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:50,140 DEBUG --- stderr --- 2024-04-24 07:43:50,140 DEBUG 2024-04-24 07:43:50,140 INFO 2024-04-24 07:43:50,140 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-40/stdout.txt 2024-04-24 07:43:50,975 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:43:50,976 DEBUG --- stdout --- 2024-04-24 07:43:50,976 DEBUG 540 /results/orders/mixedquery-40/stdout.txt 2024-04-24 07:43:50,976 DEBUG --- stderr --- 2024-04-24 07:43:50,976 DEBUG 2024-04-24 07:43:50,976 INFO - Download file (size 540k) 2024-04-24 07:43:50,976 INFO 2024-04-24 07:43:50,976 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 07:43:50,976 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:51,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:51,311 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:51,311 DEBUG --- stdout --- 2024-04-24 07:43:51,311 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:51,311 DEBUG --- stderr --- 2024-04-24 07:43:51,311 DEBUG source : /results/orders/mixedquery-40/stdout.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/stdout.txt 2024-04-24 07:43:51,311 INFO 2024-04-24 07:43:51,311 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 07:43:51,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:51,629 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:51,630 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:51,630 DEBUG --- stdout --- 2024-04-24 07:43:51,630 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:51,630 DEBUG --- stderr --- 2024-04-24 07:43:51,630 DEBUG 2024-04-24 07:43:51,630 INFO 2024-04-24 07:43:51,630 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-40/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/stdout.txt 2024-04-24 07:43:51,630 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:43:52,720 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:52,720 DEBUG --- stdout --- 2024-04-24 07:43:52,720 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:43:52,720 DEBUG --- stderr --- 2024-04-24 07:43:52,720 DEBUG ________________________________________________________________________________ [2024-04-24 07:43:52] mixedquery-40 show_cmd : Display order command ________________________________________________________________________________ 2024-04-24 07:43:52,721 INFO 2024-04-24 07:43:52,721 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 07:43:52,721 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:53,043 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:53,043 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:53,043 DEBUG --- stdout --- 2024-04-24 07:43:53,043 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:53,043 DEBUG --- stderr --- 2024-04-24 07:43:53,043 DEBUG Checking pod: overseer-0-55d5975bd7-gp92m for file exists, if not wait. 2024-04-24 07:43:53,044 INFO 2024-04-24 07:43:53,044 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 07:43:53,044 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:53,360 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:53,361 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:53,361 DEBUG --- stdout --- 2024-04-24 07:43:53,361 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:53,361 DEBUG --- stderr --- 2024-04-24 07:43:53,361 DEBUG 2024-04-24 07:43:53,362 INFO 2024-04-24 07:43:53,362 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-40/cmd.txt" ]; then echo "/results/orders/mixedquery-40/cmd.txt file found"; exit 0; else echo "/results/orders/mixedquery-40/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/mixedquery-40/cmd.txt file found" 2024-04-24 07:43:53,362 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-24 07:43:54,204 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-24 07:43:54,204 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:54,204 DEBUG --- stdout --- 2024-04-24 07:43:54,204 DEBUG /results/orders/mixedquery-40/cmd.txt file found 2024-04-24 07:43:54,204 DEBUG --- stderr --- 2024-04-24 07:43:54,204 DEBUG 2024-04-24 07:43:54,204 INFO 2024-04-24 07:43:54,204 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 07:43:54,204 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:54,526 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:54,527 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:54,527 DEBUG --- stdout --- 2024-04-24 07:43:54,527 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:54,527 DEBUG --- stderr --- 2024-04-24 07:43:54,527 DEBUG 2024-04-24 07:43:54,527 INFO 2024-04-24 07:43:54,527 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-40/cmd.txt 2024-04-24 07:43:55,363 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-24 07:43:55,363 DEBUG --- stdout --- 2024-04-24 07:43:55,363 DEBUG 4 /results/orders/mixedquery-40/cmd.txt 2024-04-24 07:43:55,363 DEBUG --- stderr --- 2024-04-24 07:43:55,363 DEBUG 2024-04-24 07:43:55,363 INFO - Download file (size 4k) 2024-04-24 07:43:55,364 INFO 2024-04-24 07:43:55,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 07:43:55,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:55,684 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:55,685 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:55,685 DEBUG --- stdout --- 2024-04-24 07:43:55,685 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:55,685 DEBUG --- stderr --- 2024-04-24 07:43:55,685 DEBUG source : /results/orders/mixedquery-40/cmd.txt @ overseer-0-55d5975bd7-gp92m destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/cmd.txt 2024-04-24 07:43:55,685 INFO 2024-04-24 07:43:55,685 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 07:43:55,685 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-24 07:43:55,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-24 07:43:55,998 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:55,998 DEBUG --- stdout --- 2024-04-24 07:43:55,998 DEBUG overseer-0-55d5975bd7-gp92m 2024-04-24 07:43:55,998 DEBUG --- stderr --- 2024-04-24 07:43:55,998 DEBUG 2024-04-24 07:43:55,998 INFO 2024-04-24 07:43:55,998 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-55d5975bd7-gp92m:/results/orders/mixedquery-40/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/cmd.txt 2024-04-24 07:43:55,999 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-24 07:43:56,756 INFO [loop_until]: OK (rc = 0) 2024-04-24 07:43:56,757 DEBUG --- stdout --- 2024-04-24 07:43:56,757 DEBUG tar: Removing leading `/' from member names 2024-04-24 07:43:56,757 DEBUG --- stderr --- 2024-04-24 07:43:56,757 DEBUG 2024-04-24 07:43:56,757 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/cmd.txt is small (1 lines), printing all lines: 2024-04-24 07:43:56,757 DEBUG ----- output ----- 2024-04-24 07:43:56,757 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-40 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-04-24 07:43:56,757 INFO ________________________________________________________________________________ [2024-04-24 07:43:56] mixedquery-40 show_rc : Display rc order ________________________________________________________________________________ 2024-04-24 07:43:56,758 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/rc.txt is small (1 lines), printing all lines: 2024-04-24 07:43:56,758 DEBUG ----- output ----- 2024-04-24 07:43:56,758 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-24 07:43:56,758 INFO ________________________________________________________________________________ [2024-04-24 07:43:56] mixedquery-40 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-24 07:43:56,759 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/04_mixedquery-40/stdout.txt is 8082 lines): 2024-04-24 07:43:56,759 DEBUG ----- output ----- 2024-04-24 07:43:56,759 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-24 07:43:56,760 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-24 07:43:56,760 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO ================================================================================ 2024-04-24 07:43:56,760 INFO 2024-04-24 08:11:37 5s elapsed 2024-04-24 07:43:56,760 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO > Global (OK=0 KO=0 ) 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO [- ] 0% 2024-04-24 07:43:56,760 INFO waiting: 72692 / active: 6 / done: 11 2024-04-24 07:43:56,760 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:43:56,760 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:43:56,760 INFO ================================================================================ 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO ================================================================================ 2024-04-24 07:43:56,760 INFO 2024-04-24 08:11:42 10s elapsed 2024-04-24 07:43:56,760 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO > Global (OK=0 KO=0 ) 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO [- ] 0% 2024-04-24 07:43:56,760 INFO waiting: 72642 / active: 12 / done: 55 2024-04-24 07:43:56,760 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:43:56,760 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:43:56,760 INFO ================================================================================ 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO 2024-04-24 07:43:56,760 INFO ================================================================================ 2024-04-24 07:43:56,760 INFO 2024-04-24 08:11:47 15s elapsed 2024-04-24 07:43:56,760 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,760 INFO > Global (OK=82 KO=0 ) 2024-04-24 07:43:56,760 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:43:56,760 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:43:56,761 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,761 INFO > ReadManagedUser (OK=16 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryStartsWith (OK=12 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryPartial (OK=16 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryBySn (OK=23 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryFull (OK=12 KO=0 ) 2024-04-24 07:43:56,761 INFO 2024-04-24 07:43:56,761 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,761 INFO [- ] 0% 2024-04-24 07:43:56,761 INFO waiting: 72558 / active: 3 / done: 148 2024-04-24 07:43:56,761 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,761 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:43:56,761 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:43:56,761 INFO ================================================================================ 2024-04-24 07:43:56,761 INFO 2024-04-24 07:43:56,761 INFO 2024-04-24 07:43:56,761 INFO ================================================================================ 2024-04-24 07:43:56,761 INFO 2024-04-24 08:11:52 20s elapsed 2024-04-24 07:43:56,761 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,761 INFO > Global (OK=199 KO=0 ) 2024-04-24 07:43:56,761 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:43:56,761 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:43:56,761 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,761 INFO > ReadManagedUser (OK=42 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryStartsWith (OK=30 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryPartial (OK=40 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryBySn (OK=46 KO=0 ) 2024-04-24 07:43:56,761 INFO > queryFull (OK=38 KO=0 ) 2024-04-24 07:43:56,761 INFO 2024-04-24 07:43:56,761 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,761 INFO [- ] 0% 2024-04-24 07:43:56,761 INFO waiting: 72442 / active: 2 / done: 265 2024-04-24 07:43:56,761 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,761 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:43:56,761 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:43:56,761 INFO ================================================================================ 2024-04-24 07:43:56,761 INFO 2024-04-24 07:43:56,761 INFO 2024-04-24 07:43:56,761 INFO ================================================================================ 2024-04-24 07:43:56,761 INFO 2024-04-24 08:11:57 25s elapsed 2024-04-24 07:43:56,761 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,761 INFO > Global (OK=348 KO=0 ) 2024-04-24 07:43:56,762 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:43:56,762 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:43:56,762 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,762 INFO > ReadManagedUser (OK=74 KO=0 ) 2024-04-24 07:43:56,762 INFO > queryStartsWith (OK=64 KO=0 ) 2024-04-24 07:43:56,762 INFO > queryPartial (OK=55 KO=0 ) 2024-04-24 07:43:56,762 INFO > queryBySn (OK=82 KO=0 ) 2024-04-24 07:43:56,762 INFO > queryFull (OK=70 KO=0 ) 2024-04-24 07:43:56,762 INFO 2024-04-24 07:43:56,762 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,762 INFO [- ] 0% 2024-04-24 07:43:56,762 INFO waiting: 72292 / active: 3 / done: 414 2024-04-24 07:43:56,762 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,762 INFO [--------------------------------------------------------------------------] 0% 2024-04-24 07:43:56,762 INFO waiting: 0 / active: 1 / done: 0 2024-04-24 07:43:56,762 INFO ================================================================================ 2024-04-24 07:43:56,762 INFO 2024-04-24 07:43:56,762 INFO 2024-04-24 07:43:56,762 INFO [...] 2024-04-24 07:43:56,764 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,764 INFO > ReadManagedUser (OK=14401 KO=0 ) 2024-04-24 07:43:56,764 INFO > queryStartsWith (OK=14494 KO=0 ) 2024-04-24 07:43:56,764 INFO > queryPartial (OK=14493 KO=0 ) 2024-04-24 07:43:56,764 INFO > queryBySn (OK=14369 KO=0 ) 2024-04-24 07:43:56,764 INFO > queryFull (OK=14487 KO=0 ) 2024-04-24 07:43:56,765 INFO 2024-04-24 07:43:56,765 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,765 INFO [#########################################################################-] 99% 2024-04-24 07:43:56,765 INFO waiting: 389 / active: 7 / done: 72313 2024-04-24 07:43:56,765 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,765 INFO [##########################################################################]100% 2024-04-24 07:43:56,765 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:43:56,765 INFO ================================================================================ 2024-04-24 07:43:56,765 INFO 2024-04-24 07:43:56,765 INFO 2024-04-24 07:43:56,765 INFO ================================================================================ 2024-04-24 07:43:56,765 INFO 2024-04-24 08:41:57 1825s elapsed 2024-04-24 07:43:56,765 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,765 INFO > Global (OK=72443 KO=0 ) 2024-04-24 07:43:56,765 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:43:56,765 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:43:56,765 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,765 INFO > ReadManagedUser (OK=14448 KO=0 ) 2024-04-24 07:43:56,765 INFO > queryStartsWith (OK=14535 KO=0 ) 2024-04-24 07:43:56,765 INFO > queryPartial (OK=14526 KO=0 ) 2024-04-24 07:43:56,765 INFO > queryBySn (OK=14403 KO=0 ) 2024-04-24 07:43:56,765 INFO > queryFull (OK=14528 KO=0 ) 2024-04-24 07:43:56,765 INFO 2024-04-24 07:43:56,765 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,765 INFO [#########################################################################-] 99% 2024-04-24 07:43:56,765 INFO waiting: 197 / active: 3 / done: 72509 2024-04-24 07:43:56,765 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,765 INFO [##########################################################################]100% 2024-04-24 07:43:56,765 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:43:56,765 INFO ================================================================================ 2024-04-24 07:43:56,765 INFO 2024-04-24 07:43:56,765 INFO 2024-04-24 07:43:56,765 INFO ================================================================================ 2024-04-24 07:43:56,765 INFO 2024-04-24 08:42:02 1830s elapsed 2024-04-24 07:43:56,765 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,765 INFO > Global (OK=72637 KO=0 ) 2024-04-24 07:43:56,765 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:43:56,765 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:43:56,765 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,765 INFO > ReadManagedUser (OK=14483 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryStartsWith (OK=14576 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryPartial (OK=14574 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryBySn (OK=14435 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryFull (OK=14566 KO=0 ) 2024-04-24 07:43:56,766 INFO 2024-04-24 07:43:56,766 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,766 INFO [#########################################################################-] 99% 2024-04-24 07:43:56,766 INFO waiting: 0 / active: 6 / done: 72703 2024-04-24 07:43:56,766 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,766 INFO [##########################################################################]100% 2024-04-24 07:43:56,766 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:43:56,766 INFO ================================================================================ 2024-04-24 07:43:56,766 INFO 2024-04-24 07:43:56,766 INFO 2024-04-24 07:43:56,766 INFO ================================================================================ 2024-04-24 07:43:56,766 INFO 2024-04-24 08:42:02 1830s elapsed 2024-04-24 07:43:56,766 INFO ---- Requests ------------------------------------------------------------------ 2024-04-24 07:43:56,766 INFO > Global (OK=72643 KO=0 ) 2024-04-24 07:43:56,766 INFO > AdminRestLogin (OK=1 KO=0 ) 2024-04-24 07:43:56,766 INFO > AdminAuthorize (OK=1 KO=0 ) 2024-04-24 07:43:56,766 INFO > Admin AccessToken stage (OK=1 KO=0 ) 2024-04-24 07:43:56,766 INFO > ReadManagedUser (OK=14483 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryStartsWith (OK=14580 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryPartial (OK=14576 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryBySn (OK=14435 KO=0 ) 2024-04-24 07:43:56,766 INFO > queryFull (OK=14566 KO=0 ) 2024-04-24 07:43:56,766 INFO 2024-04-24 07:43:56,766 INFO ---- idm.MixedQuery ------------------------------------------------------------ 2024-04-24 07:43:56,766 INFO [##########################################################################]100% 2024-04-24 07:43:56,766 INFO waiting: 0 / active: 0 / done: 72709 2024-04-24 07:43:56,766 INFO ---- getToken ------------------------------------------------------------------ 2024-04-24 07:43:56,766 INFO [##########################################################################]100% 2024-04-24 07:43:56,766 INFO waiting: 0 / active: 0 / done: 1 2024-04-24 07:43:56,766 INFO ================================================================================ 2024-04-24 07:43:56,766 INFO 2024-04-24 07:43:56,766 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-24 07:43:56,766 INFO 2024-04-24 07:43:56,766 INFO ---------------------- 2024-04-24 07:43:56,766 INFO [24/Apr/2024 08:42:02] Create tar file mixedquery-40.tar.gz 2024-04-24 07:43:56,767 INFO ---------------------- 2024-04-24 07:43:56,767 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-24 07:43:56,767 INFO RESULT_FOLDER=mixedquery-40 2024-04-24 07:43:56,767 INFO SIMULATION_PATH=mixedquery-40/loadtest-20240424071131503 2024-04-24 07:43:56,767 INFO PWD=/results/gatling/mixedquery-40/loadtest-20240424071131503 2024-04-24 07:43:56,767 INFO [cmd] tar -czvf /results/gatling//mixedquery-40.tar.gz --exclude=simulation.log * 2024-04-24 07:43:56,767 INFO /results/gatling 2024-04-24 07:43:56,767 INFO 2024-04-24 07:43:56,767 INFO ---------------------- 2024-04-24 07:43:56,767 INFO [24/Apr/2024 08:42:02] Update /results/gatling//latest 2024-04-24 07:43:56,767 INFO ---------------------- 2024-04-24 07:43:56,767 INFO 2024-04-24 07:43:56,767 INFO ---------------------- 2024-04-24 07:43:56,767 INFO [24/Apr/2024 08:42:02] Update /results/gatling//latest.tar.gz 2024-04-24 07:43:56,767 INFO ---------------------- 2024-04-24 07:43:56,767 INFO ________________________________________________________________________________ [2024-04-24 07:43:56] mixedquery-40 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-24 07:43:56,767 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/simple_managed_users/07_Scenario_scenario/04_mixedquery-40/stderr.txt is small (0 lines), printing all lines: 2024-04-24 07:43:56,767 DEBUG ----- output ----- 2024-04-24 07:43:56,767 DEBUG 2024-04-24 07:43:56,767 INFO ________________________________________________________________________________ [2024-04-24 07:43:56] mixedquery-40 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 07:43:56] mixedquery-40 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-24 07:43:56] mixedquery-40 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped