--Task-- name: login-tree-700 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am 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: ['login-tree-600'] preceding_task: login-tree-600 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 700} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 11:54:40] login-tree-700 pre : N/A ________________________________________________________________________________ [2024-05-25 11:54:40] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 11:54:40] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-700/stdout.txt Pod log : /results/orders/login-tree-700 2024-05-25 11:54:40,584 INFO 2024-05-25 11:54:40,584 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-25 11:54:40,584 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:40,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:40,900 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:40,900 DEBUG --- stdout --- 2024-05-25 11:54:40,900 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:40,900 DEBUG --- stderr --- 2024-05-25 11:54:40,900 DEBUG ________________________________________________________________________________ [2024-05-25 11:54:40] login-tree-700 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 11:54:40,901 INFO 2024-05-25 11:54:40,901 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-25 11:54:40,901 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:41,212 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:41,212 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:41,212 DEBUG --- stdout --- 2024-05-25 11:54:41,212 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:41,212 DEBUG --- stderr --- 2024-05-25 11:54:41,212 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 "idc.login@ema-perf-3580" --results-folder /results/gatling/login-tree-700 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 11:54:41] login-tree-700 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 11:54:41,213 INFO 2024-05-25 11:54:41,213 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-25 11:54:41,213 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:41,524 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:41,524 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:41,524 DEBUG --- stdout --- 2024-05-25 11:54:41,524 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:41,524 DEBUG --- stderr --- 2024-05-25 11:54:41,524 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/order.json { "name": "login-tree-700", "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 \"idc.login@ema-perf-3580\" --results-folder /results/gatling/login-tree-700 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-700", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-3580.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=9537f3c44bdb077 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=700 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 11:54:41] login-tree-700 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-25 11:54:41,527 INFO 2024-05-25 11:54:41,527 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 11:54:41,527 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 11:54:41,782 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 11:54:41,782 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:41,782 DEBUG --- stdout --- 2024-05-25 11:54:41,782 DEBUG WAITING 2024-05-25 11:54:41,782 DEBUG --- stderr --- 2024-05-25 11:54:41,782 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-05-25 11:54:41] login-tree-700 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 11:54:41,783 INFO 2024-05-25 11:54:41,783 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-25 11:54:41,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:42,095 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:42,095 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:42,095 DEBUG --- stdout --- 2024-05-25 11:54:42,096 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:42,096 DEBUG --- stderr --- 2024-05-25 11:54:42,096 DEBUG 2024-05-25 11:54:42,096 INFO 2024-05-25 11:54:42,096 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/07_Scenario_scenario/08_login-tree-700/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 11:54:42,096 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 11:54:43,451 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:43,451 DEBUG --- stdout --- 2024-05-25 11:54:43,452 DEBUG 2024-05-25 11:54:43,452 DEBUG --- stderr --- 2024-05-25 11:54:43,452 DEBUG Order has been successfully forwarded to overseer [2024-05-25 11:54:43] - INFO: Check if Gatling simulation started successfully 2024-05-25 11:54:43,452 INFO 2024-05-25 11:54:43,452 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-25 11:54:43,452 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:43,760 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:43,760 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:43,760 DEBUG --- stdout --- 2024-05-25 11:54:43,760 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:43,760 DEBUG --- stderr --- 2024-05-25 11:54:43,760 DEBUG 2024-05-25 11:54:43,761 INFO 2024-05-25 11:54:43,761 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- head -n 25 /results/orders/login-tree-700/stdout.txt | grep "started..." 2024-05-25 11:54:43,761 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 11:54:44,504 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 11:54:50,242 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 11:54:50,242 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:50,242 DEBUG --- stdout --- 2024-05-25 11:54:50,243 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 11:54:50,243 DEBUG --- stderr --- 2024-05-25 11:54:50,243 DEBUG ________________________________________________________________________________ [2024-05-25 11:54:50] login-tree-700 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 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-05-25 12:57:00] login-tree-700 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 12:57:00,727 INFO 2024-05-25 12:57:00,727 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-25 12:57:00,728 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:01,064 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:01,064 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:01,064 DEBUG --- stdout --- 2024-05-25 12:57:01,064 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:01,064 DEBUG --- stderr --- 2024-05-25 12:57:01,064 DEBUG 2024-05-25 12:57:01,065 INFO 2024-05-25 12:57:01,065 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-25 12:57:01,065 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 12:57:01,865 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:01,865 DEBUG --- stdout --- 2024-05-25 12:57:01,865 DEBUG 2024-05-25 12:57:01,865 DEBUG --- stderr --- 2024-05-25 12:57:01,865 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 12:57:01] login-tree-700 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 12:57:01] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/order.json 2024-05-25 12:57:01,867 INFO 2024-05-25 12:57:01,867 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-25 12:57:01,867 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:02,172 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:02,172 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:02,172 DEBUG --- stdout --- 2024-05-25 12:57:02,172 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:02,172 DEBUG --- stderr --- 2024-05-25 12:57:02,172 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 12:57:02,173 INFO 2024-05-25 12:57:02,173 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-25 12:57:02,173 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:02,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:02,477 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:02,477 DEBUG --- stdout --- 2024-05-25 12:57:02,477 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:02,477 DEBUG --- stderr --- 2024-05-25 12:57:02,477 DEBUG 2024-05-25 12:57:02,478 INFO 2024-05-25 12:57:02,478 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/login-tree-700/rc.txt" ]; then echo "/results/orders/login-tree-700/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-700/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/rc.txt file found" 2024-05-25 12:57:02,478 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 12:57:03,273 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 12:57:03,273 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:03,274 DEBUG --- stdout --- 2024-05-25 12:57:03,274 DEBUG /results/orders/login-tree-700/rc.txt file found 2024-05-25 12:57:03,274 DEBUG --- stderr --- 2024-05-25 12:57:03,274 DEBUG 2024-05-25 12:57:03,274 INFO 2024-05-25 12:57:03,274 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-25 12:57:03,274 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:03,592 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:03,592 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:03,592 DEBUG --- stdout --- 2024-05-25 12:57:03,592 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:03,592 DEBUG --- stderr --- 2024-05-25 12:57:03,592 DEBUG 2024-05-25 12:57:03,592 INFO 2024-05-25 12:57:03,593 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-700/rc.txt 2024-05-25 12:57:04,400 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 12:57:04,400 DEBUG --- stdout --- 2024-05-25 12:57:04,401 DEBUG 4 /results/orders/login-tree-700/rc.txt 2024-05-25 12:57:04,401 DEBUG --- stderr --- 2024-05-25 12:57:04,401 DEBUG 2024-05-25 12:57:04,401 INFO - Download file (size 4k) 2024-05-25 12:57:04,401 INFO 2024-05-25 12:57:04,401 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-25 12:57:04,401 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:04,704 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:04,704 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:04,704 DEBUG --- stdout --- 2024-05-25 12:57:04,704 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:04,704 DEBUG --- stderr --- 2024-05-25 12:57:04,704 DEBUG source : /results/orders/login-tree-700/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/rc.txt 2024-05-25 12:57:04,704 INFO 2024-05-25 12:57:04,704 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-25 12:57:04,704 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:05,002 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:05,002 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:05,003 DEBUG --- stdout --- 2024-05-25 12:57:05,003 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:05,003 DEBUG --- stderr --- 2024-05-25 12:57:05,003 DEBUG 2024-05-25 12:57:05,003 INFO 2024-05-25 12:57:05,003 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-700/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/rc.txt 2024-05-25 12:57:05,003 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 12:57:05,782 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:05,782 DEBUG --- stdout --- 2024-05-25 12:57:05,782 DEBUG tar: Removing leading `/' from member names 2024-05-25 12:57:05,783 DEBUG --- stderr --- 2024-05-25 12:57:05,783 DEBUG 2024-05-25 12:57:05,783 INFO 2024-05-25 12:57:05,783 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-25 12:57:05,783 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:06,093 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:06,093 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:06,093 DEBUG --- stdout --- 2024-05-25 12:57:06,093 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:06,093 DEBUG --- stderr --- 2024-05-25 12:57:06,093 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 12:57:06,094 INFO 2024-05-25 12:57:06,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-25 12:57:06,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:06,403 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:06,404 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:06,404 DEBUG --- stdout --- 2024-05-25 12:57:06,404 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:06,404 DEBUG --- stderr --- 2024-05-25 12:57:06,404 DEBUG 2024-05-25 12:57:06,405 INFO 2024-05-25 12:57:06,405 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/login-tree-700/stderr.txt" ]; then echo "/results/orders/login-tree-700/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-700/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/stderr.txt file found" 2024-05-25 12:57:06,405 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 12:57:07,245 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 12:57:07,245 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:07,245 DEBUG --- stdout --- 2024-05-25 12:57:07,245 DEBUG /results/orders/login-tree-700/stderr.txt file found 2024-05-25 12:57:07,245 DEBUG --- stderr --- 2024-05-25 12:57:07,245 DEBUG 2024-05-25 12:57:07,245 INFO 2024-05-25 12:57:07,245 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-25 12:57:07,245 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:07,555 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:07,555 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:07,555 DEBUG --- stdout --- 2024-05-25 12:57:07,555 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:07,555 DEBUG --- stderr --- 2024-05-25 12:57:07,555 DEBUG 2024-05-25 12:57:07,555 INFO 2024-05-25 12:57:07,555 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-700/stderr.txt 2024-05-25 12:57:08,389 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 12:57:08,389 DEBUG --- stdout --- 2024-05-25 12:57:08,389 DEBUG 0 /results/orders/login-tree-700/stderr.txt 2024-05-25 12:57:08,389 DEBUG --- stderr --- 2024-05-25 12:57:08,389 DEBUG 2024-05-25 12:57:08,389 INFO - Download file (size 0k) 2024-05-25 12:57:08,390 INFO 2024-05-25 12:57:08,390 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-25 12:57:08,390 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:08,702 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:08,702 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:08,702 DEBUG --- stdout --- 2024-05-25 12:57:08,702 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:08,702 DEBUG --- stderr --- 2024-05-25 12:57:08,702 DEBUG source : /results/orders/login-tree-700/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stderr.txt 2024-05-25 12:57:08,703 INFO 2024-05-25 12:57:08,703 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-25 12:57:08,703 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:09,004 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:09,005 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:09,005 DEBUG --- stdout --- 2024-05-25 12:57:09,005 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:09,005 DEBUG --- stderr --- 2024-05-25 12:57:09,005 DEBUG 2024-05-25 12:57:09,005 INFO 2024-05-25 12:57:09,005 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-700/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stderr.txt 2024-05-25 12:57:09,005 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 12:57:09,727 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:09,727 DEBUG --- stdout --- 2024-05-25 12:57:09,727 DEBUG tar: Removing leading `/' from member names 2024-05-25 12:57:09,727 DEBUG --- stderr --- 2024-05-25 12:57:09,727 DEBUG 2024-05-25 12:57:09,728 INFO 2024-05-25 12:57:09,728 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-25 12:57:09,728 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:10,097 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:10,098 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:10,098 DEBUG --- stdout --- 2024-05-25 12:57:10,098 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:10,098 DEBUG --- stderr --- 2024-05-25 12:57:10,098 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 12:57:10,098 INFO 2024-05-25 12:57:10,098 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-25 12:57:10,099 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:10,404 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:10,404 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:10,404 DEBUG --- stdout --- 2024-05-25 12:57:10,404 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:10,404 DEBUG --- stderr --- 2024-05-25 12:57:10,404 DEBUG 2024-05-25 12:57:10,405 INFO 2024-05-25 12:57:10,405 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/login-tree-700/stdout.txt" ]; then echo "/results/orders/login-tree-700/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-700/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/stdout.txt file found" 2024-05-25 12:57:10,405 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 12:57:11,205 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 12:57:11,206 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:11,207 DEBUG --- stdout --- 2024-05-25 12:57:11,207 DEBUG /results/orders/login-tree-700/stdout.txt file found 2024-05-25 12:57:11,207 DEBUG --- stderr --- 2024-05-25 12:57:11,207 DEBUG 2024-05-25 12:57:11,207 INFO 2024-05-25 12:57:11,207 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-25 12:57:11,207 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:11,507 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:11,507 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:11,507 DEBUG --- stdout --- 2024-05-25 12:57:11,507 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:11,507 DEBUG --- stderr --- 2024-05-25 12:57:11,507 DEBUG 2024-05-25 12:57:11,508 INFO 2024-05-25 12:57:11,508 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-700/stdout.txt 2024-05-25 12:57:12,314 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 12:57:12,314 DEBUG --- stdout --- 2024-05-25 12:57:12,315 DEBUG 676 /results/orders/login-tree-700/stdout.txt 2024-05-25 12:57:12,315 DEBUG --- stderr --- 2024-05-25 12:57:12,315 DEBUG 2024-05-25 12:57:12,315 INFO - Download file (size 676k) 2024-05-25 12:57:12,315 INFO 2024-05-25 12:57:12,315 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-25 12:57:12,315 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:12,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:12,626 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:12,626 DEBUG --- stdout --- 2024-05-25 12:57:12,626 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:12,626 DEBUG --- stderr --- 2024-05-25 12:57:12,626 DEBUG source : /results/orders/login-tree-700/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stdout.txt 2024-05-25 12:57:12,626 INFO 2024-05-25 12:57:12,627 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-25 12:57:12,627 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:12,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:12,933 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:12,933 DEBUG --- stdout --- 2024-05-25 12:57:12,934 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:12,934 DEBUG --- stderr --- 2024-05-25 12:57:12,934 DEBUG 2024-05-25 12:57:12,934 INFO 2024-05-25 12:57:12,934 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-700/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stdout.txt 2024-05-25 12:57:12,934 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 12:57:13,989 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:13,989 DEBUG --- stdout --- 2024-05-25 12:57:13,990 DEBUG tar: Removing leading `/' from member names 2024-05-25 12:57:13,990 DEBUG --- stderr --- 2024-05-25 12:57:13,990 DEBUG ________________________________________________________________________________ [2024-05-25 12:57:13] login-tree-700 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 12:57:13,990 INFO 2024-05-25 12:57:13,990 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-25 12:57:13,990 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:14,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:14,300 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:14,300 DEBUG --- stdout --- 2024-05-25 12:57:14,300 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:14,300 DEBUG --- stderr --- 2024-05-25 12:57:14,300 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 12:57:14,300 INFO 2024-05-25 12:57:14,300 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-25 12:57:14,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:14,610 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:14,610 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:14,610 DEBUG --- stdout --- 2024-05-25 12:57:14,610 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:14,610 DEBUG --- stderr --- 2024-05-25 12:57:14,610 DEBUG 2024-05-25 12:57:14,611 INFO 2024-05-25 12:57:14,611 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/login-tree-700/cmd.txt" ]; then echo "/results/orders/login-tree-700/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-700/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-700/cmd.txt file found" 2024-05-25 12:57:14,611 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 12:57:15,415 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 12:57:15,416 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:15,416 DEBUG --- stdout --- 2024-05-25 12:57:15,416 DEBUG /results/orders/login-tree-700/cmd.txt file found 2024-05-25 12:57:15,416 DEBUG --- stderr --- 2024-05-25 12:57:15,416 DEBUG 2024-05-25 12:57:15,416 INFO 2024-05-25 12:57:15,416 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-25 12:57:15,416 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:15,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:15,727 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:15,727 DEBUG --- stdout --- 2024-05-25 12:57:15,727 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:15,727 DEBUG --- stderr --- 2024-05-25 12:57:15,727 DEBUG 2024-05-25 12:57:15,727 INFO 2024-05-25 12:57:15,727 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-700/cmd.txt 2024-05-25 12:57:16,532 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 12:57:16,532 DEBUG --- stdout --- 2024-05-25 12:57:16,533 DEBUG 4 /results/orders/login-tree-700/cmd.txt 2024-05-25 12:57:16,533 DEBUG --- stderr --- 2024-05-25 12:57:16,533 DEBUG 2024-05-25 12:57:16,533 INFO - Download file (size 4k) 2024-05-25 12:57:16,533 INFO 2024-05-25 12:57:16,533 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-25 12:57:16,534 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:16,840 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:16,840 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:16,840 DEBUG --- stdout --- 2024-05-25 12:57:16,840 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:16,841 DEBUG --- stderr --- 2024-05-25 12:57:16,841 DEBUG source : /results/orders/login-tree-700/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/cmd.txt 2024-05-25 12:57:16,841 INFO 2024-05-25 12:57:16,841 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-25 12:57:16,841 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 12:57:17,142 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 12:57:17,142 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:17,142 DEBUG --- stdout --- 2024-05-25 12:57:17,142 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 12:57:17,142 DEBUG --- stderr --- 2024-05-25 12:57:17,142 DEBUG 2024-05-25 12:57:17,143 INFO 2024-05-25 12:57:17,143 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-700/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/cmd.txt 2024-05-25 12:57:17,143 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 12:57:17,863 INFO [loop_until]: OK (rc = 0) 2024-05-25 12:57:17,863 DEBUG --- stdout --- 2024-05-25 12:57:17,863 DEBUG tar: Removing leading `/' from member names 2024-05-25 12:57:17,863 DEBUG --- stderr --- 2024-05-25 12:57:17,863 DEBUG 2024-05-25 12:57:17,864 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/cmd.txt is small (1 lines), printing all lines: 2024-05-25 12:57:17,864 DEBUG ----- output ----- 2024-05-25 12:57:17,864 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 "idc.login@ema-perf-3580" --results-folder /results/gatling/login-tree-700 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 12:57:17,864 INFO ________________________________________________________________________________ [2024-05-25 12:57:17] login-tree-700 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 12:57:17,864 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/rc.txt is small (1 lines), printing all lines: 2024-05-25 12:57:17,864 DEBUG ----- output ----- 2024-05-25 12:57:17,864 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 12:57:17,864 INFO ________________________________________________________________________________ [2024-05-25 12:57:17] login-tree-700 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 12:57:17,866 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/07_Scenario_scenario/08_login-tree-700/stdout.txt is 10789 lines): 2024-05-25 12:57:17,866 DEBUG ----- output ----- 2024-05-25 12:57:17,866 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 12:57:17,866 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 12:57:17,867 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO ================================================================================ 2024-05-25 12:57:17,867 INFO 2024-05-25 12:54:53 5s elapsed 2024-05-25 12:57:17,867 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,867 INFO > Global (OK=580 KO=0 ) 2024-05-25 12:57:17,867 INFO > restLoginInitiate (OK=291 KO=0 ) 2024-05-25 12:57:17,867 INFO > restLoginUsernamePasswordCallback (OK=289 KO=0 ) 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,867 INFO [- ] 0% 2024-05-25 12:57:17,867 INFO waiting: 2528072 / active: 4 / done: 289 2024-05-25 12:57:17,867 INFO ================================================================================ 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO ================================================================================ 2024-05-25 12:57:17,867 INFO 2024-05-25 12:54:58 10s elapsed 2024-05-25 12:57:17,867 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,867 INFO > Global (OK=2328 KO=0 ) 2024-05-25 12:57:17,867 INFO > restLoginInitiate (OK=1167 KO=0 ) 2024-05-25 12:57:17,867 INFO > restLoginUsernamePasswordCallback (OK=1161 KO=0 ) 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,867 INFO [- ] 0% 2024-05-25 12:57:17,867 INFO waiting: 2527195 / active: 9 / done: 1161 2024-05-25 12:57:17,867 INFO ================================================================================ 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO ================================================================================ 2024-05-25 12:57:17,867 INFO 2024-05-25 12:55:03 15s elapsed 2024-05-25 12:57:17,867 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,867 INFO > Global (OK=5244 KO=0 ) 2024-05-25 12:57:17,867 INFO > restLoginInitiate (OK=2627 KO=0 ) 2024-05-25 12:57:17,867 INFO > restLoginUsernamePasswordCallback (OK=2617 KO=0 ) 2024-05-25 12:57:17,867 INFO 2024-05-25 12:57:17,867 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,867 INFO [- ] 0% 2024-05-25 12:57:17,867 INFO waiting: 2525734 / active: 14 / done: 2617 2024-05-25 12:57:17,867 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:55:08 20s elapsed 2024-05-25 12:57:17,868 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,868 INFO > Global (OK=9322 KO=0 ) 2024-05-25 12:57:17,868 INFO > restLoginInitiate (OK=4667 KO=0 ) 2024-05-25 12:57:17,868 INFO > restLoginUsernamePasswordCallback (OK=4655 KO=0 ) 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,868 INFO [- ] 0% 2024-05-25 12:57:17,868 INFO waiting: 2523691 / active: 19 / done: 4655 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:55:13 25s elapsed 2024-05-25 12:57:17,868 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,868 INFO > Global (OK=14568 KO=0 ) 2024-05-25 12:57:17,868 INFO > restLoginInitiate (OK=7294 KO=0 ) 2024-05-25 12:57:17,868 INFO > restLoginUsernamePasswordCallback (OK=7274 KO=0 ) 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,868 INFO [- ] 0% 2024-05-25 12:57:17,868 INFO waiting: 2521064 / active: 28 / done: 7273 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:55:18 30s elapsed 2024-05-25 12:57:17,868 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,868 INFO > Global (OK=20932 KO=0 ) 2024-05-25 12:57:17,868 INFO > restLoginInitiate (OK=10487 KO=0 ) 2024-05-25 12:57:17,868 INFO > restLoginUsernamePasswordCallback (OK=10445 KO=0 ) 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,868 INFO [- ] 0% 2024-05-25 12:57:17,868 INFO waiting: 2517857 / active: 63 / done: 10445 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO 2024-05-25 12:57:17,868 INFO ================================================================================ 2024-05-25 12:57:17,869 INFO 2024-05-25 12:55:23 35s elapsed 2024-05-25 12:57:17,869 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,869 INFO > Global (OK=27881 KO=0 ) 2024-05-25 12:57:17,869 INFO > restLoginInitiate (OK=13956 KO=0 ) 2024-05-25 12:57:17,869 INFO > restLoginUsernamePasswordCallback (OK=13925 KO=0 ) 2024-05-25 12:57:17,869 INFO 2024-05-25 12:57:17,869 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,869 INFO [- ] 0% 2024-05-25 12:57:17,869 INFO waiting: 2514403 / active: 37 / done: 13925 2024-05-25 12:57:17,869 INFO ================================================================================ 2024-05-25 12:57:17,869 INFO 2024-05-25 12:57:17,869 INFO 2024-05-25 12:57:17,869 INFO ================================================================================ 2024-05-25 12:57:17,869 INFO 2024-05-25 12:55:28 40s elapsed 2024-05-25 12:57:17,869 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,869 INFO > Global (OK=34766 KO=0 ) 2024-05-25 12:57:17,869 INFO > restLoginInitiate (OK=17407 KO=0 ) 2024-05-25 12:57:17,869 INFO [...] 2024-05-25 12:57:17,872 INFO 2024-05-25 12:57:17,872 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,872 INFO [#########################################################################-] 99% 2024-05-25 12:57:17,872 INFO waiting: 14109 / active: 45 / done: 2514211 2024-05-25 12:57:17,872 INFO ================================================================================ 2024-05-25 12:57:17,872 INFO 2024-05-25 12:57:17,872 INFO 2024-05-25 12:57:17,872 INFO ================================================================================ 2024-05-25 12:57:17,872 INFO 2024-05-25 13:55:03 3615s elapsed 2024-05-25 12:57:17,872 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,872 INFO > Global (OK=5035417 KO=4 ) 2024-05-25 12:57:17,872 INFO > restLoginInitiate (OK=2517729 KO=2 ) 2024-05-25 12:57:17,872 INFO > restLoginUsernamePasswordCallback (OK=2517688 KO=2 ) 2024-05-25 12:57:17,872 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 12:57:17,872 INFO > status.find.is(200), but actually found 502 4 (100.0%) 2024-05-25 12:57:17,872 INFO 2024-05-25 12:57:17,872 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,872 INFO [#########################################################################-] 99% 2024-05-25 12:57:17,872 INFO waiting: 10626 / active: 47 / done: 2517692 2024-05-25 12:57:17,872 INFO ================================================================================ 2024-05-25 12:57:17,872 INFO 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO ================================================================================ 2024-05-25 12:57:17,873 INFO 2024-05-25 13:55:08 3620s elapsed 2024-05-25 12:57:17,873 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,873 INFO > Global (OK=5042481 KO=4 ) 2024-05-25 12:57:17,873 INFO > restLoginInitiate (OK=2521266 KO=2 ) 2024-05-25 12:57:17,873 INFO > restLoginUsernamePasswordCallback (OK=2521215 KO=2 ) 2024-05-25 12:57:17,873 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 12:57:17,873 INFO > status.find.is(200), but actually found 502 4 (100.0%) 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,873 INFO [#########################################################################-] 99% 2024-05-25 12:57:17,873 INFO waiting: 7084 / active: 62 / done: 2521219 2024-05-25 12:57:17,873 INFO ================================================================================ 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO ================================================================================ 2024-05-25 12:57:17,873 INFO 2024-05-25 13:55:13 3625s elapsed 2024-05-25 12:57:17,873 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,873 INFO > Global (OK=5049282 KO=4 ) 2024-05-25 12:57:17,873 INFO > restLoginInitiate (OK=2524660 KO=2 ) 2024-05-25 12:57:17,873 INFO > restLoginUsernamePasswordCallback (OK=2524622 KO=2 ) 2024-05-25 12:57:17,873 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 12:57:17,873 INFO > status.find.is(200), but actually found 502 4 (100.0%) 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,873 INFO [#########################################################################-] 99% 2024-05-25 12:57:17,873 INFO waiting: 3590 / active: 149 / done: 2524626 2024-05-25 12:57:17,873 INFO ================================================================================ 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO ================================================================================ 2024-05-25 12:57:17,873 INFO 2024-05-25 13:55:18 3630s elapsed 2024-05-25 12:57:17,873 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,873 INFO > Global (OK=5056013 KO=4 ) 2024-05-25 12:57:17,873 INFO > restLoginInitiate (OK=2528352 KO=2 ) 2024-05-25 12:57:17,873 INFO > restLoginUsernamePasswordCallback (OK=2527661 KO=2 ) 2024-05-25 12:57:17,873 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 12:57:17,873 INFO > status.find.is(200), but actually found 502 4 (100.0%) 2024-05-25 12:57:17,873 INFO 2024-05-25 12:57:17,873 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,874 INFO [#########################################################################-] 99% 2024-05-25 12:57:17,874 INFO waiting: 0 / active: 700 / done: 2527665 2024-05-25 12:57:17,874 INFO ================================================================================ 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO ================================================================================ 2024-05-25 12:57:17,874 INFO 2024-05-25 13:55:18 3630s elapsed 2024-05-25 12:57:17,874 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 12:57:17,874 INFO > Global (OK=5056724 KO=4 ) 2024-05-25 12:57:17,874 INFO > restLoginInitiate (OK=2528363 KO=2 ) 2024-05-25 12:57:17,874 INFO > restLoginUsernamePasswordCallback (OK=2528361 KO=2 ) 2024-05-25 12:57:17,874 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 12:57:17,874 INFO > status.find.is(200), but actually found 502 4 (100.0%) 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 12:57:17,874 INFO [##########################################################################]100% 2024-05-25 12:57:17,874 INFO waiting: 0 / active: 0 / done: 2528365 2024-05-25 12:57:17,874 INFO ================================================================================ 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO ---------------------- 2024-05-25 12:57:17,874 INFO [25/May/2024 13:55:18] Create tar file login-tree-700.tar.gz 2024-05-25 12:57:17,874 INFO ---------------------- 2024-05-25 12:57:17,874 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 12:57:17,874 INFO RESULT_FOLDER=login-tree-700 2024-05-25 12:57:17,874 INFO SIMULATION_PATH=login-tree-700/loadtest-20240525115447632 2024-05-25 12:57:17,874 INFO PWD=/results/gatling/login-tree-700/loadtest-20240525115447632 2024-05-25 12:57:17,874 INFO [cmd] tar -czvf /results/gatling//login-tree-700.tar.gz --exclude=simulation.log * 2024-05-25 12:57:17,874 INFO /results/gatling 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO ---------------------- 2024-05-25 12:57:17,874 INFO [25/May/2024 13:55:18] Update /results/gatling//latest 2024-05-25 12:57:17,874 INFO ---------------------- 2024-05-25 12:57:17,874 INFO 2024-05-25 12:57:17,874 INFO ---------------------- 2024-05-25 12:57:17,875 INFO [25/May/2024 13:55:18] Update /results/gatling//latest.tar.gz 2024-05-25 12:57:17,875 INFO ---------------------- 2024-05-25 12:57:17,875 INFO ________________________________________________________________________________ [2024-05-25 12:57:17] login-tree-700 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 12:57:17,875 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/08_login-tree-700/stderr.txt is small (0 lines), printing all lines: 2024-05-25 12:57:17,875 DEBUG ----- output ----- 2024-05-25 12:57:17,875 DEBUG 2024-05-25 12:57:17,875 INFO ________________________________________________________________________________ [2024-05-25 12:57:17] login-tree-700 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-25 12:57:17] login-tree-700 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 12:57:17] login-tree-700 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped