--Task-- name: login-tree-200 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-100'] preceding_task: login-tree-100 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 200} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 06:41:30] login-tree-200 pre : N/A ________________________________________________________________________________ [2024-05-25 06:41:30] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 06:41:30] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-200/stdout.txt Pod log : /results/orders/login-tree-200 2024-05-25 06:41:30,593 INFO 2024-05-25 06:41:30,593 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 06:41:30,593 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:30,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:30,896 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:30,896 DEBUG --- stdout --- 2024-05-25 06:41:30,896 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:30,896 DEBUG --- stderr --- 2024-05-25 06:41:30,896 DEBUG ________________________________________________________________________________ [2024-05-25 06:41:30] login-tree-200 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 06:41:30,897 INFO 2024-05-25 06:41:30,897 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 06:41:30,897 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:31,201 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:31,202 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:31,202 DEBUG --- stdout --- 2024-05-25 06:41:31,202 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:31,202 DEBUG --- stderr --- 2024-05-25 06:41:31,202 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-200 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 06:41:31] login-tree-200 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 06:41:31,203 INFO 2024-05-25 06:41:31,203 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 06:41:31,203 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:31,521 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:31,522 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:31,522 DEBUG --- stdout --- 2024-05-25 06:41:31,522 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:31,522 DEBUG --- stderr --- 2024-05-25 06:41:31,522 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/order.json { "name": "login-tree-200", "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-200 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-200", "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=200 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 06:41:31] login-tree-200 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 06:41:31,525 INFO 2024-05-25 06:41:31,525 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 06:41:31,525 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 06:41:31,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 06:41:31,778 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:31,778 DEBUG --- stdout --- 2024-05-25 06:41:31,778 DEBUG WAITING 2024-05-25 06:41:31,778 DEBUG --- stderr --- 2024-05-25 06:41:31,778 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 06:41:31] login-tree-200 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 06:41:31,779 INFO 2024-05-25 06:41:31,779 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 06:41:31,779 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:32,092 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:32,093 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:32,093 DEBUG --- stdout --- 2024-05-25 06:41:32,093 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:32,093 DEBUG --- stderr --- 2024-05-25 06:41:32,093 DEBUG 2024-05-25 06:41:32,094 INFO 2024-05-25 06:41:32,094 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/03_login-tree-200/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 06:41:32,094 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 06:41:33,472 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:33,472 DEBUG --- stdout --- 2024-05-25 06:41:33,473 DEBUG 2024-05-25 06:41:33,473 DEBUG --- stderr --- 2024-05-25 06:41:33,473 DEBUG Order has been successfully forwarded to overseer [2024-05-25 06:41:33] - INFO: Check if Gatling simulation started successfully 2024-05-25 06:41:33,473 INFO 2024-05-25 06:41:33,473 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 06:41:33,473 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 06:41:33,777 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 06:41:33,777 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:33,777 DEBUG --- stdout --- 2024-05-25 06:41:33,777 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 06:41:33,777 DEBUG --- stderr --- 2024-05-25 06:41:33,777 DEBUG 2024-05-25 06:41:33,777 INFO 2024-05-25 06:41:33,777 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-200/stdout.txt | grep "started..." 2024-05-25 06:41:33,777 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 06:41:34,507 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 06:41:40,241 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 06:41:40,242 INFO [loop_until]: OK (rc = 0) 2024-05-25 06:41:40,242 DEBUG --- stdout --- 2024-05-25 06:41:40,242 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 06:41:40,242 DEBUG --- stderr --- 2024-05-25 06:41:40,242 DEBUG ________________________________________________________________________________ [2024-05-25 06:41:40] login-tree-200 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-05-25 07:43:50] login-tree-200 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 07:43:50,376 INFO 2024-05-25 07:43:50,376 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 07:43:50,377 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:50,720 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:50,720 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:50,720 DEBUG --- stdout --- 2024-05-25 07:43:50,720 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:50,720 DEBUG --- stderr --- 2024-05-25 07:43:50,720 DEBUG 2024-05-25 07:43:50,720 INFO 2024-05-25 07:43:50,720 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 07:43:50,720 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 07:43:51,511 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:51,511 DEBUG --- stdout --- 2024-05-25 07:43:51,511 DEBUG 2024-05-25 07:43:51,511 DEBUG --- stderr --- 2024-05-25 07:43:51,512 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 07:43:51] login-tree-200 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 07:43:51] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/order.json 2024-05-25 07:43:51,513 INFO 2024-05-25 07:43:51,513 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 07:43:51,513 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:51,822 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:51,823 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:51,823 DEBUG --- stdout --- 2024-05-25 07:43:51,823 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:51,823 DEBUG --- stderr --- 2024-05-25 07:43:51,823 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 07:43:51,823 INFO 2024-05-25 07:43:51,823 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 07:43:51,823 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:52,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:52,137 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:52,137 DEBUG --- stdout --- 2024-05-25 07:43:52,137 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:52,137 DEBUG --- stderr --- 2024-05-25 07:43:52,137 DEBUG 2024-05-25 07:43:52,138 INFO 2024-05-25 07:43:52,138 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-200/rc.txt" ]; then echo "/results/orders/login-tree-200/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-200/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/rc.txt file found" 2024-05-25 07:43:52,138 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 07:43:52,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 07:43:52,997 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:52,997 DEBUG --- stdout --- 2024-05-25 07:43:52,997 DEBUG /results/orders/login-tree-200/rc.txt file found 2024-05-25 07:43:52,997 DEBUG --- stderr --- 2024-05-25 07:43:52,997 DEBUG 2024-05-25 07:43:52,998 INFO 2024-05-25 07:43:52,998 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 07:43:52,998 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:53,301 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:53,301 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:53,301 DEBUG --- stdout --- 2024-05-25 07:43:53,301 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:53,301 DEBUG --- stderr --- 2024-05-25 07:43:53,301 DEBUG 2024-05-25 07:43:53,301 INFO 2024-05-25 07:43:53,301 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-200/rc.txt 2024-05-25 07:43:54,102 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 07:43:54,103 DEBUG --- stdout --- 2024-05-25 07:43:54,103 DEBUG 4 /results/orders/login-tree-200/rc.txt 2024-05-25 07:43:54,103 DEBUG --- stderr --- 2024-05-25 07:43:54,103 DEBUG 2024-05-25 07:43:54,103 INFO - Download file (size 4k) 2024-05-25 07:43:54,103 INFO 2024-05-25 07:43:54,103 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 07:43:54,103 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:54,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:54,408 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:54,408 DEBUG --- stdout --- 2024-05-25 07:43:54,408 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:54,408 DEBUG --- stderr --- 2024-05-25 07:43:54,408 DEBUG source : /results/orders/login-tree-200/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/rc.txt 2024-05-25 07:43:54,408 INFO 2024-05-25 07:43:54,408 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 07:43:54,408 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:54,718 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:54,719 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:54,719 DEBUG --- stdout --- 2024-05-25 07:43:54,719 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:54,719 DEBUG --- stderr --- 2024-05-25 07:43:54,719 DEBUG 2024-05-25 07:43:54,719 INFO 2024-05-25 07:43:54,719 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-200/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/rc.txt 2024-05-25 07:43:54,719 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 07:43:55,451 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:55,451 DEBUG --- stdout --- 2024-05-25 07:43:55,451 DEBUG tar: Removing leading `/' from member names 2024-05-25 07:43:55,451 DEBUG --- stderr --- 2024-05-25 07:43:55,451 DEBUG 2024-05-25 07:43:55,452 INFO 2024-05-25 07:43:55,452 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:43:55,452 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:55,760 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:55,760 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:55,760 DEBUG --- stdout --- 2024-05-25 07:43:55,760 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:55,761 DEBUG --- stderr --- 2024-05-25 07:43:55,761 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 07:43:55,761 INFO 2024-05-25 07:43:55,761 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 07:43:55,761 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:56,069 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:56,069 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:56,069 DEBUG --- stdout --- 2024-05-25 07:43:56,069 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:56,069 DEBUG --- stderr --- 2024-05-25 07:43:56,069 DEBUG 2024-05-25 07:43:56,070 INFO 2024-05-25 07:43:56,070 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-200/stderr.txt" ]; then echo "/results/orders/login-tree-200/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stderr.txt file found" 2024-05-25 07:43:56,070 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 07:43:56,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 07:43:56,879 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:56,879 DEBUG --- stdout --- 2024-05-25 07:43:56,879 DEBUG /results/orders/login-tree-200/stderr.txt file found 2024-05-25 07:43:56,879 DEBUG --- stderr --- 2024-05-25 07:43:56,879 DEBUG 2024-05-25 07:43:56,880 INFO 2024-05-25 07:43:56,880 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 07:43:56,880 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:57,249 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:57,249 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:57,249 DEBUG --- stdout --- 2024-05-25 07:43:57,250 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:57,250 DEBUG --- stderr --- 2024-05-25 07:43:57,250 DEBUG 2024-05-25 07:43:57,250 INFO 2024-05-25 07:43:57,250 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-200/stderr.txt 2024-05-25 07:43:58,052 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 07:43:58,052 DEBUG --- stdout --- 2024-05-25 07:43:58,052 DEBUG 0 /results/orders/login-tree-200/stderr.txt 2024-05-25 07:43:58,052 DEBUG --- stderr --- 2024-05-25 07:43:58,052 DEBUG 2024-05-25 07:43:58,052 INFO - Download file (size 0k) 2024-05-25 07:43:58,053 INFO 2024-05-25 07:43:58,053 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 07:43:58,053 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:58,358 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:58,358 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:58,358 DEBUG --- stdout --- 2024-05-25 07:43:58,358 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:58,358 DEBUG --- stderr --- 2024-05-25 07:43:58,359 DEBUG source : /results/orders/login-tree-200/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stderr.txt 2024-05-25 07:43:58,359 INFO 2024-05-25 07:43:58,359 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:43:58,359 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:58,668 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:58,668 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:58,668 DEBUG --- stdout --- 2024-05-25 07:43:58,668 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:58,668 DEBUG --- stderr --- 2024-05-25 07:43:58,668 DEBUG 2024-05-25 07:43:58,669 INFO 2024-05-25 07:43:58,669 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-200/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stderr.txt 2024-05-25 07:43:58,669 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 07:43:59,399 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:59,399 DEBUG --- stdout --- 2024-05-25 07:43:59,399 DEBUG tar: Removing leading `/' from member names 2024-05-25 07:43:59,399 DEBUG --- stderr --- 2024-05-25 07:43:59,400 DEBUG 2024-05-25 07:43:59,400 INFO 2024-05-25 07:43:59,400 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 07:43:59,400 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:43:59,712 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:43:59,712 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:43:59,712 DEBUG --- stdout --- 2024-05-25 07:43:59,712 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:43:59,712 DEBUG --- stderr --- 2024-05-25 07:43:59,712 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 07:43:59,712 INFO 2024-05-25 07:43:59,712 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 07:43:59,713 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:00,013 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:00,014 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:00,014 DEBUG --- stdout --- 2024-05-25 07:44:00,014 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:00,014 DEBUG --- stderr --- 2024-05-25 07:44:00,014 DEBUG 2024-05-25 07:44:00,014 INFO 2024-05-25 07:44:00,014 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-200/stdout.txt" ]; then echo "/results/orders/login-tree-200/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-200/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/stdout.txt file found" 2024-05-25 07:44:00,014 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 07:44:00,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 07:44:00,824 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:00,824 DEBUG --- stdout --- 2024-05-25 07:44:00,824 DEBUG /results/orders/login-tree-200/stdout.txt file found 2024-05-25 07:44:00,825 DEBUG --- stderr --- 2024-05-25 07:44:00,825 DEBUG 2024-05-25 07:44:00,825 INFO 2024-05-25 07:44:00,825 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 07:44:00,825 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:01,125 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:01,125 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:01,125 DEBUG --- stdout --- 2024-05-25 07:44:01,125 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:01,125 DEBUG --- stderr --- 2024-05-25 07:44:01,125 DEBUG 2024-05-25 07:44:01,126 INFO 2024-05-25 07:44:01,126 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-200/stdout.txt 2024-05-25 07:44:01,926 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 07:44:01,926 DEBUG --- stdout --- 2024-05-25 07:44:01,926 DEBUG 680 /results/orders/login-tree-200/stdout.txt 2024-05-25 07:44:01,926 DEBUG --- stderr --- 2024-05-25 07:44:01,926 DEBUG 2024-05-25 07:44:01,926 INFO - Download file (size 680k) 2024-05-25 07:44:01,927 INFO 2024-05-25 07:44:01,927 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 07:44:01,927 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:02,238 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:02,238 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:02,238 DEBUG --- stdout --- 2024-05-25 07:44:02,238 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:02,238 DEBUG --- stderr --- 2024-05-25 07:44:02,238 DEBUG source : /results/orders/login-tree-200/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stdout.txt 2024-05-25 07:44:02,239 INFO 2024-05-25 07:44:02,239 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 07:44:02,239 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:02,557 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:02,557 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:02,557 DEBUG --- stdout --- 2024-05-25 07:44:02,557 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:02,557 DEBUG --- stderr --- 2024-05-25 07:44:02,557 DEBUG 2024-05-25 07:44:02,558 INFO 2024-05-25 07:44:02,558 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-200/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stdout.txt 2024-05-25 07:44:02,558 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 07:44:03,624 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:03,624 DEBUG --- stdout --- 2024-05-25 07:44:03,624 DEBUG tar: Removing leading `/' from member names 2024-05-25 07:44:03,624 DEBUG --- stderr --- 2024-05-25 07:44:03,624 DEBUG ________________________________________________________________________________ [2024-05-25 07:44:03] login-tree-200 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 07:44:03,625 INFO 2024-05-25 07:44:03,625 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 07:44:03,625 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:03,951 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:03,952 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:03,952 DEBUG --- stdout --- 2024-05-25 07:44:03,952 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:03,952 DEBUG --- stderr --- 2024-05-25 07:44:03,952 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 07:44:03,952 INFO 2024-05-25 07:44:03,952 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 07:44:03,952 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:04,260 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:04,260 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:04,260 DEBUG --- stdout --- 2024-05-25 07:44:04,260 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:04,260 DEBUG --- stderr --- 2024-05-25 07:44:04,260 DEBUG 2024-05-25 07:44:04,261 INFO 2024-05-25 07:44:04,261 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-200/cmd.txt" ]; then echo "/results/orders/login-tree-200/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-200/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-200/cmd.txt file found" 2024-05-25 07:44:04,261 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 07:44:05,055 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 07:44:05,055 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:05,055 DEBUG --- stdout --- 2024-05-25 07:44:05,055 DEBUG /results/orders/login-tree-200/cmd.txt file found 2024-05-25 07:44:05,055 DEBUG --- stderr --- 2024-05-25 07:44:05,055 DEBUG 2024-05-25 07:44:05,055 INFO 2024-05-25 07:44:05,055 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 07:44:05,055 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:05,362 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:05,362 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:05,362 DEBUG --- stdout --- 2024-05-25 07:44:05,363 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:05,363 DEBUG --- stderr --- 2024-05-25 07:44:05,363 DEBUG 2024-05-25 07:44:05,363 INFO 2024-05-25 07:44:05,363 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-200/cmd.txt 2024-05-25 07:44:06,162 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 07:44:06,163 DEBUG --- stdout --- 2024-05-25 07:44:06,164 DEBUG 4 /results/orders/login-tree-200/cmd.txt 2024-05-25 07:44:06,164 DEBUG --- stderr --- 2024-05-25 07:44:06,164 DEBUG 2024-05-25 07:44:06,164 INFO - Download file (size 4k) 2024-05-25 07:44:06,165 INFO 2024-05-25 07:44:06,165 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 07:44:06,165 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:06,475 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:06,475 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:06,475 DEBUG --- stdout --- 2024-05-25 07:44:06,475 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:06,475 DEBUG --- stderr --- 2024-05-25 07:44:06,475 DEBUG source : /results/orders/login-tree-200/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/cmd.txt 2024-05-25 07:44:06,476 INFO 2024-05-25 07:44:06,476 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 07:44:06,476 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:06,796 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:06,796 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:06,796 DEBUG --- stdout --- 2024-05-25 07:44:06,796 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:06,796 DEBUG --- stderr --- 2024-05-25 07:44:06,796 DEBUG 2024-05-25 07:44:06,797 INFO 2024-05-25 07:44:06,797 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-200/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/cmd.txt 2024-05-25 07:44:06,797 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 07:44:07,581 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:07,582 DEBUG --- stdout --- 2024-05-25 07:44:07,582 DEBUG tar: Removing leading `/' from member names 2024-05-25 07:44:07,582 DEBUG --- stderr --- 2024-05-25 07:44:07,582 DEBUG 2024-05-25 07:44:07,582 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/cmd.txt is small (1 lines), printing all lines: 2024-05-25 07:44:07,582 DEBUG ----- output ----- 2024-05-25 07:44:07,582 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-200 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 07:44:07,582 INFO ________________________________________________________________________________ [2024-05-25 07:44:07] login-tree-200 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 07:44:07,583 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/rc.txt is small (1 lines), printing all lines: 2024-05-25 07:44:07,583 DEBUG ----- output ----- 2024-05-25 07:44:07,583 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 07:44:07,583 INFO ________________________________________________________________________________ [2024-05-25 07:44:07] login-tree-200 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 07:44:07,585 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/03_login-tree-200/stdout.txt is 10853 lines): 2024-05-25 07:44:07,585 DEBUG ----- output ----- 2024-05-25 07:44:07,585 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 07:44:07,585 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 07:44:07,585 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 07:44:07,585 INFO 2024-05-25 07:44:07,585 INFO ================================================================================ 2024-05-25 07:44:07,585 INFO 2024-05-25 07:41:42 5s elapsed 2024-05-25 07:44:07,585 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,585 INFO > Global (OK=165 KO=0 ) 2024-05-25 07:44:07,585 INFO > restLoginInitiate (OK=83 KO=0 ) 2024-05-25 07:44:07,585 INFO > restLoginUsernamePasswordCallback (OK=82 KO=0 ) 2024-05-25 07:44:07,585 INFO 2024-05-25 07:44:07,585 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,585 INFO [- ] 0% 2024-05-25 07:44:07,585 INFO waiting: 721937 / active: 2 / done: 82 2024-05-25 07:44:07,585 INFO ================================================================================ 2024-05-25 07:44:07,585 INFO 2024-05-25 07:44:07,585 INFO 2024-05-25 07:44:07,585 INFO ================================================================================ 2024-05-25 07:44:07,585 INFO 2024-05-25 07:41:47 10s elapsed 2024-05-25 07:44:07,585 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,585 INFO > Global (OK=665 KO=0 ) 2024-05-25 07:44:07,585 INFO > restLoginInitiate (OK=333 KO=0 ) 2024-05-25 07:44:07,585 INFO > restLoginUsernamePasswordCallback (OK=332 KO=0 ) 2024-05-25 07:44:07,585 INFO 2024-05-25 07:44:07,586 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,586 INFO [- ] 0% 2024-05-25 07:44:07,586 INFO waiting: 721687 / active: 2 / done: 332 2024-05-25 07:44:07,586 INFO ================================================================================ 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO ================================================================================ 2024-05-25 07:44:07,586 INFO 2024-05-25 07:41:52 15s elapsed 2024-05-25 07:44:07,586 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,586 INFO > Global (OK=1498 KO=0 ) 2024-05-25 07:44:07,586 INFO > restLoginInitiate (OK=750 KO=0 ) 2024-05-25 07:44:07,586 INFO > restLoginUsernamePasswordCallback (OK=748 KO=0 ) 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,586 INFO [- ] 0% 2024-05-25 07:44:07,586 INFO waiting: 721270 / active: 3 / done: 748 2024-05-25 07:44:07,586 INFO ================================================================================ 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO ================================================================================ 2024-05-25 07:44:07,586 INFO 2024-05-25 07:41:57 20s elapsed 2024-05-25 07:44:07,586 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,586 INFO > Global (OK=2663 KO=0 ) 2024-05-25 07:44:07,586 INFO > restLoginInitiate (OK=1333 KO=0 ) 2024-05-25 07:44:07,586 INFO > restLoginUsernamePasswordCallback (OK=1330 KO=0 ) 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,586 INFO [- ] 0% 2024-05-25 07:44:07,586 INFO waiting: 720686 / active: 5 / done: 1330 2024-05-25 07:44:07,586 INFO ================================================================================ 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO 2024-05-25 07:44:07,586 INFO ================================================================================ 2024-05-25 07:44:07,586 INFO 2024-05-25 07:42:02 25s elapsed 2024-05-25 07:44:07,586 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,586 INFO > Global (OK=4161 KO=0 ) 2024-05-25 07:44:07,586 INFO > restLoginInitiate (OK=2083 KO=0 ) 2024-05-25 07:44:07,586 INFO > restLoginUsernamePasswordCallback (OK=2078 KO=0 ) 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,587 INFO [- ] 0% 2024-05-25 07:44:07,587 INFO waiting: 719936 / active: 7 / done: 2078 2024-05-25 07:44:07,587 INFO ================================================================================ 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO ================================================================================ 2024-05-25 07:44:07,587 INFO 2024-05-25 07:42:07 30s elapsed 2024-05-25 07:44:07,587 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,587 INFO > Global (OK=5993 KO=0 ) 2024-05-25 07:44:07,587 INFO > restLoginInitiate (OK=2998 KO=0 ) 2024-05-25 07:44:07,587 INFO > restLoginUsernamePasswordCallback (OK=2995 KO=0 ) 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,587 INFO [- ] 0% 2024-05-25 07:44:07,587 INFO waiting: 719020 / active: 6 / done: 2995 2024-05-25 07:44:07,587 INFO ================================================================================ 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO ================================================================================ 2024-05-25 07:44:07,587 INFO 2024-05-25 07:42:12 35s elapsed 2024-05-25 07:44:07,587 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,587 INFO > Global (OK=8024 KO=0 ) 2024-05-25 07:44:07,587 INFO > restLoginInitiate (OK=4015 KO=0 ) 2024-05-25 07:44:07,587 INFO > restLoginUsernamePasswordCallback (OK=4009 KO=0 ) 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,587 INFO [- ] 0% 2024-05-25 07:44:07,587 INFO waiting: 718003 / active: 9 / done: 4009 2024-05-25 07:44:07,587 INFO ================================================================================ 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO 2024-05-25 07:44:07,587 INFO ================================================================================ 2024-05-25 07:44:07,587 INFO 2024-05-25 07:42:17 40s elapsed 2024-05-25 07:44:07,587 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,587 INFO > Global (OK=9940 KO=0 ) 2024-05-25 07:44:07,587 INFO > restLoginInitiate (OK=4975 KO=0 ) 2024-05-25 07:44:07,587 INFO [...] 2024-05-25 07:44:07,590 INFO 2024-05-25 07:44:07,590 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,590 INFO [#########################################################################-] 99% 2024-05-25 07:44:07,590 INFO waiting: 4019 / active: 8 / done: 717994 2024-05-25 07:44:07,590 INFO ================================================================================ 2024-05-25 07:44:07,590 INFO 2024-05-25 07:44:07,590 INFO 2024-05-25 07:44:07,591 INFO ================================================================================ 2024-05-25 07:44:07,591 INFO 2024-05-25 08:41:52 3615s elapsed 2024-05-25 07:44:07,591 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,591 INFO > Global (OK=1437962 KO=3 ) 2024-05-25 07:44:07,591 INFO > restLoginInitiate (OK=718985 KO=1 ) 2024-05-25 07:44:07,591 INFO > restLoginUsernamePasswordCallback (OK=718977 KO=2 ) 2024-05-25 07:44:07,591 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 07:44:07,591 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,591 INFO [#########################################################################-] 99% 2024-05-25 07:44:07,591 INFO waiting: 3033 / active: 8 / done: 718980 2024-05-25 07:44:07,591 INFO ================================================================================ 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO ================================================================================ 2024-05-25 07:44:07,591 INFO 2024-05-25 08:41:57 3620s elapsed 2024-05-25 07:44:07,591 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,591 INFO > Global (OK=1439926 KO=3 ) 2024-05-25 07:44:07,591 INFO > restLoginInitiate (OK=719965 KO=1 ) 2024-05-25 07:44:07,591 INFO > restLoginUsernamePasswordCallback (OK=719961 KO=2 ) 2024-05-25 07:44:07,591 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 07:44:07,591 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,591 INFO [#########################################################################-] 99% 2024-05-25 07:44:07,591 INFO waiting: 2053 / active: 4 / done: 719964 2024-05-25 07:44:07,591 INFO ================================================================================ 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO ================================================================================ 2024-05-25 07:44:07,591 INFO 2024-05-25 08:42:02 3625s elapsed 2024-05-25 07:44:07,591 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,591 INFO > Global (OK=1441989 KO=3 ) 2024-05-25 07:44:07,591 INFO > restLoginInitiate (OK=720997 KO=1 ) 2024-05-25 07:44:07,591 INFO > restLoginUsernamePasswordCallback (OK=720992 KO=2 ) 2024-05-25 07:44:07,591 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 07:44:07,591 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 07:44:07,591 INFO 2024-05-25 07:44:07,591 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,591 INFO [#########################################################################-] 99% 2024-05-25 07:44:07,591 INFO waiting: 1021 / active: 5 / done: 720995 2024-05-25 07:44:07,592 INFO ================================================================================ 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO ================================================================================ 2024-05-25 07:44:07,592 INFO 2024-05-25 08:42:07 3630s elapsed 2024-05-25 07:44:07,592 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,592 INFO > Global (OK=1444034 KO=3 ) 2024-05-25 07:44:07,592 INFO > restLoginInitiate (OK=722020 KO=1 ) 2024-05-25 07:44:07,592 INFO > restLoginUsernamePasswordCallback (OK=722014 KO=2 ) 2024-05-25 07:44:07,592 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 07:44:07,592 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,592 INFO [#########################################################################-] 99% 2024-05-25 07:44:07,592 INFO waiting: 0 / active: 4 / done: 722017 2024-05-25 07:44:07,592 INFO ================================================================================ 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO ================================================================================ 2024-05-25 07:44:07,592 INFO 2024-05-25 08:42:07 3630s elapsed 2024-05-25 07:44:07,592 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 07:44:07,592 INFO > Global (OK=1444038 KO=3 ) 2024-05-25 07:44:07,592 INFO > restLoginInitiate (OK=722020 KO=1 ) 2024-05-25 07:44:07,592 INFO > restLoginUsernamePasswordCallback (OK=722018 KO=2 ) 2024-05-25 07:44:07,592 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 07:44:07,592 INFO > status.find.is(200), but actually found 502 3 (100.0%) 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 07:44:07,592 INFO [##########################################################################]100% 2024-05-25 07:44:07,592 INFO waiting: 0 / active: 0 / done: 722021 2024-05-25 07:44:07,592 INFO ================================================================================ 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 07:44:07,592 INFO 2024-05-25 07:44:07,592 INFO ---------------------- 2024-05-25 07:44:07,592 INFO [25/May/2024 08:42:07] Create tar file login-tree-200.tar.gz 2024-05-25 07:44:07,592 INFO ---------------------- 2024-05-25 07:44:07,592 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 07:44:07,592 INFO RESULT_FOLDER=login-tree-200 2024-05-25 07:44:07,592 INFO SIMULATION_PATH=login-tree-200/loadtest-20240525064137025 2024-05-25 07:44:07,593 INFO PWD=/results/gatling/login-tree-200/loadtest-20240525064137025 2024-05-25 07:44:07,593 INFO [cmd] tar -czvf /results/gatling//login-tree-200.tar.gz --exclude=simulation.log * 2024-05-25 07:44:07,593 INFO /results/gatling 2024-05-25 07:44:07,593 INFO 2024-05-25 07:44:07,593 INFO ---------------------- 2024-05-25 07:44:07,593 INFO [25/May/2024 08:42:07] Update /results/gatling//latest 2024-05-25 07:44:07,593 INFO ---------------------- 2024-05-25 07:44:07,593 INFO 2024-05-25 07:44:07,593 INFO ---------------------- 2024-05-25 07:44:07,593 INFO [25/May/2024 08:42:07] Update /results/gatling//latest.tar.gz 2024-05-25 07:44:07,593 INFO ---------------------- 2024-05-25 07:44:07,593 INFO ________________________________________________________________________________ [2024-05-25 07:44:07] login-tree-200 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 07:44:07,593 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-200/stderr.txt is small (0 lines), printing all lines: 2024-05-25 07:44:07,593 DEBUG ----- output ----- 2024-05-25 07:44:07,593 DEBUG 2024-05-25 07:44:07,593 INFO ________________________________________________________________________________ [2024-05-25 07:44:07] login-tree-200 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 07:44:07] login-tree-200 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 07:44:07] login-tree-200 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped