--Task-- name: login-tree-400 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-300'] preceding_task: login-tree-300 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 400} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 08:46:46] login-tree-400 pre : N/A ________________________________________________________________________________ [2024-05-25 08:46:46] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 08:46:46] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-400/stdout.txt Pod log : /results/orders/login-tree-400 2024-05-25 08:46:46,468 INFO 2024-05-25 08:46:46,468 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 08:46:46,468 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:46,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:46,775 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:46,775 DEBUG --- stdout --- 2024-05-25 08:46:46,775 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:46,775 DEBUG --- stderr --- 2024-05-25 08:46:46,775 DEBUG ________________________________________________________________________________ [2024-05-25 08:46:46] login-tree-400 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 08:46:46,775 INFO 2024-05-25 08:46:46,775 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 08:46:46,775 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:47,077 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:47,077 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:47,077 DEBUG --- stdout --- 2024-05-25 08:46:47,077 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:47,077 DEBUG --- stderr --- 2024-05-25 08:46:47,077 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-400 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 08:46:47] login-tree-400 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 08:46:47,078 INFO 2024-05-25 08:46:47,078 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 08:46:47,078 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:47,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:47,380 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:47,380 DEBUG --- stdout --- 2024-05-25 08:46:47,380 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:47,380 DEBUG --- stderr --- 2024-05-25 08:46:47,380 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/order.json { "name": "login-tree-400", "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-400 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-400", "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=400 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 08:46:47] login-tree-400 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 08:46:47,383 INFO 2024-05-25 08:46:47,383 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 08:46:47,383 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 08:46:47,634 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 08:46:47,634 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:47,634 DEBUG --- stdout --- 2024-05-25 08:46:47,634 DEBUG WAITING 2024-05-25 08:46:47,634 DEBUG --- stderr --- 2024-05-25 08:46:47,634 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 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-05-25 08:46:47] login-tree-400 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 08:46:47,635 INFO 2024-05-25 08:46:47,635 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 08:46:47,635 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:47,940 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:47,940 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:47,940 DEBUG --- stdout --- 2024-05-25 08:46:47,941 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:47,941 DEBUG --- stderr --- 2024-05-25 08:46:47,941 DEBUG 2024-05-25 08:46:47,941 INFO 2024-05-25 08:46:47,941 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/05_login-tree-400/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 08:46:47,941 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 08:46:49,290 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:49,290 DEBUG --- stdout --- 2024-05-25 08:46:49,290 DEBUG 2024-05-25 08:46:49,290 DEBUG --- stderr --- 2024-05-25 08:46:49,290 DEBUG Order has been successfully forwarded to overseer [2024-05-25 08:46:49] - INFO: Check if Gatling simulation started successfully 2024-05-25 08:46:49,291 INFO 2024-05-25 08:46:49,291 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 08:46:49,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:49,595 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:49,595 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:49,595 DEBUG --- stdout --- 2024-05-25 08:46:49,595 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:49,595 DEBUG --- stderr --- 2024-05-25 08:46:49,595 DEBUG 2024-05-25 08:46:49,596 INFO 2024-05-25 08:46:49,596 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-400/stdout.txt | grep "started..." 2024-05-25 08:46:49,596 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 08:46:50,338 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 08:46:56,076 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 08:46:56,077 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:56,077 DEBUG --- stdout --- 2024-05-25 08:46:56,077 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 08:46:56,077 DEBUG --- stderr --- 2024-05-25 08:46:56,077 DEBUG ________________________________________________________________________________ [2024-05-25 08:46:56] login-tree-400 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 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 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, 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 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, 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 09:49:06] login-tree-400 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 09:49:06,430 INFO 2024-05-25 09:49:06,431 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 09:49:06,431 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:06,758 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:06,758 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:06,758 DEBUG --- stdout --- 2024-05-25 09:49:06,758 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:06,758 DEBUG --- stderr --- 2024-05-25 09:49:06,758 DEBUG 2024-05-25 09:49:06,759 INFO 2024-05-25 09:49:06,759 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 09:49:06,759 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 09:49:07,542 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:07,542 DEBUG --- stdout --- 2024-05-25 09:49:07,542 DEBUG 2024-05-25 09:49:07,542 DEBUG --- stderr --- 2024-05-25 09:49:07,542 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 09:49:07] login-tree-400 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 09:49:07] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/order.json 2024-05-25 09:49:07,544 INFO 2024-05-25 09:49:07,544 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 09:49:07,544 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:07,949 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:07,949 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:07,949 DEBUG --- stdout --- 2024-05-25 09:49:07,949 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:07,949 DEBUG --- stderr --- 2024-05-25 09:49:07,950 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 09:49:07,950 INFO 2024-05-25 09:49:07,950 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 09:49:07,950 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:08,265 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:08,265 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:08,265 DEBUG --- stdout --- 2024-05-25 09:49:08,265 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:08,265 DEBUG --- stderr --- 2024-05-25 09:49:08,265 DEBUG 2024-05-25 09:49:08,266 INFO 2024-05-25 09:49:08,266 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-400/rc.txt" ]; then echo "/results/orders/login-tree-400/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-400/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-400/rc.txt file found" 2024-05-25 09:49:08,266 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 09:49:09,071 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 09:49:09,071 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:09,072 DEBUG --- stdout --- 2024-05-25 09:49:09,072 DEBUG /results/orders/login-tree-400/rc.txt file found 2024-05-25 09:49:09,072 DEBUG --- stderr --- 2024-05-25 09:49:09,072 DEBUG 2024-05-25 09:49:09,072 INFO 2024-05-25 09:49:09,072 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 09:49:09,072 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:09,370 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:09,370 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:09,370 DEBUG --- stdout --- 2024-05-25 09:49:09,370 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:09,371 DEBUG --- stderr --- 2024-05-25 09:49:09,371 DEBUG 2024-05-25 09:49:09,371 INFO 2024-05-25 09:49:09,371 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-400/rc.txt 2024-05-25 09:49:10,172 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 09:49:10,172 DEBUG --- stdout --- 2024-05-25 09:49:10,172 DEBUG 4 /results/orders/login-tree-400/rc.txt 2024-05-25 09:49:10,172 DEBUG --- stderr --- 2024-05-25 09:49:10,172 DEBUG 2024-05-25 09:49:10,172 INFO - Download file (size 4k) 2024-05-25 09:49:10,172 INFO 2024-05-25 09:49:10,172 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 09:49:10,173 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:10,498 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:10,498 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:10,498 DEBUG --- stdout --- 2024-05-25 09:49:10,498 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:10,498 DEBUG --- stderr --- 2024-05-25 09:49:10,498 DEBUG source : /results/orders/login-tree-400/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/rc.txt 2024-05-25 09:49:10,499 INFO 2024-05-25 09:49:10,499 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 09:49:10,499 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:10,803 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:10,803 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:10,803 DEBUG --- stdout --- 2024-05-25 09:49:10,803 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:10,803 DEBUG --- stderr --- 2024-05-25 09:49:10,803 DEBUG 2024-05-25 09:49:10,804 INFO 2024-05-25 09:49:10,804 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-400/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/rc.txt 2024-05-25 09:49:10,804 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 09:49:11,579 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:11,579 DEBUG --- stdout --- 2024-05-25 09:49:11,579 DEBUG tar: Removing leading `/' from member names 2024-05-25 09:49:11,579 DEBUG --- stderr --- 2024-05-25 09:49:11,579 DEBUG 2024-05-25 09:49:11,580 INFO 2024-05-25 09:49:11,580 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 09:49:11,580 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:11,888 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:11,888 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:11,888 DEBUG --- stdout --- 2024-05-25 09:49:11,888 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:11,888 DEBUG --- stderr --- 2024-05-25 09:49:11,888 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 09:49:11,888 INFO 2024-05-25 09:49:11,888 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 09:49:11,889 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:12,189 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:12,190 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:12,190 DEBUG --- stdout --- 2024-05-25 09:49:12,190 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:12,190 DEBUG --- stderr --- 2024-05-25 09:49:12,190 DEBUG 2024-05-25 09:49:12,190 INFO 2024-05-25 09:49:12,190 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-400/stderr.txt" ]; then echo "/results/orders/login-tree-400/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-400/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-400/stderr.txt file found" 2024-05-25 09:49:12,191 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 09:49:12,987 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 09:49:12,988 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:12,988 DEBUG --- stdout --- 2024-05-25 09:49:12,988 DEBUG /results/orders/login-tree-400/stderr.txt file found 2024-05-25 09:49:12,988 DEBUG --- stderr --- 2024-05-25 09:49:12,988 DEBUG 2024-05-25 09:49:12,988 INFO 2024-05-25 09:49:12,988 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 09:49:12,988 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:13,295 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:13,296 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:13,296 DEBUG --- stdout --- 2024-05-25 09:49:13,296 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:13,296 DEBUG --- stderr --- 2024-05-25 09:49:13,296 DEBUG 2024-05-25 09:49:13,296 INFO 2024-05-25 09:49:13,296 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-400/stderr.txt 2024-05-25 09:49:14,091 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 09:49:14,091 DEBUG --- stdout --- 2024-05-25 09:49:14,091 DEBUG 0 /results/orders/login-tree-400/stderr.txt 2024-05-25 09:49:14,091 DEBUG --- stderr --- 2024-05-25 09:49:14,091 DEBUG 2024-05-25 09:49:14,091 INFO - Download file (size 0k) 2024-05-25 09:49:14,092 INFO 2024-05-25 09:49:14,092 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 09:49:14,092 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:14,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:14,465 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:14,465 DEBUG --- stdout --- 2024-05-25 09:49:14,465 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:14,465 DEBUG --- stderr --- 2024-05-25 09:49:14,465 DEBUG source : /results/orders/login-tree-400/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/stderr.txt 2024-05-25 09:49:14,466 INFO 2024-05-25 09:49:14,466 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 09:49:14,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:14,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:14,780 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:14,780 DEBUG --- stdout --- 2024-05-25 09:49:14,780 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:14,780 DEBUG --- stderr --- 2024-05-25 09:49:14,781 DEBUG 2024-05-25 09:49:14,781 INFO 2024-05-25 09:49:14,781 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-400/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/stderr.txt 2024-05-25 09:49:14,781 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 09:49:15,553 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:15,553 DEBUG --- stdout --- 2024-05-25 09:49:15,553 DEBUG tar: Removing leading `/' from member names 2024-05-25 09:49:15,553 DEBUG --- stderr --- 2024-05-25 09:49:15,553 DEBUG 2024-05-25 09:49:15,554 INFO 2024-05-25 09:49:15,554 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 09:49:15,554 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:15,865 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:15,865 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:15,865 DEBUG --- stdout --- 2024-05-25 09:49:15,865 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:15,865 DEBUG --- stderr --- 2024-05-25 09:49:15,865 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 09:49:15,865 INFO 2024-05-25 09:49:15,865 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 09:49:15,866 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:16,174 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:16,175 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:16,175 DEBUG --- stdout --- 2024-05-25 09:49:16,175 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:16,175 DEBUG --- stderr --- 2024-05-25 09:49:16,175 DEBUG 2024-05-25 09:49:16,175 INFO 2024-05-25 09:49:16,175 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-400/stdout.txt" ]; then echo "/results/orders/login-tree-400/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-400/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-400/stdout.txt file found" 2024-05-25 09:49:16,175 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 09:49:16,985 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 09:49:16,987 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:16,987 DEBUG --- stdout --- 2024-05-25 09:49:16,987 DEBUG /results/orders/login-tree-400/stdout.txt file found 2024-05-25 09:49:16,987 DEBUG --- stderr --- 2024-05-25 09:49:16,987 DEBUG 2024-05-25 09:49:16,988 INFO 2024-05-25 09:49:16,988 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 09:49:16,988 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:17,308 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:17,308 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:17,308 DEBUG --- stdout --- 2024-05-25 09:49:17,308 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:17,308 DEBUG --- stderr --- 2024-05-25 09:49:17,308 DEBUG 2024-05-25 09:49:17,308 INFO 2024-05-25 09:49:17,309 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-400/stdout.txt 2024-05-25 09:49:18,101 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 09:49:18,101 DEBUG --- stdout --- 2024-05-25 09:49:18,101 DEBUG 640 /results/orders/login-tree-400/stdout.txt 2024-05-25 09:49:18,101 DEBUG --- stderr --- 2024-05-25 09:49:18,101 DEBUG 2024-05-25 09:49:18,102 INFO - Download file (size 640k) 2024-05-25 09:49:18,102 INFO 2024-05-25 09:49:18,102 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 09:49:18,102 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:18,404 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:18,404 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:18,404 DEBUG --- stdout --- 2024-05-25 09:49:18,404 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:18,404 DEBUG --- stderr --- 2024-05-25 09:49:18,404 DEBUG source : /results/orders/login-tree-400/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/stdout.txt 2024-05-25 09:49:18,404 INFO 2024-05-25 09:49:18,404 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 09:49:18,405 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:18,715 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:18,715 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:18,715 DEBUG --- stdout --- 2024-05-25 09:49:18,715 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:18,715 DEBUG --- stderr --- 2024-05-25 09:49:18,715 DEBUG 2024-05-25 09:49:18,716 INFO 2024-05-25 09:49:18,716 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-400/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/stdout.txt 2024-05-25 09:49:18,716 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 09:49:19,765 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:19,765 DEBUG --- stdout --- 2024-05-25 09:49:19,765 DEBUG tar: Removing leading `/' from member names 2024-05-25 09:49:19,765 DEBUG --- stderr --- 2024-05-25 09:49:19,766 DEBUG ________________________________________________________________________________ [2024-05-25 09:49:19] login-tree-400 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 09:49:19,766 INFO 2024-05-25 09:49:19,766 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 09:49:19,766 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:20,067 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:20,067 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:20,067 DEBUG --- stdout --- 2024-05-25 09:49:20,067 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:20,067 DEBUG --- stderr --- 2024-05-25 09:49:20,067 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 09:49:20,067 INFO 2024-05-25 09:49:20,067 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 09:49:20,067 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:20,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:20,364 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:20,364 DEBUG --- stdout --- 2024-05-25 09:49:20,364 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:20,364 DEBUG --- stderr --- 2024-05-25 09:49:20,364 DEBUG 2024-05-25 09:49:20,365 INFO 2024-05-25 09:49:20,365 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-400/cmd.txt" ]; then echo "/results/orders/login-tree-400/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-400/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-400/cmd.txt file found" 2024-05-25 09:49:20,365 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 09:49:21,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 09:49:21,170 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:21,170 DEBUG --- stdout --- 2024-05-25 09:49:21,170 DEBUG /results/orders/login-tree-400/cmd.txt file found 2024-05-25 09:49:21,170 DEBUG --- stderr --- 2024-05-25 09:49:21,170 DEBUG 2024-05-25 09:49:21,170 INFO 2024-05-25 09:49:21,170 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 09:49:21,170 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:21,485 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:21,485 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:21,485 DEBUG --- stdout --- 2024-05-25 09:49:21,485 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:21,485 DEBUG --- stderr --- 2024-05-25 09:49:21,485 DEBUG 2024-05-25 09:49:21,485 INFO 2024-05-25 09:49:21,485 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-400/cmd.txt 2024-05-25 09:49:22,297 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 09:49:22,298 DEBUG --- stdout --- 2024-05-25 09:49:22,299 DEBUG 4 /results/orders/login-tree-400/cmd.txt 2024-05-25 09:49:22,299 DEBUG --- stderr --- 2024-05-25 09:49:22,299 DEBUG 2024-05-25 09:49:22,299 INFO - Download file (size 4k) 2024-05-25 09:49:22,299 INFO 2024-05-25 09:49:22,299 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 09:49:22,299 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:22,597 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:22,597 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:22,597 DEBUG --- stdout --- 2024-05-25 09:49:22,597 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:22,597 DEBUG --- stderr --- 2024-05-25 09:49:22,597 DEBUG source : /results/orders/login-tree-400/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/cmd.txt 2024-05-25 09:49:22,598 INFO 2024-05-25 09:49:22,598 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 09:49:22,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:22,901 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:22,901 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:22,901 DEBUG --- stdout --- 2024-05-25 09:49:22,902 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:22,902 DEBUG --- stderr --- 2024-05-25 09:49:22,902 DEBUG 2024-05-25 09:49:22,902 INFO 2024-05-25 09:49:22,902 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-400/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/cmd.txt 2024-05-25 09:49:22,902 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 09:49:23,662 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:23,662 DEBUG --- stdout --- 2024-05-25 09:49:23,662 DEBUG tar: Removing leading `/' from member names 2024-05-25 09:49:23,662 DEBUG --- stderr --- 2024-05-25 09:49:23,662 DEBUG 2024-05-25 09:49:23,663 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/cmd.txt is small (1 lines), printing all lines: 2024-05-25 09:49:23,663 DEBUG ----- output ----- 2024-05-25 09:49:23,663 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-400 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 09:49:23,663 INFO ________________________________________________________________________________ [2024-05-25 09:49:23] login-tree-400 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 09:49:23,664 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/rc.txt is small (1 lines), printing all lines: 2024-05-25 09:49:23,664 DEBUG ----- output ----- 2024-05-25 09:49:23,664 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 09:49:23,664 INFO ________________________________________________________________________________ [2024-05-25 09:49:23] login-tree-400 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 09:49:23,665 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/05_login-tree-400/stdout.txt is 10469 lines): 2024-05-25 09:49:23,666 DEBUG ----- output ----- 2024-05-25 09:49:23,666 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 09:49:23,666 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 09:49:23,666 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO ================================================================================ 2024-05-25 09:49:23,666 INFO 2024-05-25 09:46:58 5s elapsed 2024-05-25 09:49:23,666 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,666 INFO > Global (OK=331 KO=0 ) 2024-05-25 09:49:23,666 INFO > restLoginInitiate (OK=166 KO=0 ) 2024-05-25 09:49:23,666 INFO > restLoginUsernamePasswordCallback (OK=165 KO=0 ) 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,666 INFO [- ] 0% 2024-05-25 09:49:23,666 INFO waiting: 1448071 / active: 2 / done: 165 2024-05-25 09:49:23,666 INFO ================================================================================ 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO ================================================================================ 2024-05-25 09:49:23,666 INFO 2024-05-25 09:47:03 10s elapsed 2024-05-25 09:49:23,666 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,666 INFO > Global (OK=1329 KO=0 ) 2024-05-25 09:49:23,666 INFO > restLoginInitiate (OK=666 KO=0 ) 2024-05-25 09:49:23,666 INFO > restLoginUsernamePasswordCallback (OK=663 KO=0 ) 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,666 INFO [- ] 0% 2024-05-25 09:49:23,666 INFO waiting: 1447570 / active: 5 / done: 663 2024-05-25 09:49:23,666 INFO ================================================================================ 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO 2024-05-25 09:49:23,666 INFO ================================================================================ 2024-05-25 09:49:23,666 INFO 2024-05-25 09:47:08 15s elapsed 2024-05-25 09:49:23,666 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,666 INFO > Global (OK=2995 KO=0 ) 2024-05-25 09:49:23,666 INFO > restLoginInitiate (OK=1500 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginUsernamePasswordCallback (OK=1495 KO=0 ) 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,667 INFO [- ] 0% 2024-05-25 09:49:23,667 INFO waiting: 1446735 / active: 8 / done: 1495 2024-05-25 09:49:23,667 INFO ================================================================================ 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO ================================================================================ 2024-05-25 09:49:23,667 INFO 2024-05-25 09:47:13 20s elapsed 2024-05-25 09:49:23,667 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,667 INFO > Global (OK=5324 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginInitiate (OK=2666 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginUsernamePasswordCallback (OK=2658 KO=0 ) 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,667 INFO [- ] 0% 2024-05-25 09:49:23,667 INFO waiting: 1445568 / active: 12 / done: 2658 2024-05-25 09:49:23,667 INFO ================================================================================ 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO ================================================================================ 2024-05-25 09:49:23,667 INFO 2024-05-25 09:47:18 25s elapsed 2024-05-25 09:49:23,667 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,667 INFO > Global (OK=8322 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginInitiate (OK=4166 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginUsernamePasswordCallback (OK=4156 KO=0 ) 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,667 INFO [- ] 0% 2024-05-25 09:49:23,667 INFO waiting: 1444068 / active: 14 / done: 4156 2024-05-25 09:49:23,667 INFO ================================================================================ 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,667 INFO ================================================================================ 2024-05-25 09:49:23,667 INFO 2024-05-25 09:47:23 30s elapsed 2024-05-25 09:49:23,667 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,667 INFO > Global (OK=11980 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginInitiate (OK=5996 KO=0 ) 2024-05-25 09:49:23,667 INFO > restLoginUsernamePasswordCallback (OK=5984 KO=0 ) 2024-05-25 09:49:23,667 INFO 2024-05-25 09:49:23,668 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,668 INFO [- ] 0% 2024-05-25 09:49:23,668 INFO waiting: 1442236 / active: 18 / done: 5984 2024-05-25 09:49:23,668 INFO ================================================================================ 2024-05-25 09:49:23,668 INFO 2024-05-25 09:49:23,668 INFO 2024-05-25 09:49:23,668 INFO ================================================================================ 2024-05-25 09:49:23,668 INFO 2024-05-25 09:47:28 35s elapsed 2024-05-25 09:49:23,668 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,668 INFO > Global (OK=15906 KO=0 ) 2024-05-25 09:49:23,668 INFO > restLoginInitiate (OK=7956 KO=0 ) 2024-05-25 09:49:23,668 INFO > restLoginUsernamePasswordCallback (OK=7950 KO=0 ) 2024-05-25 09:49:23,668 INFO 2024-05-25 09:49:23,668 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,668 INFO [- ] 0% 2024-05-25 09:49:23,668 INFO waiting: 1440279 / active: 10 / done: 7949 2024-05-25 09:49:23,668 INFO ================================================================================ 2024-05-25 09:49:23,668 INFO 2024-05-25 09:49:23,668 INFO 2024-05-25 09:49:23,668 INFO ================================================================================ 2024-05-25 09:49:23,668 INFO 2024-05-25 09:47:33 40s elapsed 2024-05-25 09:49:23,668 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,668 INFO > Global (OK=19837 KO=0 ) 2024-05-25 09:49:23,668 INFO > restLoginInitiate (OK=9921 KO=0 ) 2024-05-25 09:49:23,668 INFO [...] 2024-05-25 09:49:23,671 INFO 2024-05-25 09:49:23,671 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,671 INFO [#########################################################################-] 99% 2024-05-25 09:49:23,671 INFO waiting: 8069 / active: 17 / done: 1440152 2024-05-25 09:49:23,671 INFO ================================================================================ 2024-05-25 09:49:23,671 INFO 2024-05-25 09:49:23,671 INFO 2024-05-25 09:49:23,671 INFO ================================================================================ 2024-05-25 09:49:23,671 INFO 2024-05-25 10:47:08 3615s elapsed 2024-05-25 09:49:23,671 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,671 INFO > Global (OK=2884342 KO=3 ) 2024-05-25 09:49:23,671 INFO > restLoginInitiate (OK=1442175 KO=3 ) 2024-05-25 09:49:23,671 INFO > restLoginUsernamePasswordCallback (OK=1442167 KO=0 ) 2024-05-25 09:49:23,671 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 09:49:23,671 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 09:49:23,671 INFO 2024-05-25 09:49:23,671 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,671 INFO [#########################################################################-] 99% 2024-05-25 09:49:23,672 INFO waiting: 6059 / active: 9 / done: 1442170 2024-05-25 09:49:23,672 INFO ================================================================================ 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO ================================================================================ 2024-05-25 09:49:23,672 INFO 2024-05-25 10:47:13 3620s elapsed 2024-05-25 09:49:23,672 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,672 INFO > Global (OK=2888275 KO=3 ) 2024-05-25 09:49:23,672 INFO > restLoginInitiate (OK=1444141 KO=3 ) 2024-05-25 09:49:23,672 INFO > restLoginUsernamePasswordCallback (OK=1444134 KO=0 ) 2024-05-25 09:49:23,672 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 09:49:23,672 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,672 INFO [#########################################################################-] 99% 2024-05-25 09:49:23,672 INFO waiting: 4088 / active: 13 / done: 1444137 2024-05-25 09:49:23,672 INFO ================================================================================ 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO ================================================================================ 2024-05-25 09:49:23,672 INFO 2024-05-25 10:47:18 3625s elapsed 2024-05-25 09:49:23,672 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,672 INFO > Global (OK=2892358 KO=3 ) 2024-05-25 09:49:23,672 INFO > restLoginInitiate (OK=1446188 KO=3 ) 2024-05-25 09:49:23,672 INFO > restLoginUsernamePasswordCallback (OK=1446170 KO=0 ) 2024-05-25 09:49:23,672 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 09:49:23,672 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,672 INFO [#########################################################################-] 99% 2024-05-25 09:49:23,672 INFO waiting: 2039 / active: 26 / done: 1446173 2024-05-25 09:49:23,672 INFO ================================================================================ 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO 2024-05-25 09:49:23,672 INFO ================================================================================ 2024-05-25 09:49:23,672 INFO 2024-05-25 10:47:23 3630s elapsed 2024-05-25 09:49:23,672 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,672 INFO > Global (OK=2896458 KO=3 ) 2024-05-25 09:49:23,672 INFO > restLoginInitiate (OK=1448235 KO=3 ) 2024-05-25 09:49:23,673 INFO > restLoginUsernamePasswordCallback (OK=1448223 KO=0 ) 2024-05-25 09:49:23,673 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 09:49:23,673 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,673 INFO [#########################################################################-] 99% 2024-05-25 09:49:23,673 INFO waiting: 0 / active: 12 / done: 1448226 2024-05-25 09:49:23,673 INFO ================================================================================ 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO ================================================================================ 2024-05-25 09:49:23,673 INFO 2024-05-25 10:47:23 3630s elapsed 2024-05-25 09:49:23,673 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 09:49:23,673 INFO > Global (OK=2896470 KO=3 ) 2024-05-25 09:49:23,673 INFO > restLoginInitiate (OK=1448235 KO=3 ) 2024-05-25 09:49:23,673 INFO > restLoginUsernamePasswordCallback (OK=1448235 KO=0 ) 2024-05-25 09:49:23,673 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 09:49:23,673 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 09:49:23,673 INFO [##########################################################################]100% 2024-05-25 09:49:23,673 INFO waiting: 0 / active: 0 / done: 1448238 2024-05-25 09:49:23,673 INFO ================================================================================ 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO ---------------------- 2024-05-25 09:49:23,673 INFO [25/May/2024 10:47:23] Create tar file login-tree-400.tar.gz 2024-05-25 09:49:23,673 INFO ---------------------- 2024-05-25 09:49:23,673 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 09:49:23,673 INFO RESULT_FOLDER=login-tree-400 2024-05-25 09:49:23,673 INFO SIMULATION_PATH=login-tree-400/loadtest-20240525084653116 2024-05-25 09:49:23,673 INFO PWD=/results/gatling/login-tree-400/loadtest-20240525084653116 2024-05-25 09:49:23,673 INFO [cmd] tar -czvf /results/gatling//login-tree-400.tar.gz --exclude=simulation.log * 2024-05-25 09:49:23,673 INFO /results/gatling 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO ---------------------- 2024-05-25 09:49:23,673 INFO [25/May/2024 10:47:23] Update /results/gatling//latest 2024-05-25 09:49:23,673 INFO ---------------------- 2024-05-25 09:49:23,673 INFO 2024-05-25 09:49:23,673 INFO ---------------------- 2024-05-25 09:49:23,674 INFO [25/May/2024 10:47:23] Update /results/gatling//latest.tar.gz 2024-05-25 09:49:23,674 INFO ---------------------- 2024-05-25 09:49:23,674 INFO ________________________________________________________________________________ [2024-05-25 09:49:23] login-tree-400 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 09:49:23,674 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-400/stderr.txt is small (0 lines), printing all lines: 2024-05-25 09:49:23,674 DEBUG ----- output ----- 2024-05-25 09:49:23,674 DEBUG 2024-05-25 09:49:23,674 INFO ________________________________________________________________________________ [2024-05-25 09:49:23] login-tree-400 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 09:49:23] login-tree-400 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 09:49:23] login-tree-400 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped