--Task-- name: login-tree-600 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-500'] preceding_task: login-tree-500 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 10:52:02] login-tree-600 pre : N/A ________________________________________________________________________________ [2024-05-25 10:52:02] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 10:52:02] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-600/stdout.txt Pod log : /results/orders/login-tree-600 2024-05-25 10:52:02,407 INFO 2024-05-25 10:52:02,407 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:02,407 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:02,716 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:02,716 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:02,716 DEBUG --- stdout --- 2024-05-25 10:52:02,716 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:02,716 DEBUG --- stderr --- 2024-05-25 10:52:02,716 DEBUG ________________________________________________________________________________ [2024-05-25 10:52:02] login-tree-600 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 10:52:02,717 INFO 2024-05-25 10:52:02,717 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 10:52:02,717 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:03,018 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:03,018 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:03,018 DEBUG --- stdout --- 2024-05-25 10:52:03,018 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:03,018 DEBUG --- stderr --- 2024-05-25 10:52:03,018 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-600 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 10:52:03] login-tree-600 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 10:52:03,019 INFO 2024-05-25 10:52:03,019 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:03,019 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:03,329 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:03,329 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:03,329 DEBUG --- stdout --- 2024-05-25 10:52:03,329 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:03,329 DEBUG --- stderr --- 2024-05-25 10:52:03,329 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/order.json { "name": "login-tree-600", "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-600 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-600", "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=600 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 10:52:03] login-tree-600 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 10:52:03,331 INFO 2024-05-25 10:52:03,331 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 10:52:03,331 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 10:52:03,588 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 10:52:03,588 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:03,588 DEBUG --- stdout --- 2024-05-25 10:52:03,588 DEBUG WAITING 2024-05-25 10:52:03,588 DEBUG --- stderr --- 2024-05-25 10:52:03,588 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-05-25 10:52:03] login-tree-600 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 10:52:03,589 INFO 2024-05-25 10:52:03,589 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:03,589 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:03,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:03,895 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:03,895 DEBUG --- stdout --- 2024-05-25 10:52:03,895 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:03,895 DEBUG --- stderr --- 2024-05-25 10:52:03,895 DEBUG 2024-05-25 10:52:03,896 INFO 2024-05-25 10:52:03,896 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/07_login-tree-600/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 10:52:03,896 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 10:52:05,289 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:05,289 DEBUG --- stdout --- 2024-05-25 10:52:05,289 DEBUG 2024-05-25 10:52:05,289 DEBUG --- stderr --- 2024-05-25 10:52:05,289 DEBUG Order has been successfully forwarded to overseer [2024-05-25 10:52:05] - INFO: Check if Gatling simulation started successfully 2024-05-25 10:52:05,290 INFO 2024-05-25 10:52:05,290 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:05,290 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 10:52:05,598 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 10:52:05,598 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:05,598 DEBUG --- stdout --- 2024-05-25 10:52:05,599 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 10:52:05,599 DEBUG --- stderr --- 2024-05-25 10:52:05,599 DEBUG 2024-05-25 10:52:05,599 INFO 2024-05-25 10:52:05,599 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-600/stdout.txt | grep "started..." 2024-05-25 10:52:05,599 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 10:52:06,330 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 10:52:12,065 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 10:52:12,065 INFO [loop_until]: OK (rc = 0) 2024-05-25 10:52:12,065 DEBUG --- stdout --- 2024-05-25 10:52:12,065 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 10:52:12,066 DEBUG --- stderr --- 2024-05-25 10:52:12,066 DEBUG ________________________________________________________________________________ [2024-05-25 10:52:12] login-tree-600 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 01s, 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 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 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 11:54:22] login-tree-600 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 11:54:22,523 INFO 2024-05-25 11:54:22,523 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:22,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:22,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:22,868 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:22,868 DEBUG --- stdout --- 2024-05-25 11:54:22,869 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:22,869 DEBUG --- stderr --- 2024-05-25 11:54:22,869 DEBUG 2024-05-25 11:54:22,869 INFO 2024-05-25 11:54:22,869 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 11:54:22,869 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 11:54:23,694 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:23,694 DEBUG --- stdout --- 2024-05-25 11:54:23,694 DEBUG 2024-05-25 11:54:23,694 DEBUG --- stderr --- 2024-05-25 11:54:23,694 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 11:54:23] login-tree-600 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 11:54:23] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/order.json 2024-05-25 11:54:23,703 INFO 2024-05-25 11:54:23,703 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:23,703 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:24,061 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:24,061 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:24,061 DEBUG --- stdout --- 2024-05-25 11:54:24,062 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:24,062 DEBUG --- stderr --- 2024-05-25 11:54:24,062 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 11:54:24,062 INFO 2024-05-25 11:54:24,062 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:24,062 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:24,365 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:24,365 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:24,365 DEBUG --- stdout --- 2024-05-25 11:54:24,365 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:24,365 DEBUG --- stderr --- 2024-05-25 11:54:24,365 DEBUG 2024-05-25 11:54:24,366 INFO 2024-05-25 11:54:24,366 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-600/rc.txt" ]; then echo "/results/orders/login-tree-600/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-600/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/rc.txt file found" 2024-05-25 11:54:24,366 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 11:54:25,167 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 11:54:25,167 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:25,167 DEBUG --- stdout --- 2024-05-25 11:54:25,168 DEBUG /results/orders/login-tree-600/rc.txt file found 2024-05-25 11:54:25,168 DEBUG --- stderr --- 2024-05-25 11:54:25,168 DEBUG 2024-05-25 11:54:25,168 INFO 2024-05-25 11:54:25,168 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:25,168 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:25,478 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:25,478 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:25,478 DEBUG --- stdout --- 2024-05-25 11:54:25,478 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:25,478 DEBUG --- stderr --- 2024-05-25 11:54:25,479 DEBUG 2024-05-25 11:54:25,479 INFO 2024-05-25 11:54:25,479 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-600/rc.txt 2024-05-25 11:54:26,297 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 11:54:26,298 DEBUG --- stdout --- 2024-05-25 11:54:26,299 DEBUG 4 /results/orders/login-tree-600/rc.txt 2024-05-25 11:54:26,299 DEBUG --- stderr --- 2024-05-25 11:54:26,299 DEBUG 2024-05-25 11:54:26,299 INFO - Download file (size 4k) 2024-05-25 11:54:26,299 INFO 2024-05-25 11:54:26,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 11:54:26,300 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:26,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:26,609 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:26,609 DEBUG --- stdout --- 2024-05-25 11:54:26,609 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:26,609 DEBUG --- stderr --- 2024-05-25 11:54:26,609 DEBUG source : /results/orders/login-tree-600/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/rc.txt 2024-05-25 11:54:26,609 INFO 2024-05-25 11:54:26,609 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:26,609 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:26,923 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:26,923 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:26,923 DEBUG --- stdout --- 2024-05-25 11:54:26,923 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:26,923 DEBUG --- stderr --- 2024-05-25 11:54:26,923 DEBUG 2024-05-25 11:54:26,924 INFO 2024-05-25 11:54:26,924 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-600/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/rc.txt 2024-05-25 11:54:26,924 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 11:54:27,655 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:27,656 DEBUG --- stdout --- 2024-05-25 11:54:27,656 DEBUG tar: Removing leading `/' from member names 2024-05-25 11:54:27,656 DEBUG --- stderr --- 2024-05-25 11:54:27,656 DEBUG 2024-05-25 11:54:27,656 INFO 2024-05-25 11:54:27,656 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:27,656 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:27,973 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:27,973 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:27,973 DEBUG --- stdout --- 2024-05-25 11:54:27,973 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:27,973 DEBUG --- stderr --- 2024-05-25 11:54:27,974 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 11:54:27,974 INFO 2024-05-25 11:54:27,974 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:27,974 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:28,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:28,282 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:28,282 DEBUG --- stdout --- 2024-05-25 11:54:28,282 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:28,282 DEBUG --- stderr --- 2024-05-25 11:54:28,282 DEBUG 2024-05-25 11:54:28,283 INFO 2024-05-25 11:54:28,283 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-600/stderr.txt" ]; then echo "/results/orders/login-tree-600/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-600/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/stderr.txt file found" 2024-05-25 11:54:28,283 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 11:54:29,088 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 11:54:29,088 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:29,089 DEBUG --- stdout --- 2024-05-25 11:54:29,089 DEBUG /results/orders/login-tree-600/stderr.txt file found 2024-05-25 11:54:29,089 DEBUG --- stderr --- 2024-05-25 11:54:29,089 DEBUG 2024-05-25 11:54:29,089 INFO 2024-05-25 11:54:29,089 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:29,089 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:29,395 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:29,395 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:29,395 DEBUG --- stdout --- 2024-05-25 11:54:29,395 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:29,395 DEBUG --- stderr --- 2024-05-25 11:54:29,395 DEBUG 2024-05-25 11:54:29,395 INFO 2024-05-25 11:54:29,395 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-600/stderr.txt 2024-05-25 11:54:30,204 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 11:54:30,204 DEBUG --- stdout --- 2024-05-25 11:54:30,204 DEBUG 0 /results/orders/login-tree-600/stderr.txt 2024-05-25 11:54:30,204 DEBUG --- stderr --- 2024-05-25 11:54:30,204 DEBUG 2024-05-25 11:54:30,204 INFO - Download file (size 0k) 2024-05-25 11:54:30,205 INFO 2024-05-25 11:54:30,205 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:30,205 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:30,517 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:30,517 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:30,517 DEBUG --- stdout --- 2024-05-25 11:54:30,517 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:30,517 DEBUG --- stderr --- 2024-05-25 11:54:30,517 DEBUG source : /results/orders/login-tree-600/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stderr.txt 2024-05-25 11:54:30,518 INFO 2024-05-25 11:54:30,518 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:30,518 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:30,832 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:30,833 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:30,833 DEBUG --- stdout --- 2024-05-25 11:54:30,833 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:30,833 DEBUG --- stderr --- 2024-05-25 11:54:30,833 DEBUG 2024-05-25 11:54:30,833 INFO 2024-05-25 11:54:30,833 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-600/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stderr.txt 2024-05-25 11:54:30,833 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 11:54:31,559 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:31,559 DEBUG --- stdout --- 2024-05-25 11:54:31,559 DEBUG tar: Removing leading `/' from member names 2024-05-25 11:54:31,559 DEBUG --- stderr --- 2024-05-25 11:54:31,559 DEBUG 2024-05-25 11:54:31,560 INFO 2024-05-25 11:54:31,560 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:31,560 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:31,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:31,864 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:31,865 DEBUG --- stdout --- 2024-05-25 11:54:31,865 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:31,865 DEBUG --- stderr --- 2024-05-25 11:54:31,865 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 11:54:31,865 INFO 2024-05-25 11:54:31,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 11:54:31,865 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:32,170 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:32,170 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:32,170 DEBUG --- stdout --- 2024-05-25 11:54:32,170 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:32,170 DEBUG --- stderr --- 2024-05-25 11:54:32,170 DEBUG 2024-05-25 11:54:32,171 INFO 2024-05-25 11:54:32,171 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-600/stdout.txt" ]; then echo "/results/orders/login-tree-600/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-600/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/stdout.txt file found" 2024-05-25 11:54:32,171 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 11:54:32,967 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 11:54:32,967 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:32,967 DEBUG --- stdout --- 2024-05-25 11:54:32,967 DEBUG /results/orders/login-tree-600/stdout.txt file found 2024-05-25 11:54:32,967 DEBUG --- stderr --- 2024-05-25 11:54:32,967 DEBUG 2024-05-25 11:54:32,968 INFO 2024-05-25 11:54:32,968 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:32,968 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:33,277 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:33,277 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:33,277 DEBUG --- stdout --- 2024-05-25 11:54:33,277 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:33,277 DEBUG --- stderr --- 2024-05-25 11:54:33,277 DEBUG 2024-05-25 11:54:33,277 INFO 2024-05-25 11:54:33,278 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-600/stdout.txt 2024-05-25 11:54:34,079 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 11:54:34,079 DEBUG --- stdout --- 2024-05-25 11:54:34,079 DEBUG 652 /results/orders/login-tree-600/stdout.txt 2024-05-25 11:54:34,079 DEBUG --- stderr --- 2024-05-25 11:54:34,079 DEBUG 2024-05-25 11:54:34,079 INFO - Download file (size 652k) 2024-05-25 11:54:34,080 INFO 2024-05-25 11:54:34,080 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:34,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:34,376 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:34,377 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:34,377 DEBUG --- stdout --- 2024-05-25 11:54:34,377 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:34,377 DEBUG --- stderr --- 2024-05-25 11:54:34,377 DEBUG source : /results/orders/login-tree-600/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stdout.txt 2024-05-25 11:54:34,377 INFO 2024-05-25 11:54:34,377 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:34,377 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:34,691 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:34,691 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:34,691 DEBUG --- stdout --- 2024-05-25 11:54:34,691 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:34,692 DEBUG --- stderr --- 2024-05-25 11:54:34,692 DEBUG 2024-05-25 11:54:34,692 INFO 2024-05-25 11:54:34,692 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-600/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stdout.txt 2024-05-25 11:54:34,692 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 11:54:35,751 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:35,752 DEBUG --- stdout --- 2024-05-25 11:54:35,752 DEBUG tar: Removing leading `/' from member names 2024-05-25 11:54:35,752 DEBUG --- stderr --- 2024-05-25 11:54:35,752 DEBUG ________________________________________________________________________________ [2024-05-25 11:54:35] login-tree-600 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 11:54:35,753 INFO 2024-05-25 11:54:35,753 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:35,753 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:36,067 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:36,067 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:36,067 DEBUG --- stdout --- 2024-05-25 11:54:36,067 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:36,067 DEBUG --- stderr --- 2024-05-25 11:54:36,067 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 11:54:36,068 INFO 2024-05-25 11:54:36,068 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:36,068 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:36,378 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:36,378 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:36,378 DEBUG --- stdout --- 2024-05-25 11:54:36,378 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:36,378 DEBUG --- stderr --- 2024-05-25 11:54:36,378 DEBUG 2024-05-25 11:54:36,379 INFO 2024-05-25 11:54:36,379 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-600/cmd.txt" ]; then echo "/results/orders/login-tree-600/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-600/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/cmd.txt file found" 2024-05-25 11:54:36,379 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 11:54:37,181 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 11:54:37,181 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:37,182 DEBUG --- stdout --- 2024-05-25 11:54:37,183 DEBUG /results/orders/login-tree-600/cmd.txt file found 2024-05-25 11:54:37,183 DEBUG --- stderr --- 2024-05-25 11:54:37,183 DEBUG 2024-05-25 11:54:37,183 INFO 2024-05-25 11:54:37,183 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:37,183 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:37,496 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:37,496 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:37,496 DEBUG --- stdout --- 2024-05-25 11:54:37,496 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:37,496 DEBUG --- stderr --- 2024-05-25 11:54:37,496 DEBUG 2024-05-25 11:54:37,496 INFO 2024-05-25 11:54:37,496 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-600/cmd.txt 2024-05-25 11:54:38,388 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 11:54:38,388 DEBUG --- stdout --- 2024-05-25 11:54:38,388 DEBUG 4 /results/orders/login-tree-600/cmd.txt 2024-05-25 11:54:38,388 DEBUG --- stderr --- 2024-05-25 11:54:38,388 DEBUG 2024-05-25 11:54:38,388 INFO - Download file (size 4k) 2024-05-25 11:54:38,389 INFO 2024-05-25 11:54:38,389 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:38,389 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:38,705 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:38,706 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:38,706 DEBUG --- stdout --- 2024-05-25 11:54:38,706 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:38,706 DEBUG --- stderr --- 2024-05-25 11:54:38,706 DEBUG source : /results/orders/login-tree-600/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/cmd.txt 2024-05-25 11:54:38,706 INFO 2024-05-25 11:54:38,706 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 11:54:38,706 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 11:54:39,024 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 11:54:39,024 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:39,024 DEBUG --- stdout --- 2024-05-25 11:54:39,024 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 11:54:39,024 DEBUG --- stderr --- 2024-05-25 11:54:39,024 DEBUG 2024-05-25 11:54:39,024 INFO 2024-05-25 11:54:39,024 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-600/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/cmd.txt 2024-05-25 11:54:39,024 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 11:54:39,754 INFO [loop_until]: OK (rc = 0) 2024-05-25 11:54:39,755 DEBUG --- stdout --- 2024-05-25 11:54:39,755 DEBUG tar: Removing leading `/' from member names 2024-05-25 11:54:39,755 DEBUG --- stderr --- 2024-05-25 11:54:39,755 DEBUG 2024-05-25 11:54:39,755 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/cmd.txt is small (1 lines), printing all lines: 2024-05-25 11:54:39,755 DEBUG ----- output ----- 2024-05-25 11:54:39,755 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-600 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 11:54:39,755 INFO ________________________________________________________________________________ [2024-05-25 11:54:39] login-tree-600 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 11:54:39,756 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/rc.txt is small (1 lines), printing all lines: 2024-05-25 11:54:39,756 DEBUG ----- output ----- 2024-05-25 11:54:39,756 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 11:54:39,756 INFO ________________________________________________________________________________ [2024-05-25 11:54:39] login-tree-600 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 11:54:39,758 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/07_login-tree-600/stdout.txt is 10556 lines): 2024-05-25 11:54:39,758 DEBUG ----- output ----- 2024-05-25 11:54:39,758 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 11:54:39,758 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 11:54:39,758 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 11:54:39,758 INFO 2024-05-25 11:54:39,758 INFO ================================================================================ 2024-05-25 11:54:39,758 INFO 2024-05-25 11:52:14 5s elapsed 2024-05-25 11:54:39,758 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,758 INFO > Global (OK=497 KO=0 ) 2024-05-25 11:54:39,758 INFO > restLoginInitiate (OK=250 KO=0 ) 2024-05-25 11:54:39,758 INFO > restLoginUsernamePasswordCallback (OK=247 KO=0 ) 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,759 INFO [- ] 0% 2024-05-25 11:54:39,759 INFO waiting: 2167458 / active: 4 / done: 247 2024-05-25 11:54:39,759 INFO ================================================================================ 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ================================================================================ 2024-05-25 11:54:39,759 INFO 2024-05-25 11:52:19 10s elapsed 2024-05-25 11:54:39,759 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,759 INFO > Global (OK=1993 KO=0 ) 2024-05-25 11:54:39,759 INFO > restLoginInitiate (OK=1000 KO=0 ) 2024-05-25 11:54:39,759 INFO > restLoginUsernamePasswordCallback (OK=993 KO=0 ) 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,759 INFO [- ] 0% 2024-05-25 11:54:39,759 INFO waiting: 2166707 / active: 9 / done: 993 2024-05-25 11:54:39,759 INFO ================================================================================ 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ================================================================================ 2024-05-25 11:54:39,759 INFO 2024-05-25 11:52:24 15s elapsed 2024-05-25 11:54:39,759 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,759 INFO > Global (OK=4494 KO=0 ) 2024-05-25 11:54:39,759 INFO > restLoginInitiate (OK=2253 KO=0 ) 2024-05-25 11:54:39,759 INFO > restLoginUsernamePasswordCallback (OK=2241 KO=0 ) 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,759 INFO [- ] 0% 2024-05-25 11:54:39,759 INFO waiting: 2165453 / active: 15 / done: 2241 2024-05-25 11:54:39,759 INFO ================================================================================ 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ================================================================================ 2024-05-25 11:54:39,759 INFO 2024-05-25 11:52:29 20s elapsed 2024-05-25 11:54:39,759 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,759 INFO > Global (OK=7988 KO=0 ) 2024-05-25 11:54:39,759 INFO > restLoginInitiate (OK=3999 KO=0 ) 2024-05-25 11:54:39,759 INFO > restLoginUsernamePasswordCallback (OK=3989 KO=0 ) 2024-05-25 11:54:39,759 INFO 2024-05-25 11:54:39,759 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,760 INFO [- ] 0% 2024-05-25 11:54:39,760 INFO waiting: 2163705 / active: 15 / done: 3989 2024-05-25 11:54:39,760 INFO ================================================================================ 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO ================================================================================ 2024-05-25 11:54:39,760 INFO 2024-05-25 11:52:34 25s elapsed 2024-05-25 11:54:39,760 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,760 INFO > Global (OK=12483 KO=0 ) 2024-05-25 11:54:39,760 INFO > restLoginInitiate (OK=6248 KO=0 ) 2024-05-25 11:54:39,760 INFO > restLoginUsernamePasswordCallback (OK=6235 KO=0 ) 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,760 INFO [- ] 0% 2024-05-25 11:54:39,760 INFO waiting: 2161454 / active: 20 / done: 6235 2024-05-25 11:54:39,760 INFO ================================================================================ 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO ================================================================================ 2024-05-25 11:54:39,760 INFO 2024-05-25 11:52:39 30s elapsed 2024-05-25 11:54:39,760 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,760 INFO > Global (OK=17968 KO=0 ) 2024-05-25 11:54:39,760 INFO > restLoginInitiate (OK=8993 KO=0 ) 2024-05-25 11:54:39,760 INFO > restLoginUsernamePasswordCallback (OK=8975 KO=0 ) 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,760 INFO [- ] 0% 2024-05-25 11:54:39,760 INFO waiting: 2158701 / active: 33 / done: 8975 2024-05-25 11:54:39,760 INFO ================================================================================ 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO ================================================================================ 2024-05-25 11:54:39,760 INFO 2024-05-25 11:52:44 35s elapsed 2024-05-25 11:54:39,760 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,760 INFO > Global (OK=24013 KO=0 ) 2024-05-25 11:54:39,760 INFO > restLoginInitiate (OK=12015 KO=0 ) 2024-05-25 11:54:39,760 INFO > restLoginUsernamePasswordCallback (OK=11998 KO=0 ) 2024-05-25 11:54:39,760 INFO 2024-05-25 11:54:39,760 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,760 INFO [- ] 0% 2024-05-25 11:54:39,760 INFO waiting: 2155689 / active: 22 / done: 11998 2024-05-25 11:54:39,761 INFO ================================================================================ 2024-05-25 11:54:39,761 INFO 2024-05-25 11:54:39,761 INFO 2024-05-25 11:54:39,761 INFO ================================================================================ 2024-05-25 11:54:39,761 INFO 2024-05-25 11:52:49 40s elapsed 2024-05-25 11:54:39,761 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,761 INFO > Global (OK=30178 KO=0 ) 2024-05-25 11:54:39,761 INFO > restLoginInitiate (OK=15099 KO=0 ) 2024-05-25 11:54:39,761 INFO [...] 2024-05-25 11:54:39,764 INFO 2024-05-25 11:54:39,764 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,764 INFO [#########################################################################-] 99% 2024-05-25 11:54:39,764 INFO waiting: 12177 / active: 46 / done: 2155486 2024-05-25 11:54:39,764 INFO ================================================================================ 2024-05-25 11:54:39,764 INFO 2024-05-25 11:54:39,764 INFO 2024-05-25 11:54:39,764 INFO ================================================================================ 2024-05-25 11:54:39,764 INFO 2024-05-25 12:52:24 3615s elapsed 2024-05-25 11:54:39,764 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,764 INFO > Global (OK=4316994 KO=3 ) 2024-05-25 11:54:39,764 INFO > restLoginInitiate (OK=2158509 KO=2 ) 2024-05-25 11:54:39,764 INFO > restLoginUsernamePasswordCallback (OK=2158485 KO=1 ) 2024-05-25 11:54:39,764 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 11:54:39,764 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 11:54:39,764 INFO 2024-05-25 11:54:39,764 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,764 INFO [#########################################################################-] 99% 2024-05-25 11:54:39,764 INFO waiting: 9188 / active: 33 / done: 2158488 2024-05-25 11:54:39,764 INFO ================================================================================ 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO ================================================================================ 2024-05-25 11:54:39,765 INFO 2024-05-25 12:52:29 3620s elapsed 2024-05-25 11:54:39,765 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,765 INFO > Global (OK=4323003 KO=3 ) 2024-05-25 11:54:39,765 INFO > restLoginInitiate (OK=2161515 KO=2 ) 2024-05-25 11:54:39,765 INFO > restLoginUsernamePasswordCallback (OK=2161488 KO=1 ) 2024-05-25 11:54:39,765 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 11:54:39,765 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,765 INFO [#########################################################################-] 99% 2024-05-25 11:54:39,765 INFO waiting: 6180 / active: 38 / done: 2161491 2024-05-25 11:54:39,765 INFO ================================================================================ 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO ================================================================================ 2024-05-25 11:54:39,765 INFO 2024-05-25 12:52:34 3625s elapsed 2024-05-25 11:54:39,765 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,765 INFO > Global (OK=4329089 KO=3 ) 2024-05-25 11:54:39,765 INFO > restLoginInitiate (OK=2164558 KO=2 ) 2024-05-25 11:54:39,765 INFO > restLoginUsernamePasswordCallback (OK=2164531 KO=1 ) 2024-05-25 11:54:39,765 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 11:54:39,765 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,765 INFO [#########################################################################-] 99% 2024-05-25 11:54:39,765 INFO waiting: 3137 / active: 39 / done: 2164533 2024-05-25 11:54:39,765 INFO ================================================================================ 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO 2024-05-25 11:54:39,765 INFO ================================================================================ 2024-05-25 11:54:39,765 INFO 2024-05-25 12:52:39 3630s elapsed 2024-05-25 11:54:39,765 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,765 INFO > Global (OK=4335382 KO=3 ) 2024-05-25 11:54:39,766 INFO > restLoginInitiate (OK=2167703 KO=2 ) 2024-05-25 11:54:39,766 INFO > restLoginUsernamePasswordCallback (OK=2167679 KO=1 ) 2024-05-25 11:54:39,766 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 11:54:39,766 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 11:54:39,766 INFO 2024-05-25 11:54:39,766 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,766 INFO [#########################################################################-] 99% 2024-05-25 11:54:39,766 INFO waiting: 0 / active: 28 / done: 2167681 2024-05-25 11:54:39,766 INFO ================================================================================ 2024-05-25 11:54:39,766 INFO 2024-05-25 11:54:39,766 INFO 2024-05-25 11:54:39,766 INFO ================================================================================ 2024-05-25 11:54:39,766 INFO 2024-05-25 12:52:39 3630s elapsed 2024-05-25 11:54:39,766 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 11:54:39,766 INFO > Global (OK=4335413 KO=3 ) 2024-05-25 11:54:39,766 INFO > restLoginInitiate (OK=2167707 KO=2 ) 2024-05-25 11:54:39,766 INFO > restLoginUsernamePasswordCallback (OK=2167706 KO=1 ) 2024-05-25 11:54:39,766 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 11:54:39,766 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 11:54:39,766 INFO 2024-05-25 11:54:39,766 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 11:54:39,766 INFO [##########################################################################]100% 2024-05-25 11:54:39,766 INFO waiting: 0 / active: 0 / done: 2167709 2024-05-25 11:54:39,766 INFO ================================================================================ 2024-05-25 11:54:39,766 INFO 2024-05-25 11:54:39,766 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 11:54:39,766 INFO 2024-05-25 11:54:39,766 INFO ---------------------- 2024-05-25 11:54:39,766 INFO [25/May/2024 12:52:39] Create tar file login-tree-600.tar.gz 2024-05-25 11:54:39,766 INFO ---------------------- 2024-05-25 11:54:39,766 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 11:54:39,766 INFO RESULT_FOLDER=login-tree-600 2024-05-25 11:54:39,766 INFO SIMULATION_PATH=login-tree-600/loadtest-20240525105208881 2024-05-25 11:54:39,766 INFO PWD=/results/gatling/login-tree-600/loadtest-20240525105208881 2024-05-25 11:54:39,766 INFO [cmd] tar -czvf /results/gatling//login-tree-600.tar.gz --exclude=simulation.log * 2024-05-25 11:54:39,766 INFO /results/gatling 2024-05-25 11:54:39,767 INFO 2024-05-25 11:54:39,767 INFO ---------------------- 2024-05-25 11:54:39,767 INFO [25/May/2024 12:52:39] Update /results/gatling//latest 2024-05-25 11:54:39,767 INFO ---------------------- 2024-05-25 11:54:39,767 INFO 2024-05-25 11:54:39,767 INFO ---------------------- 2024-05-25 11:54:39,767 INFO [25/May/2024 12:52:39] Update /results/gatling//latest.tar.gz 2024-05-25 11:54:39,767 INFO ---------------------- 2024-05-25 11:54:39,767 INFO ________________________________________________________________________________ [2024-05-25 11:54:39] login-tree-600 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 11:54:39,767 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stderr.txt is small (0 lines), printing all lines: 2024-05-25 11:54:39,767 DEBUG ----- output ----- 2024-05-25 11:54:39,767 DEBUG 2024-05-25 11:54:39,767 INFO ________________________________________________________________________________ [2024-05-25 11:54:39] login-tree-600 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 11:54:39] login-tree-600 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 11:54:39] login-tree-600 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped