--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 01:07:04] login-tree-500 pre : N/A ________________________________________________________________________________ [2024-05-25 01:07:04] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 01:07:04] - 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 01:07:04,724 INFO 2024-05-25 01:07:04,725 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 01:07:04,725 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 01:07:05,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 01:07:05,028 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:05,028 DEBUG --- stdout --- 2024-05-25 01:07:05,028 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 01:07:05,028 DEBUG --- stderr --- 2024-05-25 01:07:05,028 DEBUG ________________________________________________________________________________ [2024-05-25 01:07:05] login-tree-500 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 01:07:05,029 INFO 2024-05-25 01:07:05,029 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 01:07:05,029 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 01:07:05,328 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 01:07:05,328 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:05,328 DEBUG --- stdout --- 2024-05-25 01:07:05,328 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 01:07:05,328 DEBUG --- stderr --- 2024-05-25 01:07:05,328 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 01:07:05] login-tree-500 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 01:07:05,329 INFO 2024-05-25 01:07:05,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 01:07:05,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 01:07:05,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 01:07:05,632 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:05,632 DEBUG --- stdout --- 2024-05-25 01:07:05,633 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 01:07:05,633 DEBUG --- stderr --- 2024-05-25 01:07:05,633 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 01:07:05] 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 01:07:05,635 INFO 2024-05-25 01:07:05,635 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 01:07:05,635 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 01:07:05,876 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 01:07:05,877 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:05,877 DEBUG --- stdout --- 2024-05-25 01:07:05,877 DEBUG WAITING 2024-05-25 01:07:05,877 DEBUG --- stderr --- 2024-05-25 01:07:05,877 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 01:07:05] login-tree-500 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 01:07:05,878 INFO 2024-05-25 01:07:05,878 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 01:07:05,878 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 01:07:06,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 01:07:06,190 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:06,190 DEBUG --- stdout --- 2024-05-25 01:07:06,190 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 01:07:06,190 DEBUG --- stderr --- 2024-05-25 01:07:06,190 DEBUG 2024-05-25 01:07:06,191 INFO 2024-05-25 01:07:06,191 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 01:07:06,191 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 01:07:07,547 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:07,547 DEBUG --- stdout --- 2024-05-25 01:07:07,547 DEBUG 2024-05-25 01:07:07,547 DEBUG --- stderr --- 2024-05-25 01:07:07,547 DEBUG Order has been successfully forwarded to overseer [2024-05-25 01:07:07] - INFO: Check if Gatling simulation started successfully 2024-05-25 01:07:07,548 INFO 2024-05-25 01:07:07,548 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 01:07:07,548 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 01:07:07,947 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 01:07:07,951 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:07,951 DEBUG --- stdout --- 2024-05-25 01:07:07,951 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 01:07:07,951 DEBUG --- stderr --- 2024-05-25 01:07:07,951 DEBUG 2024-05-25 01:07:07,952 INFO 2024-05-25 01:07:07,952 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 01:07:07,952 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 01:07:08,685 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 01:07:14,424 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 01:07:14,424 INFO [loop_until]: OK (rc = 0) 2024-05-25 01:07:14,424 DEBUG --- stdout --- 2024-05-25 01:07:14,424 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 01:07:14,424 DEBUG --- stderr --- 2024-05-25 01:07:14,424 DEBUG ________________________________________________________________________________ [2024-05-25 01:07:14] 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 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-05-25 02:09:24] login-tree-500 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 02:09:24,972 INFO 2024-05-25 02:09:24,973 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 02:09:24,973 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:25,358 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:25,359 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:25,359 DEBUG --- stdout --- 2024-05-25 02:09:25,359 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:25,359 DEBUG --- stderr --- 2024-05-25 02:09:25,359 DEBUG 2024-05-25 02:09:25,359 INFO 2024-05-25 02:09:25,359 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 02:09:25,359 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 02:09:26,162 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:26,163 DEBUG --- stdout --- 2024-05-25 02:09:26,163 DEBUG 2024-05-25 02:09:26,163 DEBUG --- stderr --- 2024-05-25 02:09:26,163 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 02:09:26] login-tree-500 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 02:09:26] - 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 02:09:26,164 INFO 2024-05-25 02:09:26,164 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 02:09:26,164 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:26,471 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:26,471 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:26,471 DEBUG --- stdout --- 2024-05-25 02:09:26,471 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:26,471 DEBUG --- stderr --- 2024-05-25 02:09:26,471 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 02:09:26,472 INFO 2024-05-25 02:09:26,472 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 02:09:26,472 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:26,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:26,778 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:26,778 DEBUG --- stdout --- 2024-05-25 02:09:26,778 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:26,778 DEBUG --- stderr --- 2024-05-25 02:09:26,778 DEBUG 2024-05-25 02:09:26,779 INFO 2024-05-25 02:09:26,779 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 02:09:26,779 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 02:09:27,586 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 02:09:27,586 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:27,586 DEBUG --- stdout --- 2024-05-25 02:09:27,586 DEBUG /results/orders/login-tree-500/rc.txt file found 2024-05-25 02:09:27,586 DEBUG --- stderr --- 2024-05-25 02:09:27,586 DEBUG 2024-05-25 02:09:27,586 INFO 2024-05-25 02:09:27,586 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 02:09:27,586 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:27,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:27,896 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:27,896 DEBUG --- stdout --- 2024-05-25 02:09:27,896 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:27,896 DEBUG --- stderr --- 2024-05-25 02:09:27,896 DEBUG 2024-05-25 02:09:27,896 INFO 2024-05-25 02:09:27,897 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 02:09:28,704 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 02:09:28,705 DEBUG --- stdout --- 2024-05-25 02:09:28,705 DEBUG 4 /results/orders/login-tree-500/rc.txt 2024-05-25 02:09:28,705 DEBUG --- stderr --- 2024-05-25 02:09:28,705 DEBUG 2024-05-25 02:09:28,705 INFO - Download file (size 4k) 2024-05-25 02:09:28,705 INFO 2024-05-25 02:09:28,705 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 02:09:28,705 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:29,016 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:29,017 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:29,017 DEBUG --- stdout --- 2024-05-25 02:09:29,017 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:29,017 DEBUG --- stderr --- 2024-05-25 02:09:29,017 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 02:09:29,017 INFO 2024-05-25 02:09:29,017 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 02:09:29,017 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:29,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:29,323 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:29,323 DEBUG --- stdout --- 2024-05-25 02:09:29,323 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:29,323 DEBUG --- stderr --- 2024-05-25 02:09:29,323 DEBUG 2024-05-25 02:09:29,324 INFO 2024-05-25 02:09:29,324 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 02:09:29,324 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 02:09:30,058 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:30,058 DEBUG --- stdout --- 2024-05-25 02:09:30,058 DEBUG tar: Removing leading `/' from member names 2024-05-25 02:09:30,058 DEBUG --- stderr --- 2024-05-25 02:09:30,058 DEBUG 2024-05-25 02:09:30,059 INFO 2024-05-25 02:09:30,059 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 02:09:30,059 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:30,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:30,363 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:30,363 DEBUG --- stdout --- 2024-05-25 02:09:30,363 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:30,363 DEBUG --- stderr --- 2024-05-25 02:09:30,363 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 02:09:30,363 INFO 2024-05-25 02:09:30,363 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 02:09:30,363 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:30,665 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:30,665 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:30,665 DEBUG --- stdout --- 2024-05-25 02:09:30,665 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:30,665 DEBUG --- stderr --- 2024-05-25 02:09:30,665 DEBUG 2024-05-25 02:09:30,666 INFO 2024-05-25 02:09:30,666 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 02:09:30,666 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 02:09:31,463 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 02:09:31,464 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:31,464 DEBUG --- stdout --- 2024-05-25 02:09:31,464 DEBUG /results/orders/login-tree-500/stderr.txt file found 2024-05-25 02:09:31,464 DEBUG --- stderr --- 2024-05-25 02:09:31,464 DEBUG 2024-05-25 02:09:31,464 INFO 2024-05-25 02:09:31,464 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 02:09:31,464 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:31,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:31,767 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:31,767 DEBUG --- stdout --- 2024-05-25 02:09:31,767 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:31,767 DEBUG --- stderr --- 2024-05-25 02:09:31,767 DEBUG 2024-05-25 02:09:31,767 INFO 2024-05-25 02:09:31,767 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 02:09:32,567 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 02:09:32,567 DEBUG --- stdout --- 2024-05-25 02:09:32,567 DEBUG 0 /results/orders/login-tree-500/stderr.txt 2024-05-25 02:09:32,567 DEBUG --- stderr --- 2024-05-25 02:09:32,567 DEBUG 2024-05-25 02:09:32,567 INFO - Download file (size 0k) 2024-05-25 02:09:32,567 INFO 2024-05-25 02:09:32,568 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 02:09:32,568 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:32,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:32,868 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:32,868 DEBUG --- stdout --- 2024-05-25 02:09:32,868 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:32,869 DEBUG --- stderr --- 2024-05-25 02:09:32,869 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 02:09:32,869 INFO 2024-05-25 02:09:32,869 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 02:09:32,869 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:33,175 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:33,175 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:33,175 DEBUG --- stdout --- 2024-05-25 02:09:33,175 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:33,175 DEBUG --- stderr --- 2024-05-25 02:09:33,175 DEBUG 2024-05-25 02:09:33,176 INFO 2024-05-25 02:09:33,176 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 02:09:33,176 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 02:09:33,897 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:33,898 DEBUG --- stdout --- 2024-05-25 02:09:33,898 DEBUG tar: Removing leading `/' from member names 2024-05-25 02:09:33,898 DEBUG --- stderr --- 2024-05-25 02:09:33,898 DEBUG 2024-05-25 02:09:33,898 INFO 2024-05-25 02:09:33,898 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 02:09:33,899 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:34,205 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:34,205 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:34,205 DEBUG --- stdout --- 2024-05-25 02:09:34,205 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:34,205 DEBUG --- stderr --- 2024-05-25 02:09:34,205 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 02:09:34,206 INFO 2024-05-25 02:09:34,206 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 02:09:34,206 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:34,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:34,516 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:34,516 DEBUG --- stdout --- 2024-05-25 02:09:34,516 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:34,516 DEBUG --- stderr --- 2024-05-25 02:09:34,516 DEBUG 2024-05-25 02:09:34,517 INFO 2024-05-25 02:09:34,517 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 02:09:34,517 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 02:09:35,315 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 02:09:35,315 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:35,315 DEBUG --- stdout --- 2024-05-25 02:09:35,315 DEBUG /results/orders/login-tree-500/stdout.txt file found 2024-05-25 02:09:35,315 DEBUG --- stderr --- 2024-05-25 02:09:35,315 DEBUG 2024-05-25 02:09:35,315 INFO 2024-05-25 02:09:35,316 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 02:09:35,316 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:35,623 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:35,623 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:35,623 DEBUG --- stdout --- 2024-05-25 02:09:35,623 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:35,623 DEBUG --- stderr --- 2024-05-25 02:09:35,623 DEBUG 2024-05-25 02:09:35,623 INFO 2024-05-25 02:09:35,624 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 02:09:36,425 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 02:09:36,425 DEBUG --- stdout --- 2024-05-25 02:09:36,425 DEBUG 576 /results/orders/login-tree-500/stdout.txt 2024-05-25 02:09:36,426 DEBUG --- stderr --- 2024-05-25 02:09:36,426 DEBUG 2024-05-25 02:09:36,426 INFO - Download file (size 576k) 2024-05-25 02:09:36,426 INFO 2024-05-25 02:09:36,426 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 02:09:36,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:36,735 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:36,735 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:36,735 DEBUG --- stdout --- 2024-05-25 02:09:36,736 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:36,736 DEBUG --- stderr --- 2024-05-25 02:09:36,736 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 02:09:36,736 INFO 2024-05-25 02:09:36,736 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 02:09:36,736 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:37,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:37,041 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:37,041 DEBUG --- stdout --- 2024-05-25 02:09:37,041 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:37,041 DEBUG --- stderr --- 2024-05-25 02:09:37,041 DEBUG 2024-05-25 02:09:37,042 INFO 2024-05-25 02:09:37,042 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 02:09:37,042 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 02:09:38,103 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:38,103 DEBUG --- stdout --- 2024-05-25 02:09:38,103 DEBUG tar: Removing leading `/' from member names 2024-05-25 02:09:38,103 DEBUG --- stderr --- 2024-05-25 02:09:38,103 DEBUG ________________________________________________________________________________ [2024-05-25 02:09:38] login-tree-500 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 02:09:38,104 INFO 2024-05-25 02:09:38,104 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 02:09:38,104 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:38,414 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:38,415 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:38,415 DEBUG --- stdout --- 2024-05-25 02:09:38,415 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:38,415 DEBUG --- stderr --- 2024-05-25 02:09:38,415 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 02:09:38,415 INFO 2024-05-25 02:09:38,415 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 02:09:38,415 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:38,767 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:38,768 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:38,768 DEBUG --- stdout --- 2024-05-25 02:09:38,768 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:38,768 DEBUG --- stderr --- 2024-05-25 02:09:38,768 DEBUG 2024-05-25 02:09:38,768 INFO 2024-05-25 02:09:38,768 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 02:09:38,768 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 02:09:39,608 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 02:09:39,608 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:39,608 DEBUG --- stdout --- 2024-05-25 02:09:39,608 DEBUG /results/orders/login-tree-500/cmd.txt file found 2024-05-25 02:09:39,608 DEBUG --- stderr --- 2024-05-25 02:09:39,608 DEBUG 2024-05-25 02:09:39,608 INFO 2024-05-25 02:09:39,608 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 02:09:39,608 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:39,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:39,916 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:39,916 DEBUG --- stdout --- 2024-05-25 02:09:39,916 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:39,916 DEBUG --- stderr --- 2024-05-25 02:09:39,917 DEBUG 2024-05-25 02:09:39,917 INFO 2024-05-25 02:09:39,917 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 02:09:40,722 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 02:09:40,722 DEBUG --- stdout --- 2024-05-25 02:09:40,722 DEBUG 4 /results/orders/login-tree-500/cmd.txt 2024-05-25 02:09:40,722 DEBUG --- stderr --- 2024-05-25 02:09:40,722 DEBUG 2024-05-25 02:09:40,722 INFO - Download file (size 4k) 2024-05-25 02:09:40,723 INFO 2024-05-25 02:09:40,723 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 02:09:40,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:41,031 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:41,031 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:41,031 DEBUG --- stdout --- 2024-05-25 02:09:41,031 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:41,031 DEBUG --- stderr --- 2024-05-25 02:09:41,031 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 02:09:41,031 INFO 2024-05-25 02:09:41,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 02:09:41,032 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:41,340 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:41,340 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:41,340 DEBUG --- stdout --- 2024-05-25 02:09:41,340 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:41,340 DEBUG --- stderr --- 2024-05-25 02:09:41,340 DEBUG 2024-05-25 02:09:41,341 INFO 2024-05-25 02:09:41,341 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 02:09:41,341 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 02:09:42,065 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:42,065 DEBUG --- stdout --- 2024-05-25 02:09:42,065 DEBUG tar: Removing leading `/' from member names 2024-05-25 02:09:42,066 DEBUG --- stderr --- 2024-05-25 02:09:42,066 DEBUG 2024-05-25 02:09:42,066 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 02:09:42,066 DEBUG ----- output ----- 2024-05-25 02:09:42,066 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 02:09:42,066 INFO ________________________________________________________________________________ [2024-05-25 02:09:42] login-tree-500 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 02:09:42,066 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 02:09:42,066 DEBUG ----- output ----- 2024-05-25 02:09:42,067 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 02:09:42,067 INFO ________________________________________________________________________________ [2024-05-25 02:09:42] login-tree-500 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 02:09:42,068 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 9586 lines): 2024-05-25 02:09:42,068 DEBUG ----- output ----- 2024-05-25 02:09:42,068 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 02:09:42,068 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 02:09:42,068 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 02:09:42,068 INFO 2024-05-25 02:09:42,068 INFO ================================================================================ 2024-05-25 02:09:42,068 INFO 2024-05-25 02:07:16 5s elapsed 2024-05-25 02:09:42,068 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,068 INFO > Global (OK=416 KO=0 ) 2024-05-25 02:09:42,068 INFO > restLoginInitiate (OK=209 KO=0 ) 2024-05-25 02:09:42,068 INFO > restLoginUsernamePasswordCallback (OK=207 KO=0 ) 2024-05-25 02:09:42,068 INFO 2024-05-25 02:09:42,069 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,069 INFO [- ] 0% 2024-05-25 02:09:42,069 INFO waiting: 1804767 / active: 3 / done: 207 2024-05-25 02:09:42,069 INFO ================================================================================ 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO ================================================================================ 2024-05-25 02:09:42,069 INFO 2024-05-25 02:07:21 10s elapsed 2024-05-25 02:09:42,069 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,069 INFO > Global (OK=1666 KO=0 ) 2024-05-25 02:09:42,069 INFO > restLoginInitiate (OK=835 KO=0 ) 2024-05-25 02:09:42,069 INFO > restLoginUsernamePasswordCallback (OK=831 KO=0 ) 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,069 INFO [- ] 0% 2024-05-25 02:09:42,069 INFO waiting: 1804140 / active: 6 / done: 831 2024-05-25 02:09:42,069 INFO ================================================================================ 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO ================================================================================ 2024-05-25 02:09:42,069 INFO 2024-05-25 02:07:26 15s elapsed 2024-05-25 02:09:42,069 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,069 INFO > Global (OK=3744 KO=0 ) 2024-05-25 02:09:42,069 INFO > restLoginInitiate (OK=1875 KO=0 ) 2024-05-25 02:09:42,069 INFO > restLoginUsernamePasswordCallback (OK=1869 KO=0 ) 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,069 INFO [- ] 0% 2024-05-25 02:09:42,069 INFO waiting: 1803099 / active: 9 / done: 1869 2024-05-25 02:09:42,069 INFO ================================================================================ 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO ================================================================================ 2024-05-25 02:09:42,069 INFO 2024-05-25 02:07:31 20s elapsed 2024-05-25 02:09:42,069 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,069 INFO > Global (OK=6665 KO=0 ) 2024-05-25 02:09:42,069 INFO > restLoginInitiate (OK=3337 KO=0 ) 2024-05-25 02:09:42,069 INFO > restLoginUsernamePasswordCallback (OK=3328 KO=0 ) 2024-05-25 02:09:42,069 INFO 2024-05-25 02:09:42,069 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,069 INFO [- ] 0% 2024-05-25 02:09:42,069 INFO waiting: 1801637 / active: 12 / done: 3328 2024-05-25 02:09:42,070 INFO ================================================================================ 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO ================================================================================ 2024-05-25 02:09:42,070 INFO 2024-05-25 02:07:36 25s elapsed 2024-05-25 02:09:42,070 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,070 INFO > Global (OK=10404 KO=0 ) 2024-05-25 02:09:42,070 INFO > restLoginInitiate (OK=5208 KO=0 ) 2024-05-25 02:09:42,070 INFO > restLoginUsernamePasswordCallback (OK=5196 KO=0 ) 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,070 INFO [- ] 0% 2024-05-25 02:09:42,070 INFO waiting: 1799765 / active: 16 / done: 5196 2024-05-25 02:09:42,070 INFO ================================================================================ 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO ================================================================================ 2024-05-25 02:09:42,070 INFO 2024-05-25 02:07:41 30s elapsed 2024-05-25 02:09:42,070 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,070 INFO > Global (OK=14974 KO=0 ) 2024-05-25 02:09:42,070 INFO > restLoginInitiate (OK=7495 KO=0 ) 2024-05-25 02:09:42,070 INFO > restLoginUsernamePasswordCallback (OK=7479 KO=0 ) 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,070 INFO [- ] 0% 2024-05-25 02:09:42,070 INFO waiting: 1797474 / active: 24 / done: 7479 2024-05-25 02:09:42,070 INFO ================================================================================ 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO ================================================================================ 2024-05-25 02:09:42,070 INFO 2024-05-25 02:07:46 35s elapsed 2024-05-25 02:09:42,070 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,070 INFO > Global (OK=19952 KO=0 ) 2024-05-25 02:09:42,070 INFO > restLoginInitiate (OK=9984 KO=0 ) 2024-05-25 02:09:42,070 INFO > restLoginUsernamePasswordCallback (OK=9968 KO=0 ) 2024-05-25 02:09:42,070 INFO 2024-05-25 02:09:42,070 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,070 INFO [- ] 0% 2024-05-25 02:09:42,070 INFO waiting: 1794990 / active: 19 / done: 9968 2024-05-25 02:09:42,071 INFO ================================================================================ 2024-05-25 02:09:42,071 INFO 2024-05-25 02:09:42,071 INFO 2024-05-25 02:09:42,071 INFO ================================================================================ 2024-05-25 02:09:42,071 INFO 2024-05-25 02:07:51 40s elapsed 2024-05-25 02:09:42,071 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,071 INFO > Global (OK=24945 KO=0 ) 2024-05-25 02:09:42,071 INFO > restLoginInitiate (OK=12484 KO=0 ) 2024-05-25 02:09:42,071 INFO [...] 2024-05-25 02:09:42,073 INFO 2024-05-25 02:09:42,073 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,073 INFO [#########################################################################-] 99% 2024-05-25 02:09:42,073 INFO waiting: 9992 / active: 21 / done: 1794964 2024-05-25 02:09:42,073 INFO ================================================================================ 2024-05-25 02:09:42,073 INFO 2024-05-25 02:09:42,073 INFO 2024-05-25 02:09:42,073 INFO ================================================================================ 2024-05-25 02:09:42,073 INFO 2024-05-25 03:07:26 3615s elapsed 2024-05-25 02:09:42,073 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,073 INFO > Global (OK=3595021 KO=1 ) 2024-05-25 02:09:42,073 INFO > restLoginInitiate (OK=1797520 KO=0 ) 2024-05-25 02:09:42,074 INFO > restLoginUsernamePasswordCallback (OK=1797501 KO=1 ) 2024-05-25 02:09:42,074 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 02:09:42,074 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,074 INFO [#########################################################################-] 99% 2024-05-25 02:09:42,074 INFO waiting: 7457 / active: 18 / done: 1797502 2024-05-25 02:09:42,074 INFO ================================================================================ 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO ================================================================================ 2024-05-25 02:09:42,074 INFO 2024-05-25 03:07:31 3620s elapsed 2024-05-25 02:09:42,074 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,074 INFO > Global (OK=3600027 KO=1 ) 2024-05-25 02:09:42,074 INFO > restLoginInitiate (OK=1800020 KO=0 ) 2024-05-25 02:09:42,074 INFO > restLoginUsernamePasswordCallback (OK=1800007 KO=1 ) 2024-05-25 02:09:42,074 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 02:09:42,074 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,074 INFO [#########################################################################-] 99% 2024-05-25 02:09:42,074 INFO waiting: 4951 / active: 18 / done: 1800008 2024-05-25 02:09:42,074 INFO ================================================================================ 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO ================================================================================ 2024-05-25 02:09:42,074 INFO 2024-05-25 03:07:36 3625s elapsed 2024-05-25 02:09:42,074 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,074 INFO > Global (OK=3604932 KO=1 ) 2024-05-25 02:09:42,074 INFO > restLoginInitiate (OK=1802478 KO=0 ) 2024-05-25 02:09:42,074 INFO > restLoginUsernamePasswordCallback (OK=1802454 KO=1 ) 2024-05-25 02:09:42,074 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 02:09:42,074 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,074 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,074 INFO [#########################################################################-] 99% 2024-05-25 02:09:42,074 INFO waiting: 2497 / active: 25 / done: 1802455 2024-05-25 02:09:42,074 INFO ================================================================================ 2024-05-25 02:09:42,074 INFO 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO ================================================================================ 2024-05-25 02:09:42,075 INFO 2024-05-25 03:07:41 3630s elapsed 2024-05-25 02:09:42,075 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,075 INFO > Global (OK=3609940 KO=1 ) 2024-05-25 02:09:42,075 INFO > restLoginInitiate (OK=1804977 KO=0 ) 2024-05-25 02:09:42,075 INFO > restLoginUsernamePasswordCallback (OK=1804963 KO=1 ) 2024-05-25 02:09:42,075 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 02:09:42,075 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,075 INFO [#########################################################################-] 99% 2024-05-25 02:09:42,075 INFO waiting: 0 / active: 13 / done: 1804964 2024-05-25 02:09:42,075 INFO ================================================================================ 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO ================================================================================ 2024-05-25 02:09:42,075 INFO 2024-05-25 03:07:41 3630s elapsed 2024-05-25 02:09:42,075 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 02:09:42,075 INFO > Global (OK=3609953 KO=1 ) 2024-05-25 02:09:42,075 INFO > restLoginInitiate (OK=1804977 KO=0 ) 2024-05-25 02:09:42,075 INFO > restLoginUsernamePasswordCallback (OK=1804976 KO=1 ) 2024-05-25 02:09:42,075 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 02:09:42,075 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 02:09:42,075 INFO [##########################################################################]100% 2024-05-25 02:09:42,075 INFO waiting: 0 / active: 0 / done: 1804977 2024-05-25 02:09:42,075 INFO ================================================================================ 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 02:09:42,075 INFO 2024-05-25 02:09:42,075 INFO ---------------------- 2024-05-25 02:09:42,075 INFO [25/May/2024 03:07:41] Create tar file login-tree-500.tar.gz 2024-05-25 02:09:42,075 INFO ---------------------- 2024-05-25 02:09:42,075 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 02:09:42,075 INFO RESULT_FOLDER=login-tree-500 2024-05-25 02:09:42,075 INFO SIMULATION_PATH=login-tree-500/loadtest-20240525010711086 2024-05-25 02:09:42,075 INFO PWD=/results/gatling/login-tree-500/loadtest-20240525010711086 2024-05-25 02:09:42,075 INFO [cmd] tar -czvf /results/gatling//login-tree-500.tar.gz --exclude=simulation.log * 2024-05-25 02:09:42,076 INFO /results/gatling 2024-05-25 02:09:42,076 INFO 2024-05-25 02:09:42,076 INFO ---------------------- 2024-05-25 02:09:42,076 INFO [25/May/2024 03:07:41] Update /results/gatling//latest 2024-05-25 02:09:42,076 INFO ---------------------- 2024-05-25 02:09:42,076 INFO 2024-05-25 02:09:42,076 INFO ---------------------- 2024-05-25 02:09:42,076 INFO [25/May/2024 03:07:41] Update /results/gatling//latest.tar.gz 2024-05-25 02:09:42,076 INFO ---------------------- 2024-05-25 02:09:42,076 INFO ________________________________________________________________________________ [2024-05-25 02:09:42] login-tree-500 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 02:09:42,076 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 02:09:42,076 DEBUG ----- output ----- 2024-05-25 02:09:42,076 DEBUG 2024-05-25 02:09:42,076 INFO ________________________________________________________________________________ [2024-05-25 02:09:42] 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 02:09:42] 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 02:09:42] login-tree-500 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped