--Task-- name: preload-with-gatling 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: ['prepare-workload'] wait_for: ['Pre-check Num Users'] preceding_task: None options: {'check-before-preload': True, 'concurrency': 35, 'java-opts': '-Dcsvfile=/results/idm/preload.csv -Doutput=/results/idm/user_feeder.csv -Drealm=alpha -Duser_password=Pa_ssw0rd', 'simulation': 'idm.simple_managed_users.ManagedUsersPreload'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-24 18:11:20] preload-with-gatling pre : N/A ________________________________________________________________________________ [2024-05-24 18:11:20] - INFO: Timeout for this Task has changed to 2h (7200 seconds) [2024-05-24 18:11:20] - INFO: timeout was set to GatlingTask default, because it was unset Pod log file : /results/orders/preload-with-gatling/stdout.txt Pod log : /results/orders/preload-with-gatling 2024-05-24 18:11:20,395 INFO 2024-05-24 18:11:20,395 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-05-24 18:11:20,395 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:11:20,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:11:20,700 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:20,700 DEBUG --- stdout --- 2024-05-24 18:11:20,700 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:11:20,700 DEBUG --- stderr --- 2024-05-24 18:11:20,700 DEBUG ________________________________________________________________________________ [2024-05-24 18:11:20] preload-with-gatling print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-24 18:11:20,701 INFO 2024-05-24 18:11:20,701 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-05-24 18:11:20,701 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:11:21,008 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:11:21,009 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:21,009 DEBUG --- stdout --- 2024-05-24 18:11:21,009 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:11:21,009 DEBUG --- stderr --- 2024-05-24 18:11:21,009 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation idm.simple_managed_users.ManagedUsersPreload --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description "idm.simple_managed_users.ManagedUsersPreload@ema-perf-3580" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-24 18:11:21] preload-with-gatling create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-24 18:11:21,009 INFO 2024-05-24 18:11:21,009 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-05-24 18:11:21,009 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:11:21,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:11:21,314 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:21,314 DEBUG --- stdout --- 2024-05-24 18:11:21,314 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:11:21,314 DEBUG --- stderr --- 2024-05-24 18:11:21,315 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/order.json { "name": "preload-with-gatling", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation idm.simple_managed_users.ManagedUsersPreload --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description \"idm.simple_managed_users.ManagedUsersPreload@ema-perf-3580\" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/preload-with-gatling", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-3580.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dcheck_before_preload=True -Dconcurrency=35 -Dcsvfile=/results/idm/preload.csv -Didc=true -Didm_host=openam-ema-perf-3580.forgeblocks.com -Dlogin_cookie=9537f3c44bdb077 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Doutput=/results/idm/user_feeder.csv -Drealm=alpha -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-24 18:11:21] preload-with-gatling 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-05-24 18:11:21,316 INFO 2024-05-24 18:11:21,316 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-24 18:11:21,316 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 18:11:21,558 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 18:11:21,558 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:21,558 DEBUG --- stdout --- 2024-05-24 18:11:21,558 DEBUG WAITING 2024-05-24 18:11:21,558 DEBUG --- stderr --- 2024-05-24 18:11:21,558 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-05-24 18:11:21] preload-with-gatling start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-24 18:11:21,559 INFO 2024-05-24 18:11:21,559 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-05-24 18:11:21,559 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:11:21,881 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:11:21,881 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:21,881 DEBUG --- stdout --- 2024-05-24 18:11:21,881 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:11:21,881 DEBUG --- stderr --- 2024-05-24 18:11:21,881 DEBUG 2024-05-24 18:11:21,882 INFO 2024-05-24 18:11:21,882 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-24 18:11:21,882 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 18:11:23,295 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:23,295 DEBUG --- stdout --- 2024-05-24 18:11:23,295 DEBUG 2024-05-24 18:11:23,295 DEBUG --- stderr --- 2024-05-24 18:11:23,295 DEBUG Order has been successfully forwarded to overseer [2024-05-24 18:11:23] - INFO: Check if Gatling simulation started successfully 2024-05-24 18:11:23,296 INFO 2024-05-24 18:11:23,296 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-05-24 18:11:23,296 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:11:23,611 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:11:23,611 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:23,611 DEBUG --- stdout --- 2024-05-24 18:11:23,611 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:11:23,611 DEBUG --- stderr --- 2024-05-24 18:11:23,611 DEBUG 2024-05-24 18:11:23,611 INFO 2024-05-24 18:11:23,611 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- head -n 25 /results/orders/preload-with-gatling/stdout.txt | grep "started..." 2024-05-24 18:11:23,611 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 18:11:24,385 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-24 18:11:30,125 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-05-24 18:11:35,870 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-05-24 18:11:35,870 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:11:35,870 DEBUG --- stdout --- 2024-05-24 18:11:35,870 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 19:11:25.370 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 19:11:31.092 [WARN ] i.g.c.ZincCompiler$ - one warning found Gatling 3.11.3 is available! (you're using 3.9.5) think time multiplier is 0x Simulation idm.simple_managed_users.ManagedUsersPreload started... 2024-05-24 18:11:35,870 DEBUG --- stderr --- 2024-05-24 18:11:35,870 DEBUG ________________________________________________________________________________ [2024-05-24 18:11:35] preload-with-gatling 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 2h Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry PASS : Order stopped by itself after 32m 05s and before timeout (2h) ________________________________________________________________________________ [2024-05-24 18:43:41] preload-with-gatling stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-24 18:43:41,225 INFO 2024-05-24 18:43:41,226 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-05-24 18:43:41,226 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:41,557 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:41,557 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:41,557 DEBUG --- stdout --- 2024-05-24 18:43:41,557 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:41,558 DEBUG --- stderr --- 2024-05-24 18:43:41,558 DEBUG 2024-05-24 18:43:41,558 INFO 2024-05-24 18:43:41,558 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- rm -f /results/orders/order.json 2024-05-24 18:43:41,558 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 18:43:42,352 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:42,353 DEBUG --- stdout --- 2024-05-24 18:43:42,353 DEBUG 2024-05-24 18:43:42,353 DEBUG --- stderr --- 2024-05-24 18:43:42,353 DEBUG Order ran during 32m 19s ________________________________________________________________________________ [2024-05-24 18:43:42] preload-with-gatling download_logs : Download individual order files ________________________________________________________________________________ [2024-05-24 18:43:42] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/order.json 2024-05-24 18:43:42,353 INFO 2024-05-24 18:43:42,353 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 18:43:42,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:42,665 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:42,665 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:42,665 DEBUG --- stdout --- 2024-05-24 18:43:42,665 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:42,665 DEBUG --- stderr --- 2024-05-24 18:43:42,665 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 18:43:42,666 INFO 2024-05-24 18:43:42,666 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-05-24 18:43:42,666 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:42,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:42,970 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:42,970 DEBUG --- stdout --- 2024-05-24 18:43:42,970 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:42,971 DEBUG --- stderr --- 2024-05-24 18:43:42,971 DEBUG 2024-05-24 18:43:42,971 INFO 2024-05-24 18:43:42,971 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/rc.txt" ]; then echo "/results/orders/preload-with-gatling/rc.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/rc.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/rc.txt file found" 2024-05-24 18:43:42,971 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 18:43:43,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 18:43:43,780 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:43,780 DEBUG --- stdout --- 2024-05-24 18:43:43,780 DEBUG /results/orders/preload-with-gatling/rc.txt file found 2024-05-24 18:43:43,780 DEBUG --- stderr --- 2024-05-24 18:43:43,780 DEBUG 2024-05-24 18:43:43,781 INFO 2024-05-24 18:43:43,781 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-05-24 18:43:43,781 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:44,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:44,097 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:44,097 DEBUG --- stdout --- 2024-05-24 18:43:44,097 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:44,097 DEBUG --- stderr --- 2024-05-24 18:43:44,097 DEBUG 2024-05-24 18:43:44,097 INFO 2024-05-24 18:43:44,097 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/preload-with-gatling/rc.txt 2024-05-24 18:43:44,909 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 18:43:44,909 DEBUG --- stdout --- 2024-05-24 18:43:44,909 DEBUG 4 /results/orders/preload-with-gatling/rc.txt 2024-05-24 18:43:44,910 DEBUG --- stderr --- 2024-05-24 18:43:44,910 DEBUG 2024-05-24 18:43:44,910 INFO - Download file (size 4k) 2024-05-24 18:43:44,910 INFO 2024-05-24 18:43:44,910 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-05-24 18:43:44,910 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:45,242 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:45,242 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:45,242 DEBUG --- stdout --- 2024-05-24 18:43:45,242 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:45,242 DEBUG --- stderr --- 2024-05-24 18:43:45,242 DEBUG source : /results/orders/preload-with-gatling/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/rc.txt 2024-05-24 18:43:45,242 INFO 2024-05-24 18:43:45,242 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-05-24 18:43:45,242 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:45,554 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:45,554 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:45,554 DEBUG --- stdout --- 2024-05-24 18:43:45,554 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:45,554 DEBUG --- stderr --- 2024-05-24 18:43:45,554 DEBUG 2024-05-24 18:43:45,555 INFO 2024-05-24 18:43:45,555 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/preload-with-gatling/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/rc.txt 2024-05-24 18:43:45,555 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 18:43:46,285 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:46,286 DEBUG --- stdout --- 2024-05-24 18:43:46,286 DEBUG tar: Removing leading `/' from member names 2024-05-24 18:43:46,286 DEBUG --- stderr --- 2024-05-24 18:43:46,286 DEBUG 2024-05-24 18:43:46,286 INFO 2024-05-24 18:43:46,286 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-05-24 18:43:46,286 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:46,595 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:46,596 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:46,596 DEBUG --- stdout --- 2024-05-24 18:43:46,596 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:46,596 DEBUG --- stderr --- 2024-05-24 18:43:46,596 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 18:43:46,597 INFO 2024-05-24 18:43:46,597 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-05-24 18:43:46,597 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:46,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:46,900 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:46,900 DEBUG --- stdout --- 2024-05-24 18:43:46,900 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:46,900 DEBUG --- stderr --- 2024-05-24 18:43:46,900 DEBUG 2024-05-24 18:43:46,901 INFO 2024-05-24 18:43:46,901 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/stderr.txt" ]; then echo "/results/orders/preload-with-gatling/stderr.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/stderr.txt file found" 2024-05-24 18:43:46,901 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 18:43:47,707 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 18:43:47,707 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:47,707 DEBUG --- stdout --- 2024-05-24 18:43:47,707 DEBUG /results/orders/preload-with-gatling/stderr.txt file found 2024-05-24 18:43:47,707 DEBUG --- stderr --- 2024-05-24 18:43:47,707 DEBUG 2024-05-24 18:43:47,707 INFO 2024-05-24 18:43:47,708 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 18:43:47,708 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:48,017 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:48,017 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:48,017 DEBUG --- stdout --- 2024-05-24 18:43:48,017 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:48,017 DEBUG --- stderr --- 2024-05-24 18:43:48,017 DEBUG 2024-05-24 18:43:48,017 INFO 2024-05-24 18:43:48,018 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/preload-with-gatling/stderr.txt 2024-05-24 18:43:48,869 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 18:43:48,871 DEBUG --- stdout --- 2024-05-24 18:43:48,871 DEBUG 0 /results/orders/preload-with-gatling/stderr.txt 2024-05-24 18:43:48,871 DEBUG --- stderr --- 2024-05-24 18:43:48,871 DEBUG 2024-05-24 18:43:48,871 INFO - Download file (size 0k) 2024-05-24 18:43:48,872 INFO 2024-05-24 18:43:48,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-05-24 18:43:48,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:49,179 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:49,179 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:49,179 DEBUG --- stdout --- 2024-05-24 18:43:49,180 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:49,180 DEBUG --- stderr --- 2024-05-24 18:43:49,180 DEBUG source : /results/orders/preload-with-gatling/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/stderr.txt 2024-05-24 18:43:49,180 INFO 2024-05-24 18:43:49,180 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-05-24 18:43:49,180 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:49,488 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:49,488 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:49,488 DEBUG --- stdout --- 2024-05-24 18:43:49,489 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:49,489 DEBUG --- stderr --- 2024-05-24 18:43:49,489 DEBUG 2024-05-24 18:43:49,489 INFO 2024-05-24 18:43:49,489 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/preload-with-gatling/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/stderr.txt 2024-05-24 18:43:49,489 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 18:43:50,217 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:50,217 DEBUG --- stdout --- 2024-05-24 18:43:50,217 DEBUG tar: Removing leading `/' from member names 2024-05-24 18:43:50,217 DEBUG --- stderr --- 2024-05-24 18:43:50,217 DEBUG 2024-05-24 18:43:50,218 INFO 2024-05-24 18:43:50,218 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-05-24 18:43:50,218 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:50,525 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:50,525 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:50,525 DEBUG --- stdout --- 2024-05-24 18:43:50,525 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:50,525 DEBUG --- stderr --- 2024-05-24 18:43:50,525 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 18:43:50,526 INFO 2024-05-24 18:43:50,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-05-24 18:43:50,526 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:50,838 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:50,838 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:50,838 DEBUG --- stdout --- 2024-05-24 18:43:50,838 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:50,838 DEBUG --- stderr --- 2024-05-24 18:43:50,838 DEBUG 2024-05-24 18:43:50,839 INFO 2024-05-24 18:43:50,839 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/stdout.txt" ]; then echo "/results/orders/preload-with-gatling/stdout.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/stdout.txt file found" 2024-05-24 18:43:50,839 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 18:43:51,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 18:43:51,650 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:51,650 DEBUG --- stdout --- 2024-05-24 18:43:51,650 DEBUG /results/orders/preload-with-gatling/stdout.txt file found 2024-05-24 18:43:51,650 DEBUG --- stderr --- 2024-05-24 18:43:51,650 DEBUG 2024-05-24 18:43:51,651 INFO 2024-05-24 18:43:51,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-05-24 18:43:51,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:51,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:51,961 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:51,961 DEBUG --- stdout --- 2024-05-24 18:43:51,961 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:51,961 DEBUG --- stderr --- 2024-05-24 18:43:51,961 DEBUG 2024-05-24 18:43:51,961 INFO 2024-05-24 18:43:51,962 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/preload-with-gatling/stdout.txt 2024-05-24 18:43:52,767 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 18:43:52,767 DEBUG --- stdout --- 2024-05-24 18:43:52,767 DEBUG 408 /results/orders/preload-with-gatling/stdout.txt 2024-05-24 18:43:52,767 DEBUG --- stderr --- 2024-05-24 18:43:52,767 DEBUG 2024-05-24 18:43:52,767 INFO - Download file (size 408k) 2024-05-24 18:43:52,768 INFO 2024-05-24 18:43:52,768 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-05-24 18:43:52,768 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:53,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:53,093 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:53,093 DEBUG --- stdout --- 2024-05-24 18:43:53,093 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:53,093 DEBUG --- stderr --- 2024-05-24 18:43:53,093 DEBUG source : /results/orders/preload-with-gatling/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/stdout.txt 2024-05-24 18:43:53,093 INFO 2024-05-24 18:43:53,094 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-05-24 18:43:53,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:53,393 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:53,393 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:53,393 DEBUG --- stdout --- 2024-05-24 18:43:53,393 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:53,394 DEBUG --- stderr --- 2024-05-24 18:43:53,394 DEBUG 2024-05-24 18:43:53,394 INFO 2024-05-24 18:43:53,394 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/preload-with-gatling/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/stdout.txt 2024-05-24 18:43:53,394 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 18:43:54,422 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:54,422 DEBUG --- stdout --- 2024-05-24 18:43:54,422 DEBUG tar: Removing leading `/' from member names 2024-05-24 18:43:54,422 DEBUG --- stderr --- 2024-05-24 18:43:54,422 DEBUG ________________________________________________________________________________ [2024-05-24 18:43:54] preload-with-gatling show_cmd : Display order command ________________________________________________________________________________ 2024-05-24 18:43:54,423 INFO 2024-05-24 18:43:54,423 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-05-24 18:43:54,423 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:54,736 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:54,736 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:54,736 DEBUG --- stdout --- 2024-05-24 18:43:54,736 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:54,736 DEBUG --- stderr --- 2024-05-24 18:43:54,736 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 18:43:54,737 INFO 2024-05-24 18:43:54,737 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-05-24 18:43:54,737 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:55,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:55,097 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:55,097 DEBUG --- stdout --- 2024-05-24 18:43:55,097 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:55,097 DEBUG --- stderr --- 2024-05-24 18:43:55,097 DEBUG 2024-05-24 18:43:55,097 INFO 2024-05-24 18:43:55,097 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/cmd.txt" ]; then echo "/results/orders/preload-with-gatling/cmd.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/cmd.txt file found" 2024-05-24 18:43:55,097 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 18:43:55,901 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 18:43:55,901 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:55,901 DEBUG --- stdout --- 2024-05-24 18:43:55,901 DEBUG /results/orders/preload-with-gatling/cmd.txt file found 2024-05-24 18:43:55,901 DEBUG --- stderr --- 2024-05-24 18:43:55,901 DEBUG 2024-05-24 18:43:55,902 INFO 2024-05-24 18:43:55,902 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-05-24 18:43:55,902 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:56,213 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:56,213 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:56,213 DEBUG --- stdout --- 2024-05-24 18:43:56,213 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:56,213 DEBUG --- stderr --- 2024-05-24 18:43:56,213 DEBUG 2024-05-24 18:43:56,214 INFO 2024-05-24 18:43:56,214 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/preload-with-gatling/cmd.txt 2024-05-24 18:43:57,016 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 18:43:57,016 DEBUG --- stdout --- 2024-05-24 18:43:57,017 DEBUG 4 /results/orders/preload-with-gatling/cmd.txt 2024-05-24 18:43:57,017 DEBUG --- stderr --- 2024-05-24 18:43:57,017 DEBUG 2024-05-24 18:43:57,017 INFO - Download file (size 4k) 2024-05-24 18:43:57,017 INFO 2024-05-24 18:43:57,017 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-05-24 18:43:57,017 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:57,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:57,332 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:57,332 DEBUG --- stdout --- 2024-05-24 18:43:57,332 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:57,332 DEBUG --- stderr --- 2024-05-24 18:43:57,332 DEBUG source : /results/orders/preload-with-gatling/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/cmd.txt 2024-05-24 18:43:57,332 INFO 2024-05-24 18:43:57,332 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 18:43:57,332 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 18:43:57,648 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 18:43:57,648 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:57,648 DEBUG --- stdout --- 2024-05-24 18:43:57,648 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 18:43:57,648 DEBUG --- stderr --- 2024-05-24 18:43:57,648 DEBUG 2024-05-24 18:43:57,649 INFO 2024-05-24 18:43:57,649 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/preload-with-gatling/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/cmd.txt 2024-05-24 18:43:57,649 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 18:43:58,377 INFO [loop_until]: OK (rc = 0) 2024-05-24 18:43:58,377 DEBUG --- stdout --- 2024-05-24 18:43:58,377 DEBUG tar: Removing leading `/' from member names 2024-05-24 18:43:58,377 DEBUG --- stderr --- 2024-05-24 18:43:58,377 DEBUG 2024-05-24 18:43:58,377 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/cmd.txt is small (1 lines), printing all lines: 2024-05-24 18:43:58,377 DEBUG ----- output ----- 2024-05-24 18:43:58,378 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation idm.simple_managed_users.ManagedUsersPreload --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description "idm.simple_managed_users.ManagedUsersPreload@ema-perf-3580" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-24 18:43:58,378 INFO ________________________________________________________________________________ [2024-05-24 18:43:58] preload-with-gatling show_rc : Display rc order ________________________________________________________________________________ 2024-05-24 18:43:58,378 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/rc.txt is small (1 lines), printing all lines: 2024-05-24 18:43:58,378 DEBUG ----- output ----- 2024-05-24 18:43:58,378 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-24 18:43:58,378 INFO ________________________________________________________________________________ [2024-05-24 18:43:58] preload-with-gatling show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-24 18:43:58,380 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/stdout.txt is 6315 lines): 2024-05-24 18:43:58,380 DEBUG ----- output ----- 2024-05-24 18:43:58,380 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-24 18:43:58,380 INFO 19:11:25.370 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-05-24 18:43:58,381 INFO 19:11:31.092 [WARN ] i.g.c.ZincCompiler$ - one warning found 2024-05-24 18:43:58,381 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-24 18:43:58,381 INFO think time multiplier is 0x 2024-05-24 18:43:58,381 INFO Simulation idm.simple_managed_users.ManagedUsersPreload started... 2024-05-24 18:43:58,381 INFO 2024-05-24 18:43:58,381 INFO ================================================================================ 2024-05-24 18:43:58,381 INFO 2024-05-24 19:11:38 5s elapsed 2024-05-24 18:43:58,381 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,381 INFO > Global (OK=2277 KO=0 ) 2024-05-24 18:43:58,381 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,381 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,381 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,381 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,381 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,381 INFO > Create Managed User (OK=2102 KO=0 ) 2024-05-24 18:43:58,382 INFO 2024-05-24 18:43:58,382 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,382 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,382 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,382 INFO ================================================================================ 2024-05-24 18:43:58,382 INFO 2024-05-24 18:43:58,382 INFO 2024-05-24 18:43:58,382 INFO ================================================================================ 2024-05-24 18:43:58,382 INFO 2024-05-24 19:11:43 10s elapsed 2024-05-24 18:43:58,382 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,382 INFO > Global (OK=4649 KO=0 ) 2024-05-24 18:43:58,382 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,382 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,382 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,382 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,382 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,383 INFO > Create Managed User (OK=4474 KO=0 ) 2024-05-24 18:43:58,383 INFO 2024-05-24 18:43:58,383 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,383 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,383 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,383 INFO ================================================================================ 2024-05-24 18:43:58,383 INFO 2024-05-24 18:43:58,383 INFO 2024-05-24 18:43:58,383 INFO ================================================================================ 2024-05-24 18:43:58,383 INFO 2024-05-24 19:11:48 15s elapsed 2024-05-24 18:43:58,383 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,383 INFO > Global (OK=7488 KO=0 ) 2024-05-24 18:43:58,383 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,383 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,383 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,383 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,383 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,383 INFO > Create Managed User (OK=7313 KO=0 ) 2024-05-24 18:43:58,383 INFO 2024-05-24 18:43:58,383 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,383 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,383 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,383 INFO ================================================================================ 2024-05-24 18:43:58,383 INFO 2024-05-24 18:43:58,383 INFO 2024-05-24 18:43:58,383 INFO ================================================================================ 2024-05-24 18:43:58,383 INFO 2024-05-24 19:11:53 20s elapsed 2024-05-24 18:43:58,383 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,384 INFO > Global (OK=10291 KO=0 ) 2024-05-24 18:43:58,384 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > Create Managed User (OK=10116 KO=0 ) 2024-05-24 18:43:58,384 INFO 2024-05-24 18:43:58,384 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,384 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,384 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,384 INFO ================================================================================ 2024-05-24 18:43:58,384 INFO 2024-05-24 18:43:58,384 INFO 2024-05-24 18:43:58,384 INFO ================================================================================ 2024-05-24 18:43:58,384 INFO 2024-05-24 19:11:58 25s elapsed 2024-05-24 18:43:58,384 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,384 INFO > Global (OK=13070 KO=0 ) 2024-05-24 18:43:58,384 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,384 INFO > Create Managed User (OK=12895 KO=0 ) 2024-05-24 18:43:58,384 INFO 2024-05-24 18:43:58,384 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,385 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,385 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,385 INFO ================================================================================ 2024-05-24 18:43:58,385 INFO 2024-05-24 18:43:58,385 INFO 2024-05-24 18:43:58,385 INFO ================================================================================ 2024-05-24 18:43:58,385 INFO 2024-05-24 19:12:03 30s elapsed 2024-05-24 18:43:58,385 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,385 INFO > Global (OK=15831 KO=0 ) 2024-05-24 18:43:58,385 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,385 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,385 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,385 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,385 INFO [...] 2024-05-24 18:43:58,394 INFO > Global (OK=991694 KO=0 ) 2024-05-24 18:43:58,394 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,394 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,394 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,394 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,394 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,394 INFO > Create Managed User (OK=991519 KO=0 ) 2024-05-24 18:43:58,394 INFO 2024-05-24 18:43:58,394 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,394 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,395 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,395 INFO ================================================================================ 2024-05-24 18:43:58,395 INFO 2024-05-24 18:43:58,395 INFO 2024-05-24 18:43:58,395 INFO ================================================================================ 2024-05-24 18:43:58,395 INFO 2024-05-24 19:42:08 1835s elapsed 2024-05-24 18:43:58,395 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,395 INFO > Global (OK=994392 KO=0 ) 2024-05-24 18:43:58,395 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > Create Managed User (OK=994217 KO=0 ) 2024-05-24 18:43:58,395 INFO 2024-05-24 18:43:58,395 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,395 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,395 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,395 INFO ================================================================================ 2024-05-24 18:43:58,395 INFO 2024-05-24 18:43:58,395 INFO 2024-05-24 18:43:58,395 INFO ================================================================================ 2024-05-24 18:43:58,395 INFO 2024-05-24 19:42:13 1840s elapsed 2024-05-24 18:43:58,395 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,395 INFO > Global (OK=997066 KO=0 ) 2024-05-24 18:43:58,395 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,395 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > Create Managed User (OK=996891 KO=0 ) 2024-05-24 18:43:58,396 INFO 2024-05-24 18:43:58,396 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,396 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,396 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,396 INFO ================================================================================ 2024-05-24 18:43:58,396 INFO 2024-05-24 18:43:58,396 INFO 2024-05-24 18:43:58,396 INFO ================================================================================ 2024-05-24 18:43:58,396 INFO 2024-05-24 19:42:18 1845s elapsed 2024-05-24 18:43:58,396 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,396 INFO > Global (OK=999578 KO=0 ) 2024-05-24 18:43:58,396 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > Create Managed User (OK=999403 KO=0 ) 2024-05-24 18:43:58,396 INFO 2024-05-24 18:43:58,396 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,396 INFO [--------------------------------------------------------------------------] 0% 2024-05-24 18:43:58,396 INFO waiting: 0 / active: 35 / done: 0 2024-05-24 18:43:58,396 INFO ================================================================================ 2024-05-24 18:43:58,396 INFO 2024-05-24 18:43:58,396 INFO 2024-05-24 18:43:58,396 INFO ================================================================================ 2024-05-24 18:43:58,396 INFO 2024-05-24 19:42:19 1846s elapsed 2024-05-24 18:43:58,396 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 18:43:58,396 INFO > Global (OK=1000175 KO=0 ) 2024-05-24 18:43:58,396 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > Skip 2FA (OK=35 KO=0 ) 2024-05-24 18:43:58,396 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,397 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-05-24 18:43:58,397 INFO > Create Managed User (OK=1000000 KO=0 ) 2024-05-24 18:43:58,397 INFO 2024-05-24 18:43:58,397 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-05-24 18:43:58,397 INFO [##########################################################################]100% 2024-05-24 18:43:58,397 INFO waiting: 0 / active: 0 / done: 35 2024-05-24 18:43:58,397 INFO ================================================================================ 2024-05-24 18:43:58,397 INFO 2024-05-24 18:43:58,397 INFO Simulation idm.simple_managed_users.ManagedUsersPreload completed in 1846 seconds 2024-05-24 18:43:58,397 INFO 2024-05-24 18:43:58,397 INFO ---------------------- 2024-05-24 18:43:58,397 INFO [24/May/2024 19:42:19] Create tar file preload-with-gatling.tar.gz 2024-05-24 18:43:58,397 INFO ---------------------- 2024-05-24 18:43:58,397 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-24 18:43:58,397 INFO RESULT_FOLDER=preload-with-gatling 2024-05-24 18:43:58,397 INFO SIMULATION_PATH=preload-with-gatling/manageduserspreload-20240524181132577 2024-05-24 18:43:58,397 INFO PWD=/results/gatling/preload-with-gatling/manageduserspreload-20240524181132577 2024-05-24 18:43:58,397 INFO [cmd] tar -czvf /results/gatling//preload-with-gatling.tar.gz --exclude=simulation.log * 2024-05-24 18:43:58,397 INFO /results/gatling 2024-05-24 18:43:58,397 INFO 2024-05-24 18:43:58,397 INFO ---------------------- 2024-05-24 18:43:58,397 INFO [24/May/2024 19:42:19] Update /results/gatling//latest 2024-05-24 18:43:58,397 INFO ---------------------- 2024-05-24 18:43:58,397 INFO 2024-05-24 18:43:58,398 INFO ---------------------- 2024-05-24 18:43:58,398 INFO [24/May/2024 19:42:19] Update /results/gatling//latest.tar.gz 2024-05-24 18:43:58,398 INFO ---------------------- 2024-05-24 18:43:58,398 INFO ________________________________________________________________________________ [2024-05-24 18:43:58] preload-with-gatling show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-24 18:43:58,398 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/05_Data_Init/04_preload-with-gatling/stderr.txt is small (0 lines), printing all lines: 2024-05-24 18:43:58,398 DEBUG ----- output ----- 2024-05-24 18:43:58,398 DEBUG 2024-05-24 18:43:58,398 INFO ________________________________________________________________________________ [2024-05-24 18:43:58] preload-with-gatling 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-05-24 18:43:58] preload-with-gatling print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-24 18:43:58] preload-with-gatling post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped