--Task-- name: login-tree-275 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-250'] preceding_task: login-tree-250 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 275, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-22 23:24:38] login-tree-275 pre : N/A ________________________________________________________________________________ 2024-06-22 23:24:38 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-22 23:24:38 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-275/stdout.txt Pod log : /results/orders/login-tree-275 2024-06-22 23:24:38,872 INFO 2024-06-22 23:24:38,872 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-06-22 23:24:38,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 23:24:39,190 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 23:24:39,191 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:39,191 DEBUG --- stdout --- 2024-06-22 23:24:39,191 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 23:24:39,191 DEBUG --- stderr --- 2024-06-22 23:24:39,191 DEBUG ________________________________________________________________________________ [2024-06-22 23:24:39] login-tree-275 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-22 23:24:39,192 INFO 2024-06-22 23:24:39,192 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-06-22 23:24:39,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 23:24:39,516 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 23:24:39,517 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:39,517 DEBUG --- stdout --- 2024-06-22 23:24:39,517 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 23:24:39,517 DEBUG --- stderr --- 2024-06-22 23:24:39,517 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-p1" --results-folder /results/gatling/login-tree-275 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-22 23:24:39] login-tree-275 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-22 23:24:39,518 INFO 2024-06-22 23:24:39,518 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-22 23:24:39,518 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 23:24:39,838 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 23:24:39,838 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:39,838 DEBUG --- stdout --- 2024-06-22 23:24:39,838 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 23:24:39,838 DEBUG --- stderr --- 2024-06-22 23:24:39,838 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/order.json { "name": "login-tree-275", "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-p1\" --results-folder /results/gatling/login-tree-275 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-275", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -Dlogin_tree=Login -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=275 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-22 23:24:39] login-tree-275 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-06-22 23:24:39,840 INFO 2024-06-22 23:24:39,840 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-22 23:24:39,840 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 23:24:40,083 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-22 23:24:40,083 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:40,083 DEBUG --- stdout --- 2024-06-22 23:24:40,084 DEBUG WAITING 2024-06-22 23:24:40,084 DEBUG --- stderr --- 2024-06-22 23:24:40,084 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-06-22 23:24:40] login-tree-275 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-22 23:24:40,084 INFO 2024-06-22 23:24:40,085 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-06-22 23:24:40,085 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 23:24:40,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 23:24:40,408 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:40,408 DEBUG --- stdout --- 2024-06-22 23:24:40,409 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 23:24:40,409 DEBUG --- stderr --- 2024-06-22 23:24:40,409 DEBUG 2024-06-22 23:24:40,409 INFO 2024-06-22 23:24:40,409 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-275/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-22 23:24:40,409 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-22 23:24:41,763 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:41,763 DEBUG --- stdout --- 2024-06-22 23:24:41,763 DEBUG 2024-06-22 23:24:41,763 DEBUG --- stderr --- 2024-06-22 23:24:41,763 DEBUG Order has been successfully forwarded to overseer 2024-06-22 23:24:41 - INFO: Check if Gatling simulation started successfully 2024-06-22 23:24:41,764 INFO 2024-06-22 23:24:41,764 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-06-22 23:24:41,764 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-22 23:24:42,067 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-22 23:24:42,067 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:42,067 DEBUG --- stdout --- 2024-06-22 23:24:42,067 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-22 23:24:42,068 DEBUG --- stderr --- 2024-06-22 23:24:42,068 DEBUG 2024-06-22 23:24:42,068 INFO 2024-06-22 23:24:42,068 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-275/stdout.txt | grep "started..." 2024-06-22 23:24:42,068 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-22 23:24:42,801 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-22 23:24:48,574 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-22 23:24:48,574 INFO [loop_until]: OK (rc = 0) 2024-06-22 23:24:48,574 DEBUG --- stdout --- 2024-06-22 23:24:48,574 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.4 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-22 23:24:48,575 DEBUG --- stderr --- 2024-06-22 23:24:48,575 DEBUG ________________________________________________________________________________ [2024-06-22 23:24:48] login-tree-275 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, 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 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-06-23 00:26:58] login-tree-275 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-23 00:26:58,755 INFO 2024-06-23 00:26:58,755 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-06-23 00:26:58,755 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:26:59,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:26:59,098 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:26:59,098 DEBUG --- stdout --- 2024-06-23 00:26:59,099 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:26:59,099 DEBUG --- stderr --- 2024-06-23 00:26:59,099 DEBUG 2024-06-23 00:26:59,099 INFO 2024-06-23 00:26:59,099 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json 2024-06-23 00:26:59,099 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 00:26:59,896 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:26:59,896 DEBUG --- stdout --- 2024-06-23 00:26:59,896 DEBUG 2024-06-23 00:26:59,896 DEBUG --- stderr --- 2024-06-23 00:26:59,896 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-23 00:26:59] login-tree-275 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-23 00:26:59 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/order.json 2024-06-23 00:26:59,898 INFO 2024-06-23 00:26:59,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-06-23 00:26:59,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:00,204 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:00,204 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:00,204 DEBUG --- stdout --- 2024-06-23 00:27:00,205 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:00,205 DEBUG --- stderr --- 2024-06-23 00:27:00,205 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 00:27:00,205 INFO 2024-06-23 00:27:00,205 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 00:27:00,205 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:00,506 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:00,506 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:00,507 DEBUG --- stdout --- 2024-06-23 00:27:00,507 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:00,507 DEBUG --- stderr --- 2024-06-23 00:27:00,507 DEBUG 2024-06-23 00:27:00,507 INFO 2024-06-23 00:27:00,507 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/rc.txt" ]; then echo "/results/orders/login-tree-275/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-275/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/rc.txt file found" 2024-06-23 00:27:00,507 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 00:27:01,310 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 00:27:01,310 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:01,310 DEBUG --- stdout --- 2024-06-23 00:27:01,310 DEBUG /results/orders/login-tree-275/rc.txt file found 2024-06-23 00:27:01,310 DEBUG --- stderr --- 2024-06-23 00:27:01,310 DEBUG 2024-06-23 00:27:01,311 INFO 2024-06-23 00:27:01,311 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-06-23 00:27:01,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:01,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:01,627 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:01,627 DEBUG --- stdout --- 2024-06-23 00:27:01,627 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:01,627 DEBUG --- stderr --- 2024-06-23 00:27:01,627 DEBUG 2024-06-23 00:27:01,627 INFO 2024-06-23 00:27:01,627 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/rc.txt 2024-06-23 00:27:02,444 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 00:27:02,444 DEBUG --- stdout --- 2024-06-23 00:27:02,445 DEBUG 4 /results/orders/login-tree-275/rc.txt 2024-06-23 00:27:02,445 DEBUG --- stderr --- 2024-06-23 00:27:02,445 DEBUG 2024-06-23 00:27:02,445 INFO - Download file (size 4k) 2024-06-23 00:27:02,446 INFO 2024-06-23 00:27:02,446 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-06-23 00:27:02,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:02,754 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:02,754 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:02,754 DEBUG --- stdout --- 2024-06-23 00:27:02,754 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:02,754 DEBUG --- stderr --- 2024-06-23 00:27:02,754 DEBUG source : /results/orders/login-tree-275/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/rc.txt 2024-06-23 00:27:02,755 INFO 2024-06-23 00:27:02,755 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-06-23 00:27:02,755 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:03,061 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:03,061 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:03,061 DEBUG --- stdout --- 2024-06-23 00:27:03,061 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:03,061 DEBUG --- stderr --- 2024-06-23 00:27:03,061 DEBUG 2024-06-23 00:27:03,062 INFO 2024-06-23 00:27:03,062 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/rc.txt 2024-06-23 00:27:03,062 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 00:27:03,819 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:03,819 DEBUG --- stdout --- 2024-06-23 00:27:03,820 DEBUG tar: Removing leading `/' from member names 2024-06-23 00:27:03,820 DEBUG --- stderr --- 2024-06-23 00:27:03,820 DEBUG 2024-06-23 00:27:03,820 INFO 2024-06-23 00:27:03,820 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-06-23 00:27:03,820 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:04,125 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:04,125 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:04,125 DEBUG --- stdout --- 2024-06-23 00:27:04,125 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:04,125 DEBUG --- stderr --- 2024-06-23 00:27:04,125 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 00:27:04,126 INFO 2024-06-23 00:27:04,126 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-06-23 00:27:04,126 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:04,427 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:04,427 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:04,427 DEBUG --- stdout --- 2024-06-23 00:27:04,427 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:04,428 DEBUG --- stderr --- 2024-06-23 00:27:04,428 DEBUG 2024-06-23 00:27:04,428 INFO 2024-06-23 00:27:04,428 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/stderr.txt" ]; then echo "/results/orders/login-tree-275/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-275/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/stderr.txt file found" 2024-06-23 00:27:04,428 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 00:27:05,233 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 00:27:05,233 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:05,233 DEBUG --- stdout --- 2024-06-23 00:27:05,234 DEBUG /results/orders/login-tree-275/stderr.txt file found 2024-06-23 00:27:05,234 DEBUG --- stderr --- 2024-06-23 00:27:05,234 DEBUG 2024-06-23 00:27:05,234 INFO 2024-06-23 00:27:05,234 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-06-23 00:27:05,234 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:05,549 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:05,549 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:05,549 DEBUG --- stdout --- 2024-06-23 00:27:05,549 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:05,550 DEBUG --- stderr --- 2024-06-23 00:27:05,550 DEBUG 2024-06-23 00:27:05,550 INFO 2024-06-23 00:27:05,550 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/stderr.txt 2024-06-23 00:27:06,362 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 00:27:06,363 DEBUG --- stdout --- 2024-06-23 00:27:06,363 DEBUG 0 /results/orders/login-tree-275/stderr.txt 2024-06-23 00:27:06,363 DEBUG --- stderr --- 2024-06-23 00:27:06,363 DEBUG 2024-06-23 00:27:06,363 INFO - Download file (size 0k) 2024-06-23 00:27:06,363 INFO 2024-06-23 00:27:06,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-06-23 00:27:06,363 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:06,700 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:06,701 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:06,701 DEBUG --- stdout --- 2024-06-23 00:27:06,701 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:06,701 DEBUG --- stderr --- 2024-06-23 00:27:06,701 DEBUG source : /results/orders/login-tree-275/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stderr.txt 2024-06-23 00:27:06,701 INFO 2024-06-23 00:27:06,701 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-06-23 00:27:06,702 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:07,026 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:07,026 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:07,026 DEBUG --- stdout --- 2024-06-23 00:27:07,026 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:07,026 DEBUG --- stderr --- 2024-06-23 00:27:07,027 DEBUG 2024-06-23 00:27:07,027 INFO 2024-06-23 00:27:07,027 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stderr.txt 2024-06-23 00:27:07,027 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 00:27:07,822 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:07,822 DEBUG --- stdout --- 2024-06-23 00:27:07,823 DEBUG tar: Removing leading `/' from member names 2024-06-23 00:27:07,823 DEBUG --- stderr --- 2024-06-23 00:27:07,823 DEBUG 2024-06-23 00:27:07,823 INFO 2024-06-23 00:27:07,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-06-23 00:27:07,823 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:08,140 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:08,140 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:08,140 DEBUG --- stdout --- 2024-06-23 00:27:08,140 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:08,141 DEBUG --- stderr --- 2024-06-23 00:27:08,141 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 00:27:08,141 INFO 2024-06-23 00:27:08,141 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-06-23 00:27:08,141 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:08,447 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:08,447 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:08,447 DEBUG --- stdout --- 2024-06-23 00:27:08,447 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:08,447 DEBUG --- stderr --- 2024-06-23 00:27:08,447 DEBUG 2024-06-23 00:27:08,448 INFO 2024-06-23 00:27:08,448 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/stdout.txt" ]; then echo "/results/orders/login-tree-275/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-275/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/stdout.txt file found" 2024-06-23 00:27:08,448 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 00:27:09,246 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 00:27:09,246 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:09,246 DEBUG --- stdout --- 2024-06-23 00:27:09,246 DEBUG /results/orders/login-tree-275/stdout.txt file found 2024-06-23 00:27:09,246 DEBUG --- stderr --- 2024-06-23 00:27:09,246 DEBUG 2024-06-23 00:27:09,247 INFO 2024-06-23 00:27:09,247 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-06-23 00:27:09,247 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:09,616 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:09,616 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:09,616 DEBUG --- stdout --- 2024-06-23 00:27:09,616 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:09,616 DEBUG --- stderr --- 2024-06-23 00:27:09,616 DEBUG 2024-06-23 00:27:09,616 INFO 2024-06-23 00:27:09,617 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/stdout.txt 2024-06-23 00:27:10,475 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 00:27:10,475 DEBUG --- stdout --- 2024-06-23 00:27:10,476 DEBUG 860 /results/orders/login-tree-275/stdout.txt 2024-06-23 00:27:10,476 DEBUG --- stderr --- 2024-06-23 00:27:10,476 DEBUG 2024-06-23 00:27:10,476 INFO - Download file (size 860k) 2024-06-23 00:27:10,476 INFO 2024-06-23 00:27:10,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-06-23 00:27:10,476 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:10,781 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:10,781 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:10,781 DEBUG --- stdout --- 2024-06-23 00:27:10,781 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:10,782 DEBUG --- stderr --- 2024-06-23 00:27:10,782 DEBUG source : /results/orders/login-tree-275/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stdout.txt 2024-06-23 00:27:10,782 INFO 2024-06-23 00:27:10,782 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-06-23 00:27:10,782 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:11,092 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:11,092 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:11,092 DEBUG --- stdout --- 2024-06-23 00:27:11,092 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:11,092 DEBUG --- stderr --- 2024-06-23 00:27:11,092 DEBUG 2024-06-23 00:27:11,093 INFO 2024-06-23 00:27:11,093 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stdout.txt 2024-06-23 00:27:11,093 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 00:27:12,166 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:12,167 DEBUG --- stdout --- 2024-06-23 00:27:12,167 DEBUG tar: Removing leading `/' from member names 2024-06-23 00:27:12,167 DEBUG --- stderr --- 2024-06-23 00:27:12,167 DEBUG ________________________________________________________________________________ [2024-06-23 00:27:12] login-tree-275 show_cmd : Display order command ________________________________________________________________________________ 2024-06-23 00:27:12,168 INFO 2024-06-23 00:27:12,168 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-23 00:27:12,168 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:12,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:12,483 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:12,483 DEBUG --- stdout --- 2024-06-23 00:27:12,483 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:12,483 DEBUG --- stderr --- 2024-06-23 00:27:12,483 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-23 00:27:12,484 INFO 2024-06-23 00:27:12,484 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-06-23 00:27:12,484 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:12,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:12,803 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:12,803 DEBUG --- stdout --- 2024-06-23 00:27:12,803 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:12,803 DEBUG --- stderr --- 2024-06-23 00:27:12,803 DEBUG 2024-06-23 00:27:12,803 INFO 2024-06-23 00:27:12,803 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/cmd.txt" ]; then echo "/results/orders/login-tree-275/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-275/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/cmd.txt file found" 2024-06-23 00:27:12,803 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-23 00:27:13,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-23 00:27:13,619 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:13,619 DEBUG --- stdout --- 2024-06-23 00:27:13,619 DEBUG /results/orders/login-tree-275/cmd.txt file found 2024-06-23 00:27:13,619 DEBUG --- stderr --- 2024-06-23 00:27:13,619 DEBUG 2024-06-23 00:27:13,619 INFO 2024-06-23 00:27:13,619 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-06-23 00:27:13,619 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:13,940 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:13,940 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:13,940 DEBUG --- stdout --- 2024-06-23 00:27:13,940 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:13,940 DEBUG --- stderr --- 2024-06-23 00:27:13,940 DEBUG 2024-06-23 00:27:13,940 INFO 2024-06-23 00:27:13,941 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/cmd.txt 2024-06-23 00:27:14,753 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-23 00:27:14,754 DEBUG --- stdout --- 2024-06-23 00:27:14,754 DEBUG 4 /results/orders/login-tree-275/cmd.txt 2024-06-23 00:27:14,754 DEBUG --- stderr --- 2024-06-23 00:27:14,754 DEBUG 2024-06-23 00:27:14,754 INFO - Download file (size 4k) 2024-06-23 00:27:14,755 INFO 2024-06-23 00:27:14,755 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-06-23 00:27:14,755 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:15,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:15,080 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:15,080 DEBUG --- stdout --- 2024-06-23 00:27:15,080 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:15,080 DEBUG --- stderr --- 2024-06-23 00:27:15,080 DEBUG source : /results/orders/login-tree-275/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/cmd.txt 2024-06-23 00:27:15,081 INFO 2024-06-23 00:27:15,081 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-06-23 00:27:15,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-23 00:27:15,406 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-23 00:27:15,406 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:15,406 DEBUG --- stdout --- 2024-06-23 00:27:15,407 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-23 00:27:15,407 DEBUG --- stderr --- 2024-06-23 00:27:15,407 DEBUG 2024-06-23 00:27:15,407 INFO 2024-06-23 00:27:15,407 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/cmd.txt 2024-06-23 00:27:15,407 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-23 00:27:16,144 INFO [loop_until]: OK (rc = 0) 2024-06-23 00:27:16,144 DEBUG --- stdout --- 2024-06-23 00:27:16,144 DEBUG tar: Removing leading `/' from member names 2024-06-23 00:27:16,144 DEBUG --- stderr --- 2024-06-23 00:27:16,144 DEBUG 2024-06-23 00:27:16,145 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/cmd.txt is small (1 lines), printing all lines: 2024-06-23 00:27:16,145 DEBUG ----- output ----- 2024-06-23 00:27:16,145 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-p1" --results-folder /results/gatling/login-tree-275 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-23 00:27:16,145 INFO ________________________________________________________________________________ [2024-06-23 00:27:16] login-tree-275 show_rc : Display rc order ________________________________________________________________________________ 2024-06-23 00:27:16,145 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/rc.txt is small (1 lines), printing all lines: 2024-06-23 00:27:16,145 DEBUG ----- output ----- 2024-06-23 00:27:16,145 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-23 00:27:16,145 INFO ________________________________________________________________________________ [2024-06-23 00:27:16] login-tree-275 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-23 00:27:16,148 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-275/stdout.txt is 12962 lines): 2024-06-23 00:27:16,148 DEBUG ----- output ----- 2024-06-23 00:27:16,148 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-23 00:27:16,148 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-23 00:27:16,148 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-23 00:27:16,148 INFO 2024-06-23 00:27:16,148 INFO ================================================================================ 2024-06-23 00:27:16,148 INFO 2024-06-23 00:24:50 5s elapsed 2024-06-23 00:27:16,148 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,148 INFO > Global (OK=250 KO=0 ) 2024-06-23 00:27:16,148 INFO > restLoginInitiate (OK=113 KO=0 ) 2024-06-23 00:27:16,148 INFO > restLoginUsernamePasswordCallback (OK=108 KO=0 ) 2024-06-23 00:27:16,148 INFO > restloginProgressiveProfileCallback (OK=29 KO=0 ) 2024-06-23 00:27:16,148 INFO 2024-06-23 00:27:16,148 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,148 INFO [- ] 0% 2024-06-23 00:27:16,148 INFO waiting: 994914 / active: 9 / done: 106 2024-06-23 00:27:16,148 INFO ================================================================================ 2024-06-23 00:27:16,148 INFO 2024-06-23 00:27:16,148 INFO 2024-06-23 00:27:16,148 INFO ================================================================================ 2024-06-23 00:27:16,148 INFO 2024-06-23 00:24:55 10s elapsed 2024-06-23 00:27:16,148 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,148 INFO > Global (OK=1011 KO=0 ) 2024-06-23 00:27:16,148 INFO > restLoginInitiate (OK=456 KO=0 ) 2024-06-23 00:27:16,148 INFO > restLoginUsernamePasswordCallback (OK=446 KO=0 ) 2024-06-23 00:27:16,148 INFO > restloginProgressiveProfileCallback (OK=109 KO=0 ) 2024-06-23 00:27:16,148 INFO 2024-06-23 00:27:16,149 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,149 INFO [- ] 0% 2024-06-23 00:27:16,149 INFO waiting: 994569 / active: 18 / done: 442 2024-06-23 00:27:16,149 INFO ================================================================================ 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO ================================================================================ 2024-06-23 00:27:16,149 INFO 2024-06-23 00:25:00 15s elapsed 2024-06-23 00:27:16,149 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,149 INFO > Global (OK=2304 KO=0 ) 2024-06-23 00:27:16,149 INFO > restLoginInitiate (OK=1027 KO=0 ) 2024-06-23 00:27:16,149 INFO > restLoginUsernamePasswordCallback (OK=1012 KO=0 ) 2024-06-23 00:27:16,149 INFO > restloginProgressiveProfileCallback (OK=265 KO=0 ) 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,149 INFO [- ] 0% 2024-06-23 00:27:16,149 INFO waiting: 993995 / active: 27 / done: 1007 2024-06-23 00:27:16,149 INFO ================================================================================ 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO ================================================================================ 2024-06-23 00:27:16,149 INFO 2024-06-23 00:25:05 20s elapsed 2024-06-23 00:27:16,149 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,149 INFO > Global (OK=4102 KO=0 ) 2024-06-23 00:27:16,149 INFO > restLoginInitiate (OK=1828 KO=0 ) 2024-06-23 00:27:16,149 INFO > restLoginUsernamePasswordCallback (OK=1803 KO=0 ) 2024-06-23 00:27:16,149 INFO > restloginProgressiveProfileCallback (OK=471 KO=0 ) 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,149 INFO [- ] 0% 2024-06-23 00:27:16,149 INFO waiting: 993192 / active: 40 / done: 1797 2024-06-23 00:27:16,149 INFO ================================================================================ 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO 2024-06-23 00:27:16,149 INFO ================================================================================ 2024-06-23 00:27:16,149 INFO 2024-06-23 00:25:10 25s elapsed 2024-06-23 00:27:16,149 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,149 INFO > Global (OK=6416 KO=0 ) 2024-06-23 00:27:16,149 INFO > restLoginInitiate (OK=2857 KO=0 ) 2024-06-23 00:27:16,149 INFO > restLoginUsernamePasswordCallback (OK=2829 KO=0 ) 2024-06-23 00:27:16,150 INFO > restloginProgressiveProfileCallback (OK=730 KO=0 ) 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,150 INFO [- ] 0% 2024-06-23 00:27:16,150 INFO waiting: 992161 / active: 47 / done: 2821 2024-06-23 00:27:16,150 INFO ================================================================================ 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO ================================================================================ 2024-06-23 00:27:16,150 INFO 2024-06-23 00:25:15 30s elapsed 2024-06-23 00:27:16,150 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,150 INFO > Global (OK=9215 KO=0 ) 2024-06-23 00:27:16,150 INFO > restLoginInitiate (OK=4115 KO=0 ) 2024-06-23 00:27:16,150 INFO > restLoginUsernamePasswordCallback (OK=4070 KO=0 ) 2024-06-23 00:27:16,150 INFO > restloginProgressiveProfileCallback (OK=1030 KO=0 ) 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,150 INFO [- ] 0% 2024-06-23 00:27:16,150 INFO waiting: 990900 / active: 74 / done: 4055 2024-06-23 00:27:16,150 INFO ================================================================================ 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO ================================================================================ 2024-06-23 00:27:16,150 INFO 2024-06-23 00:25:20 35s elapsed 2024-06-23 00:27:16,150 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,150 INFO > Global (OK=12315 KO=0 ) 2024-06-23 00:27:16,150 INFO > restLoginInitiate (OK=5485 KO=0 ) 2024-06-23 00:27:16,150 INFO > restLoginUsernamePasswordCallback (OK=5439 KO=0 ) 2024-06-23 00:27:16,150 INFO > restloginProgressiveProfileCallback (OK=1391 KO=0 ) 2024-06-23 00:27:16,150 INFO 2024-06-23 00:27:16,150 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,150 INFO [- ] 0% 2024-06-23 00:27:16,150 INFO waiting: 989526 / active: 82 / done: 5421 2024-06-23 00:27:16,150 INFO ================================================================================ 2024-06-23 00:27:16,150 INFO [...] 2024-06-23 00:27:16,154 INFO > status.find.is(200), but actually found 502 19 (90.48%) 2024-06-23 00:27:16,154 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 ( 9.52%) 2024-06-23 00:27:16,154 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 00:27:16,154 INFO 2024-06-23 00:27:16,154 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,154 INFO [#########################################################################-] 99% 2024-06-23 00:27:16,154 INFO waiting: 4068 / active: 71 / done: 990890 2024-06-23 00:27:16,154 INFO ================================================================================ 2024-06-23 00:27:16,154 INFO 2024-06-23 00:27:16,154 INFO 2024-06-23 00:27:16,154 INFO ================================================================================ 2024-06-23 00:27:16,155 INFO 2024-06-23 01:25:05 3620s elapsed 2024-06-23 00:27:16,155 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,155 INFO > Global (OK=2201234 KO=21 ) 2024-06-23 00:27:16,155 INFO > restLoginInitiate (OK=992317 KO=12 ) 2024-06-23 00:27:16,155 INFO > restLoginUsernamePasswordCallback (OK=992265 KO=8 ) 2024-06-23 00:27:16,155 INFO > restloginProgressiveProfileCallback (OK=216652 KO=1 ) 2024-06-23 00:27:16,155 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 00:27:16,155 INFO > status.find.is(200), but actually found 502 19 (90.48%) 2024-06-23 00:27:16,155 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 ( 9.52%) 2024-06-23 00:27:16,155 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 00:27:16,155 INFO 2024-06-23 00:27:16,155 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,155 INFO [#########################################################################-] 99% 2024-06-23 00:27:16,155 INFO waiting: 2691 / active: 67 / done: 992271 2024-06-23 00:27:16,155 INFO ================================================================================ 2024-06-23 00:27:16,155 INFO 2024-06-23 00:27:16,155 INFO 2024-06-23 00:27:16,155 INFO ================================================================================ 2024-06-23 00:27:16,155 INFO 2024-06-23 01:25:10 3625s elapsed 2024-06-23 00:27:16,155 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,155 INFO > Global (OK=2204219 KO=21 ) 2024-06-23 00:27:16,155 INFO > restLoginInitiate (OK=993677 KO=12 ) 2024-06-23 00:27:16,155 INFO > restLoginUsernamePasswordCallback (OK=993634 KO=8 ) 2024-06-23 00:27:16,155 INFO > restloginProgressiveProfileCallback (OK=216908 KO=1 ) 2024-06-23 00:27:16,155 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 00:27:16,155 INFO > status.find.is(200), but actually found 502 19 (90.48%) 2024-06-23 00:27:16,155 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 ( 9.52%) 2024-06-23 00:27:16,155 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 00:27:16,155 INFO 2024-06-23 00:27:16,155 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,155 INFO [#########################################################################-] 99% 2024-06-23 00:27:16,155 INFO waiting: 1330 / active: 51 / done: 993648 2024-06-23 00:27:16,155 INFO ================================================================================ 2024-06-23 00:27:16,155 INFO 2024-06-23 00:27:16,155 INFO 2024-06-23 00:27:16,155 INFO ================================================================================ 2024-06-23 00:27:16,155 INFO 2024-06-23 01:25:15 3630s elapsed 2024-06-23 00:27:16,155 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,155 INFO > Global (OK=2207071 KO=21 ) 2024-06-23 00:27:16,156 INFO > restLoginInitiate (OK=994998 KO=12 ) 2024-06-23 00:27:16,156 INFO > restLoginUsernamePasswordCallback (OK=994933 KO=8 ) 2024-06-23 00:27:16,156 INFO > restloginProgressiveProfileCallback (OK=217140 KO=1 ) 2024-06-23 00:27:16,156 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 00:27:16,156 INFO > status.find.is(200), but actually found 502 19 (90.48%) 2024-06-23 00:27:16,156 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 ( 9.52%) 2024-06-23 00:27:16,156 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 00:27:16,156 INFO 2024-06-23 00:27:16,156 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,156 INFO [#########################################################################-] 99% 2024-06-23 00:27:16,156 INFO waiting: 0 / active: 83 / done: 994946 2024-06-23 00:27:16,156 INFO ================================================================================ 2024-06-23 00:27:16,156 INFO 2024-06-23 00:27:16,156 INFO 2024-06-23 00:27:16,156 INFO ================================================================================ 2024-06-23 00:27:16,156 INFO 2024-06-23 01:25:16 3630s elapsed 2024-06-23 00:27:16,156 INFO ---- Requests ------------------------------------------------------------------ 2024-06-23 00:27:16,156 INFO > Global (OK=2207185 KO=21 ) 2024-06-23 00:27:16,156 INFO > restLoginInitiate (OK=995017 KO=12 ) 2024-06-23 00:27:16,156 INFO > restLoginUsernamePasswordCallback (OK=995009 KO=8 ) 2024-06-23 00:27:16,156 INFO > restloginProgressiveProfileCallback (OK=217159 KO=1 ) 2024-06-23 00:27:16,156 INFO ---- Errors -------------------------------------------------------------------- 2024-06-23 00:27:16,156 INFO > status.find.is(200), but actually found 502 19 (90.48%) 2024-06-23 00:27:16,156 INFO > i.n.c.ConnectTimeoutException: connection timed out: openam-em 2 ( 9.52%) 2024-06-23 00:27:16,156 INFO a-perf-p1.forgeblocks.com/35.241.59.5:443 2024-06-23 00:27:16,156 INFO 2024-06-23 00:27:16,156 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-23 00:27:16,156 INFO [##########################################################################]100% 2024-06-23 00:27:16,156 INFO waiting: 0 / active: 0 / done: 995029 2024-06-23 00:27:16,156 INFO ================================================================================ 2024-06-23 00:27:16,156 INFO 2024-06-23 00:27:16,156 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-23 00:27:16,156 INFO 2024-06-23 00:27:16,156 INFO ---------------------- 2024-06-23 00:27:16,156 INFO [23/Jun/2024 01:25:16] Create tar file login-tree-275.tar.gz 2024-06-23 00:27:16,156 INFO ---------------------- 2024-06-23 00:27:16,156 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-23 00:27:16,156 INFO RESULT_FOLDER=login-tree-275 2024-06-23 00:27:16,156 INFO SIMULATION_PATH=login-tree-275/loadtest-20240622232445476 2024-06-23 00:27:16,156 INFO PWD=/results/gatling/login-tree-275/loadtest-20240622232445476 2024-06-23 00:27:16,156 INFO [cmd] tar -czvf /results/gatling//login-tree-275.tar.gz --exclude=simulation.log * 2024-06-23 00:27:16,157 INFO /results/gatling 2024-06-23 00:27:16,157 INFO 2024-06-23 00:27:16,157 INFO ---------------------- 2024-06-23 00:27:16,157 INFO [23/Jun/2024 01:25:16] Update /results/gatling//latest 2024-06-23 00:27:16,157 INFO ---------------------- 2024-06-23 00:27:16,157 INFO 2024-06-23 00:27:16,157 INFO ---------------------- 2024-06-23 00:27:16,157 INFO [23/Jun/2024 01:25:16] Update /results/gatling//latest.tar.gz 2024-06-23 00:27:16,157 INFO ---------------------- 2024-06-23 00:27:16,157 INFO ________________________________________________________________________________ [2024-06-23 00:27:16] login-tree-275 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-23 00:27:16,157 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stderr.txt is small (0 lines), printing all lines: 2024-06-23 00:27:16,157 DEBUG ----- output ----- 2024-06-23 00:27:16,157 DEBUG 2024-06-23 00:27:16,157 INFO ________________________________________________________________________________ [2024-06-23 00:27:16] login-tree-275 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-06-23 00:27:16] login-tree-275 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-23 00:27:16] login-tree-275 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped