--Task-- name: login-tree-500 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-400'] preceding_task: login-tree-400 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 500} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 09:49:24] login-tree-500 pre : N/A ________________________________________________________________________________ [2024-05-25 09:49:24] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 09:49:24] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-500/stdout.txt Pod log : /results/orders/login-tree-500 2024-05-25 09:49:24,526 INFO 2024-05-25 09:49:24,526 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 09:49:24,526 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:24,833 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:24,834 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:24,834 DEBUG --- stdout --- 2024-05-25 09:49:24,834 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:24,834 DEBUG --- stderr --- 2024-05-25 09:49:24,834 DEBUG ________________________________________________________________________________ [2024-05-25 09:49:24] login-tree-500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 09:49:24,834 INFO 2024-05-25 09:49:24,835 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:24,835 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:25,134 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:25,134 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:25,134 DEBUG --- stdout --- 2024-05-25 09:49:25,134 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:25,135 DEBUG --- stderr --- 2024-05-25 09:49:25,135 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-500 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 09:49:25] login-tree-500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 09:49:25,135 INFO 2024-05-25 09:49:25,135 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:25,135 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:25,435 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:25,435 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:25,435 DEBUG --- stdout --- 2024-05-25 09:49:25,435 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:25,435 DEBUG --- stderr --- 2024-05-25 09:49:25,435 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/order.json { "name": "login-tree-500", "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-500 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-500", "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=500 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 09:49:25] login-tree-500 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 09:49:25,438 INFO 2024-05-25 09:49:25,438 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 09:49:25,438 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 09:49:25,689 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 09:49:25,690 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:25,690 DEBUG --- stdout --- 2024-05-25 09:49:25,690 DEBUG WAITING 2024-05-25 09:49:25,690 DEBUG --- stderr --- 2024-05-25 09:49:25,690 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 09:49:25] login-tree-500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 09:49:25,691 INFO 2024-05-25 09:49:25,691 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:25,691 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:26,005 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:26,006 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:26,006 DEBUG --- stdout --- 2024-05-25 09:49:26,006 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:26,006 DEBUG --- stderr --- 2024-05-25 09:49:26,006 DEBUG 2024-05-25 09:49:26,006 INFO 2024-05-25 09:49:26,006 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/06_login-tree-500/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 09:49:26,006 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 09:49:27,357 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:27,357 DEBUG --- stdout --- 2024-05-25 09:49:27,359 DEBUG 2024-05-25 09:49:27,359 DEBUG --- stderr --- 2024-05-25 09:49:27,359 DEBUG Order has been successfully forwarded to overseer [2024-05-25 09:49:27] - INFO: Check if Gatling simulation started successfully 2024-05-25 09:49:27,359 INFO 2024-05-25 09:49:27,359 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:27,359 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 09:49:27,671 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 09:49:27,671 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:27,671 DEBUG --- stdout --- 2024-05-25 09:49:27,671 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 09:49:27,671 DEBUG --- stderr --- 2024-05-25 09:49:27,671 DEBUG 2024-05-25 09:49:27,672 INFO 2024-05-25 09:49:27,672 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-500/stdout.txt | grep "started..." 2024-05-25 09:49:27,672 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 09:49:28,421 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 09:49:34,199 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 09:49:34,199 INFO [loop_until]: OK (rc = 0) 2024-05-25 09:49:34,199 DEBUG --- stdout --- 2024-05-25 09:49:34,199 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 09:49:34,199 DEBUG --- stderr --- 2024-05-25 09:49:34,199 DEBUG ________________________________________________________________________________ [2024-05-25 09:49:34] login-tree-500 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 03s, 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 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, 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 10:51:44] login-tree-500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 10:51:44,507 INFO 2024-05-25 10:51:44,508 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 10:51:44,508 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:44,837 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:44,837 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:44,837 DEBUG --- stdout --- 2024-05-25 10:51:44,837 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:44,837 DEBUG --- stderr --- 2024-05-25 10:51:44,837 DEBUG 2024-05-25 10:51:44,838 INFO 2024-05-25 10:51:44,838 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 10:51:44,838 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 10:51:45,621 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:45,621 DEBUG --- stdout --- 2024-05-25 10:51:45,621 DEBUG 2024-05-25 10:51:45,621 DEBUG --- stderr --- 2024-05-25 10:51:45,621 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 10:51:45] login-tree-500 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 10:51:45] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/order.json 2024-05-25 10:51:45,623 INFO 2024-05-25 10:51:45,623 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 10:51:45,623 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:45,934 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:45,934 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:45,934 DEBUG --- stdout --- 2024-05-25 10:51:45,934 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:45,934 DEBUG --- stderr --- 2024-05-25 10:51:45,934 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 10:51:45,934 INFO 2024-05-25 10:51:45,935 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 10:51:45,935 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:46,240 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:46,240 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:46,240 DEBUG --- stdout --- 2024-05-25 10:51:46,240 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:46,240 DEBUG --- stderr --- 2024-05-25 10:51:46,240 DEBUG 2024-05-25 10:51:46,241 INFO 2024-05-25 10:51:46,241 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-500/rc.txt" ]; then echo "/results/orders/login-tree-500/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-500/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-500/rc.txt file found" 2024-05-25 10:51:46,241 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 10:51:47,040 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 10:51:47,040 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:47,040 DEBUG --- stdout --- 2024-05-25 10:51:47,040 DEBUG /results/orders/login-tree-500/rc.txt file found 2024-05-25 10:51:47,040 DEBUG --- stderr --- 2024-05-25 10:51:47,040 DEBUG 2024-05-25 10:51:47,041 INFO 2024-05-25 10:51:47,041 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 10:51:47,041 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:47,349 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:47,349 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:47,349 DEBUG --- stdout --- 2024-05-25 10:51:47,349 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:47,349 DEBUG --- stderr --- 2024-05-25 10:51:47,349 DEBUG 2024-05-25 10:51:47,349 INFO 2024-05-25 10:51:47,350 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-500/rc.txt 2024-05-25 10:51:48,154 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 10:51:48,155 DEBUG --- stdout --- 2024-05-25 10:51:48,155 DEBUG 4 /results/orders/login-tree-500/rc.txt 2024-05-25 10:51:48,155 DEBUG --- stderr --- 2024-05-25 10:51:48,155 DEBUG 2024-05-25 10:51:48,155 INFO - Download file (size 4k) 2024-05-25 10:51:48,155 INFO 2024-05-25 10:51:48,155 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 10:51:48,155 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:48,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:48,466 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:48,466 DEBUG --- stdout --- 2024-05-25 10:51:48,466 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:48,466 DEBUG --- stderr --- 2024-05-25 10:51:48,466 DEBUG source : /results/orders/login-tree-500/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/rc.txt 2024-05-25 10:51:48,466 INFO 2024-05-25 10:51:48,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 10:51:48,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:48,774 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:48,774 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:48,774 DEBUG --- stdout --- 2024-05-25 10:51:48,774 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:48,774 DEBUG --- stderr --- 2024-05-25 10:51:48,774 DEBUG 2024-05-25 10:51:48,775 INFO 2024-05-25 10:51:48,775 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-500/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/rc.txt 2024-05-25 10:51:48,775 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 10:51:49,506 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:49,507 DEBUG --- stdout --- 2024-05-25 10:51:49,507 DEBUG tar: Removing leading `/' from member names 2024-05-25 10:51:49,507 DEBUG --- stderr --- 2024-05-25 10:51:49,507 DEBUG 2024-05-25 10:51:49,507 INFO 2024-05-25 10:51:49,507 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 10:51:49,507 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:49,818 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:49,819 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:49,819 DEBUG --- stdout --- 2024-05-25 10:51:49,819 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:49,819 DEBUG --- stderr --- 2024-05-25 10:51:49,819 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 10:51:49,819 INFO 2024-05-25 10:51:49,819 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 10:51:49,819 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:50,134 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:50,135 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:50,135 DEBUG --- stdout --- 2024-05-25 10:51:50,135 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:50,135 DEBUG --- stderr --- 2024-05-25 10:51:50,135 DEBUG 2024-05-25 10:51:50,135 INFO 2024-05-25 10:51:50,136 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-500/stderr.txt" ]; then echo "/results/orders/login-tree-500/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-500/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-500/stderr.txt file found" 2024-05-25 10:51:50,136 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 10:51:50,933 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 10:51:50,933 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:50,933 DEBUG --- stdout --- 2024-05-25 10:51:50,933 DEBUG /results/orders/login-tree-500/stderr.txt file found 2024-05-25 10:51:50,933 DEBUG --- stderr --- 2024-05-25 10:51:50,933 DEBUG 2024-05-25 10:51:50,934 INFO 2024-05-25 10:51:50,934 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 10:51:50,934 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:51,234 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:51,234 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:51,234 DEBUG --- stdout --- 2024-05-25 10:51:51,234 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:51,234 DEBUG --- stderr --- 2024-05-25 10:51:51,234 DEBUG 2024-05-25 10:51:51,234 INFO 2024-05-25 10:51:51,234 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-500/stderr.txt 2024-05-25 10:51:52,030 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 10:51:52,031 DEBUG --- stdout --- 2024-05-25 10:51:52,031 DEBUG 0 /results/orders/login-tree-500/stderr.txt 2024-05-25 10:51:52,031 DEBUG --- stderr --- 2024-05-25 10:51:52,031 DEBUG 2024-05-25 10:51:52,031 INFO - Download file (size 0k) 2024-05-25 10:51:52,031 INFO 2024-05-25 10:51:52,032 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 10:51:52,032 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:52,328 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:52,329 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:52,329 DEBUG --- stdout --- 2024-05-25 10:51:52,329 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:52,329 DEBUG --- stderr --- 2024-05-25 10:51:52,329 DEBUG source : /results/orders/login-tree-500/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/stderr.txt 2024-05-25 10:51:52,329 INFO 2024-05-25 10:51:52,329 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 10:51:52,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:52,633 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:52,634 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:52,634 DEBUG --- stdout --- 2024-05-25 10:51:52,634 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:52,634 DEBUG --- stderr --- 2024-05-25 10:51:52,634 DEBUG 2024-05-25 10:51:52,634 INFO 2024-05-25 10:51:52,634 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-500/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/stderr.txt 2024-05-25 10:51:52,634 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 10:51:53,391 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:53,391 DEBUG --- stdout --- 2024-05-25 10:51:53,391 DEBUG tar: Removing leading `/' from member names 2024-05-25 10:51:53,391 DEBUG --- stderr --- 2024-05-25 10:51:53,391 DEBUG 2024-05-25 10:51:53,392 INFO 2024-05-25 10:51:53,392 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 10:51:53,392 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:53,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:53,698 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:53,698 DEBUG --- stdout --- 2024-05-25 10:51:53,698 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:53,698 DEBUG --- stderr --- 2024-05-25 10:51:53,698 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 10:51:53,698 INFO 2024-05-25 10:51:53,698 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 10:51:53,698 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:54,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:54,010 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:54,010 DEBUG --- stdout --- 2024-05-25 10:51:54,010 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:54,010 DEBUG --- stderr --- 2024-05-25 10:51:54,010 DEBUG 2024-05-25 10:51:54,010 INFO 2024-05-25 10:51:54,011 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-500/stdout.txt" ]; then echo "/results/orders/login-tree-500/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-500/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-500/stdout.txt file found" 2024-05-25 10:51:54,011 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 10:51:54,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 10:51:54,815 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:54,816 DEBUG --- stdout --- 2024-05-25 10:51:54,816 DEBUG /results/orders/login-tree-500/stdout.txt file found 2024-05-25 10:51:54,816 DEBUG --- stderr --- 2024-05-25 10:51:54,816 DEBUG 2024-05-25 10:51:54,816 INFO 2024-05-25 10:51:54,816 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 10:51:54,817 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:55,122 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:55,123 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:55,123 DEBUG --- stdout --- 2024-05-25 10:51:55,123 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:55,123 DEBUG --- stderr --- 2024-05-25 10:51:55,123 DEBUG 2024-05-25 10:51:55,123 INFO 2024-05-25 10:51:55,123 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-500/stdout.txt 2024-05-25 10:51:55,930 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 10:51:55,930 DEBUG --- stdout --- 2024-05-25 10:51:55,931 DEBUG 604 /results/orders/login-tree-500/stdout.txt 2024-05-25 10:51:55,931 DEBUG --- stderr --- 2024-05-25 10:51:55,931 DEBUG 2024-05-25 10:51:55,931 INFO - Download file (size 604k) 2024-05-25 10:51:55,931 INFO 2024-05-25 10:51:55,931 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 10:51:55,931 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:56,249 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:56,249 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:56,249 DEBUG --- stdout --- 2024-05-25 10:51:56,249 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:56,249 DEBUG --- stderr --- 2024-05-25 10:51:56,249 DEBUG source : /results/orders/login-tree-500/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/stdout.txt 2024-05-25 10:51:56,250 INFO 2024-05-25 10:51:56,250 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 10:51:56,250 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:56,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:56,567 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:56,567 DEBUG --- stdout --- 2024-05-25 10:51:56,567 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:56,567 DEBUG --- stderr --- 2024-05-25 10:51:56,567 DEBUG 2024-05-25 10:51:56,567 INFO 2024-05-25 10:51:56,567 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-500/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/stdout.txt 2024-05-25 10:51:56,567 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 10:51:57,627 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:57,627 DEBUG --- stdout --- 2024-05-25 10:51:57,627 DEBUG tar: Removing leading `/' from member names 2024-05-25 10:51:57,627 DEBUG --- stderr --- 2024-05-25 10:51:57,627 DEBUG ________________________________________________________________________________ [2024-05-25 10:51:57] login-tree-500 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 10:51:57,628 INFO 2024-05-25 10:51:57,628 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 10:51:57,628 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:57,932 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:57,932 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:57,932 DEBUG --- stdout --- 2024-05-25 10:51:57,932 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:57,932 DEBUG --- stderr --- 2024-05-25 10:51:57,932 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 10:51:57,933 INFO 2024-05-25 10:51:57,933 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 10:51:57,933 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:58,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:58,241 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:58,241 DEBUG --- stdout --- 2024-05-25 10:51:58,241 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:58,241 DEBUG --- stderr --- 2024-05-25 10:51:58,241 DEBUG 2024-05-25 10:51:58,242 INFO 2024-05-25 10:51:58,242 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-500/cmd.txt" ]; then echo "/results/orders/login-tree-500/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-500/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-500/cmd.txt file found" 2024-05-25 10:51:58,242 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 10:51:59,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 10:51:59,046 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:59,046 DEBUG --- stdout --- 2024-05-25 10:51:59,046 DEBUG /results/orders/login-tree-500/cmd.txt file found 2024-05-25 10:51:59,046 DEBUG --- stderr --- 2024-05-25 10:51:59,046 DEBUG 2024-05-25 10:51:59,046 INFO 2024-05-25 10:51:59,046 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 10:51:59,046 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:51:59,359 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:51:59,359 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:51:59,359 DEBUG --- stdout --- 2024-05-25 10:51:59,359 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:51:59,359 DEBUG --- stderr --- 2024-05-25 10:51:59,360 DEBUG 2024-05-25 10:51:59,360 INFO 2024-05-25 10:51:59,360 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-500/cmd.txt 2024-05-25 10:52:00,164 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 10:52:00,165 DEBUG --- stdout --- 2024-05-25 10:52:00,165 DEBUG 4 /results/orders/login-tree-500/cmd.txt 2024-05-25 10:52:00,165 DEBUG --- stderr --- 2024-05-25 10:52:00,166 DEBUG 2024-05-25 10:52:00,166 INFO - Download file (size 4k) 2024-05-25 10:52:00,166 INFO 2024-05-25 10:52:00,166 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 10:52:00,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:00,477 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:00,478 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:00,478 DEBUG --- stdout --- 2024-05-25 10:52:00,478 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:00,478 DEBUG --- stderr --- 2024-05-25 10:52:00,478 DEBUG source : /results/orders/login-tree-500/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/cmd.txt 2024-05-25 10:52:00,478 INFO 2024-05-25 10:52:00,478 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 10:52:00,478 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:00,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:00,841 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:00,841 DEBUG --- stdout --- 2024-05-25 10:52:00,841 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:00,842 DEBUG --- stderr --- 2024-05-25 10:52:00,842 DEBUG 2024-05-25 10:52:00,842 INFO 2024-05-25 10:52:00,842 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-500/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/cmd.txt 2024-05-25 10:52:00,842 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 10:52:01,579 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:01,579 DEBUG --- stdout --- 2024-05-25 10:52:01,579 DEBUG tar: Removing leading `/' from member names 2024-05-25 10:52:01,580 DEBUG --- stderr --- 2024-05-25 10:52:01,580 DEBUG 2024-05-25 10:52:01,580 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/cmd.txt is small (1 lines), printing all lines: 2024-05-25 10:52:01,580 DEBUG ----- output ----- 2024-05-25 10:52:01,580 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-500 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 10:52:01,580 INFO ________________________________________________________________________________ [2024-05-25 10:52:01] login-tree-500 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 10:52:01,581 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/rc.txt is small (1 lines), printing all lines: 2024-05-25 10:52:01,581 DEBUG ----- output ----- 2024-05-25 10:52:01,581 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 10:52:01,581 INFO ________________________________________________________________________________ [2024-05-25 10:52:01] login-tree-500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 10:52:01,582 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/06_login-tree-500/stdout.txt is 10005 lines): 2024-05-25 10:52:01,583 DEBUG ----- output ----- 2024-05-25 10:52:01,583 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 10:52:01,583 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 10:52:01,583 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO ================================================================================ 2024-05-25 10:52:01,583 INFO 2024-05-25 10:49:36 5s elapsed 2024-05-25 10:52:01,583 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,583 INFO > Global (OK=413 KO=0 ) 2024-05-25 10:52:01,583 INFO > restLoginInitiate (OK=208 KO=0 ) 2024-05-25 10:52:01,583 INFO > restLoginUsernamePasswordCallback (OK=205 KO=0 ) 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,583 INFO [- ] 0% 2024-05-25 10:52:01,583 INFO waiting: 1806620 / active: 4 / done: 205 2024-05-25 10:52:01,583 INFO ================================================================================ 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO ================================================================================ 2024-05-25 10:52:01,583 INFO 2024-05-25 10:49:41 10s elapsed 2024-05-25 10:52:01,583 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,583 INFO > Global (OK=1661 KO=0 ) 2024-05-25 10:52:01,583 INFO > restLoginInitiate (OK=833 KO=0 ) 2024-05-25 10:52:01,583 INFO > restLoginUsernamePasswordCallback (OK=828 KO=0 ) 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,583 INFO [- ] 0% 2024-05-25 10:52:01,583 INFO waiting: 1805994 / active: 7 / done: 828 2024-05-25 10:52:01,583 INFO ================================================================================ 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,583 INFO ================================================================================ 2024-05-25 10:52:01,583 INFO 2024-05-25 10:49:46 15s elapsed 2024-05-25 10:52:01,583 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,583 INFO > Global (OK=3743 KO=0 ) 2024-05-25 10:52:01,583 INFO > restLoginInitiate (OK=1875 KO=0 ) 2024-05-25 10:52:01,583 INFO > restLoginUsernamePasswordCallback (OK=1868 KO=0 ) 2024-05-25 10:52:01,583 INFO 2024-05-25 10:52:01,584 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,584 INFO [- ] 0% 2024-05-25 10:52:01,584 INFO waiting: 1804951 / active: 10 / done: 1868 2024-05-25 10:52:01,584 INFO ================================================================================ 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO ================================================================================ 2024-05-25 10:52:01,584 INFO 2024-05-25 10:49:51 20s elapsed 2024-05-25 10:52:01,584 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,584 INFO > Global (OK=6654 KO=0 ) 2024-05-25 10:52:01,584 INFO > restLoginInitiate (OK=3332 KO=0 ) 2024-05-25 10:52:01,584 INFO > restLoginUsernamePasswordCallback (OK=3322 KO=0 ) 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,584 INFO [- ] 0% 2024-05-25 10:52:01,584 INFO waiting: 1803493 / active: 14 / done: 3322 2024-05-25 10:52:01,584 INFO ================================================================================ 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO ================================================================================ 2024-05-25 10:52:01,584 INFO 2024-05-25 10:49:56 25s elapsed 2024-05-25 10:52:01,584 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,584 INFO > Global (OK=10402 KO=0 ) 2024-05-25 10:52:01,584 INFO > restLoginInitiate (OK=5207 KO=0 ) 2024-05-25 10:52:01,584 INFO > restLoginUsernamePasswordCallback (OK=5195 KO=0 ) 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,584 INFO [- ] 0% 2024-05-25 10:52:01,584 INFO waiting: 1801617 / active: 17 / done: 5195 2024-05-25 10:52:01,584 INFO ================================================================================ 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO ================================================================================ 2024-05-25 10:52:01,584 INFO 2024-05-25 10:50:01 30s elapsed 2024-05-25 10:52:01,584 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,584 INFO > Global (OK=14962 KO=0 ) 2024-05-25 10:52:01,584 INFO > restLoginInitiate (OK=7494 KO=0 ) 2024-05-25 10:52:01,584 INFO > restLoginUsernamePasswordCallback (OK=7468 KO=0 ) 2024-05-25 10:52:01,584 INFO 2024-05-25 10:52:01,584 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,584 INFO [- ] 0% 2024-05-25 10:52:01,584 INFO waiting: 1799328 / active: 33 / done: 7468 2024-05-25 10:52:01,585 INFO ================================================================================ 2024-05-25 10:52:01,585 INFO 2024-05-25 10:52:01,585 INFO 2024-05-25 10:52:01,585 INFO ================================================================================ 2024-05-25 10:52:01,585 INFO 2024-05-25 10:50:06 35s elapsed 2024-05-25 10:52:01,585 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,585 INFO > Global (OK=19954 KO=0 ) 2024-05-25 10:52:01,585 INFO > restLoginInitiate (OK=9985 KO=0 ) 2024-05-25 10:52:01,585 INFO > restLoginUsernamePasswordCallback (OK=9969 KO=0 ) 2024-05-25 10:52:01,585 INFO 2024-05-25 10:52:01,585 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,585 INFO [- ] 0% 2024-05-25 10:52:01,585 INFO waiting: 1796839 / active: 21 / done: 9969 2024-05-25 10:52:01,585 INFO ================================================================================ 2024-05-25 10:52:01,585 INFO 2024-05-25 10:52:01,585 INFO 2024-05-25 10:52:01,585 INFO ================================================================================ 2024-05-25 10:52:01,585 INFO 2024-05-25 10:50:11 40s elapsed 2024-05-25 10:52:01,585 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,585 INFO > Global (OK=25126 KO=0 ) 2024-05-25 10:52:01,585 INFO > restLoginInitiate (OK=12574 KO=0 ) 2024-05-25 10:52:01,585 INFO [...] 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,588 INFO [#########################################################################-] 99% 2024-05-25 10:52:01,588 INFO waiting: 9963 / active: 34 / done: 1796832 2024-05-25 10:52:01,588 INFO ================================================================================ 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO ================================================================================ 2024-05-25 10:52:01,588 INFO 2024-05-25 11:49:46 3615s elapsed 2024-05-25 10:52:01,588 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,588 INFO > Global (OK=3598690 KO=1 ) 2024-05-25 10:52:01,588 INFO > restLoginInitiate (OK=1799353 KO=1 ) 2024-05-25 10:52:01,588 INFO > restLoginUsernamePasswordCallback (OK=1799337 KO=0 ) 2024-05-25 10:52:01,588 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 10:52:01,588 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,588 INFO [#########################################################################-] 99% 2024-05-25 10:52:01,588 INFO waiting: 7469 / active: 22 / done: 1799338 2024-05-25 10:52:01,588 INFO ================================================================================ 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO ================================================================================ 2024-05-25 10:52:01,588 INFO 2024-05-25 11:49:51 3620s elapsed 2024-05-25 10:52:01,588 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,588 INFO > Global (OK=3603621 KO=1 ) 2024-05-25 10:52:01,588 INFO > restLoginInitiate (OK=1801827 KO=1 ) 2024-05-25 10:52:01,588 INFO > restLoginUsernamePasswordCallback (OK=1801794 KO=0 ) 2024-05-25 10:52:01,588 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 10:52:01,588 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 10:52:01,588 INFO 2024-05-25 10:52:01,588 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,588 INFO [#########################################################################-] 99% 2024-05-25 10:52:01,589 INFO waiting: 4995 / active: 40 / done: 1801794 2024-05-25 10:52:01,589 INFO ================================================================================ 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO ================================================================================ 2024-05-25 10:52:01,589 INFO 2024-05-25 11:49:56 3625s elapsed 2024-05-25 10:52:01,589 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,589 INFO > Global (OK=3608704 KO=1 ) 2024-05-25 10:52:01,589 INFO > restLoginInitiate (OK=1804369 KO=1 ) 2024-05-25 10:52:01,589 INFO > restLoginUsernamePasswordCallback (OK=1804335 KO=0 ) 2024-05-25 10:52:01,589 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 10:52:01,589 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,589 INFO [#########################################################################-] 99% 2024-05-25 10:52:01,589 INFO waiting: 2453 / active: 40 / done: 1804336 2024-05-25 10:52:01,589 INFO ================================================================================ 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO ================================================================================ 2024-05-25 10:52:01,589 INFO 2024-05-25 11:50:01 3630s elapsed 2024-05-25 10:52:01,589 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,589 INFO > Global (OK=3613628 KO=1 ) 2024-05-25 10:52:01,589 INFO > restLoginInitiate (OK=1806823 KO=1 ) 2024-05-25 10:52:01,589 INFO > restLoginUsernamePasswordCallback (OK=1806805 KO=0 ) 2024-05-25 10:52:01,589 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 10:52:01,589 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,589 INFO [#########################################################################-] 99% 2024-05-25 10:52:01,589 INFO waiting: 0 / active: 23 / done: 1806806 2024-05-25 10:52:01,589 INFO ================================================================================ 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO 2024-05-25 10:52:01,589 INFO ================================================================================ 2024-05-25 10:52:01,589 INFO 2024-05-25 11:50:01 3630s elapsed 2024-05-25 10:52:01,589 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 10:52:01,589 INFO > Global (OK=3613656 KO=1 ) 2024-05-25 10:52:01,589 INFO > restLoginInitiate (OK=1806828 KO=1 ) 2024-05-25 10:52:01,589 INFO > restLoginUsernamePasswordCallback (OK=1806828 KO=0 ) 2024-05-25 10:52:01,589 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 10:52:01,589 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 10:52:01,590 INFO 2024-05-25 10:52:01,590 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 10:52:01,590 INFO [##########################################################################]100% 2024-05-25 10:52:01,590 INFO waiting: 0 / active: 0 / done: 1806829 2024-05-25 10:52:01,590 INFO ================================================================================ 2024-05-25 10:52:01,590 INFO 2024-05-25 10:52:01,590 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 10:52:01,590 INFO 2024-05-25 10:52:01,590 INFO ---------------------- 2024-05-25 10:52:01,590 INFO [25/May/2024 11:50:01] Create tar file login-tree-500.tar.gz 2024-05-25 10:52:01,590 INFO ---------------------- 2024-05-25 10:52:01,590 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 10:52:01,590 INFO RESULT_FOLDER=login-tree-500 2024-05-25 10:52:01,590 INFO SIMULATION_PATH=login-tree-500/loadtest-20240525094930762 2024-05-25 10:52:01,590 INFO PWD=/results/gatling/login-tree-500/loadtest-20240525094930762 2024-05-25 10:52:01,590 INFO [cmd] tar -czvf /results/gatling//login-tree-500.tar.gz --exclude=simulation.log * 2024-05-25 10:52:01,590 INFO /results/gatling 2024-05-25 10:52:01,590 INFO 2024-05-25 10:52:01,590 INFO ---------------------- 2024-05-25 10:52:01,590 INFO [25/May/2024 11:50:01] Update /results/gatling//latest 2024-05-25 10:52:01,590 INFO ---------------------- 2024-05-25 10:52:01,590 INFO 2024-05-25 10:52:01,590 INFO ---------------------- 2024-05-25 10:52:01,590 INFO [25/May/2024 11:50:01] Update /results/gatling//latest.tar.gz 2024-05-25 10:52:01,590 INFO ---------------------- 2024-05-25 10:52:01,590 INFO ________________________________________________________________________________ [2024-05-25 10:52:01] login-tree-500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 10:52:01,591 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-500/stderr.txt is small (0 lines), printing all lines: 2024-05-25 10:52:01,591 DEBUG ----- output ----- 2024-05-25 10:52:01,591 DEBUG 2024-05-25 10:52:01,591 INFO ________________________________________________________________________________ [2024-05-25 10:52:01] login-tree-500 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 10:52:01] login-tree-500 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 10:52:01] login-tree-500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped