--Task-- name: login-tree-250 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-200'] preceding_task: login-tree-200 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 250, '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-24 01:30:40] login-tree-250 pre : N/A ________________________________________________________________________________ 2024-06-24 01:30:40 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-24 01:30:40 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-250/stdout.txt Pod log : /results/orders/login-tree-250 2024-06-24 01:30:40,014 INFO 2024-06-24 01:30:40,014 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-24 01:30:40,014 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 01:30:40,328 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 01:30:40,328 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:40,328 DEBUG --- stdout --- 2024-06-24 01:30:40,328 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 01:30:40,328 DEBUG --- stderr --- 2024-06-24 01:30:40,328 DEBUG ________________________________________________________________________________ [2024-06-24 01:30:40] login-tree-250 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-24 01:30:40,329 INFO 2024-06-24 01:30:40,329 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-24 01:30:40,329 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 01:30:40,645 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 01:30:40,645 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:40,645 DEBUG --- stdout --- 2024-06-24 01:30:40,645 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 01:30:40,645 DEBUG --- stderr --- 2024-06-24 01:30:40,645 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-250 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-24 01:30:40] login-tree-250 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-24 01:30:40,646 INFO 2024-06-24 01:30:40,646 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-24 01:30:40,646 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 01:30:40,970 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 01:30:40,971 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:40,971 DEBUG --- stdout --- 2024-06-24 01:30:40,971 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 01:30:40,971 DEBUG --- stderr --- 2024-06-24 01:30:40,971 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json { "name": "login-tree-250", "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-250 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-250", "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=250 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-24 01:30:40] login-tree-250 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-24 01:30:40,973 INFO 2024-06-24 01:30:40,973 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-24 01:30:40,973 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-24 01:30:41,215 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 01:30:41,215 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:41,215 DEBUG --- stdout --- 2024-06-24 01:30:41,215 DEBUG WAITING 2024-06-24 01:30:41,215 DEBUG --- stderr --- 2024-06-24 01:30:41,215 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-24 01:30:41] login-tree-250 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-24 01:30:41,216 INFO 2024-06-24 01:30:41,216 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-24 01:30:41,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 01:30:41,539 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 01:30:41,539 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:41,539 DEBUG --- stdout --- 2024-06-24 01:30:41,539 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 01:30:41,539 DEBUG --- stderr --- 2024-06-24 01:30:41,539 DEBUG 2024-06-24 01:30:41,539 INFO 2024-06-24 01:30:41,540 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/05_login-tree-250/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json 2024-06-24 01:30:41,540 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 01:30:42,916 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:42,917 DEBUG --- stdout --- 2024-06-24 01:30:42,917 DEBUG 2024-06-24 01:30:42,917 DEBUG --- stderr --- 2024-06-24 01:30:42,917 DEBUG Order has been successfully forwarded to overseer 2024-06-24 01:30:42 - INFO: Check if Gatling simulation started successfully 2024-06-24 01:30:42,917 INFO 2024-06-24 01:30:42,917 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-24 01:30:42,917 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 01:30:43,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 01:30:43,233 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:43,233 DEBUG --- stdout --- 2024-06-24 01:30:43,233 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 01:30:43,233 DEBUG --- stderr --- 2024-06-24 01:30:43,233 DEBUG 2024-06-24 01:30:43,233 INFO 2024-06-24 01:30:43,233 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-250/stdout.txt | grep "started..." 2024-06-24 01:30:43,233 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-24 01:30:43,981 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-24 01:30:49,744 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-24 01:30:49,745 INFO [loop_until]: OK (rc = 0) 2024-06-24 01:30:49,745 DEBUG --- stdout --- 2024-06-24 01:30:49,745 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-24 01:30:49,745 DEBUG --- stderr --- 2024-06-24 01:30:49,745 DEBUG ________________________________________________________________________________ [2024-06-24 01:30:49] login-tree-250 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-06-24 02:33:00] login-tree-250 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-24 02:33:00,052 INFO 2024-06-24 02:33:00,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-06-24 02:33:00,053 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:00,385 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:00,385 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:00,386 DEBUG --- stdout --- 2024-06-24 02:33:00,386 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:00,386 DEBUG --- stderr --- 2024-06-24 02:33:00,386 DEBUG 2024-06-24 02:33:00,387 INFO 2024-06-24 02:33:00,387 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-24 02:33:00,387 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 02:33:01,189 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:01,189 DEBUG --- stdout --- 2024-06-24 02:33:01,190 DEBUG 2024-06-24 02:33:01,190 DEBUG --- stderr --- 2024-06-24 02:33:01,190 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-24 02:33:01] login-tree-250 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-24 02:33:01 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json 2024-06-24 02:33:01,191 INFO 2024-06-24 02:33:01,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-24 02:33:01,192 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:01,509 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:01,509 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:01,509 DEBUG --- stdout --- 2024-06-24 02:33:01,509 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:01,509 DEBUG --- stderr --- 2024-06-24 02:33:01,509 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 02:33:01,509 INFO 2024-06-24 02:33:01,509 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-24 02:33:01,510 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:01,812 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:01,812 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:01,812 DEBUG --- stdout --- 2024-06-24 02:33:01,812 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:01,812 DEBUG --- stderr --- 2024-06-24 02:33:01,813 DEBUG 2024-06-24 02:33:01,813 INFO 2024-06-24 02:33:01,813 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-250/rc.txt" ]; then echo "/results/orders/login-tree-250/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-250/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/rc.txt file found" 2024-06-24 02:33:01,813 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 02:33:02,640 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 02:33:02,640 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:02,640 DEBUG --- stdout --- 2024-06-24 02:33:02,641 DEBUG /results/orders/login-tree-250/rc.txt file found 2024-06-24 02:33:02,641 DEBUG --- stderr --- 2024-06-24 02:33:02,641 DEBUG 2024-06-24 02:33:02,641 INFO 2024-06-24 02:33:02,642 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-24 02:33:02,642 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:03,031 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:03,031 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:03,032 DEBUG --- stdout --- 2024-06-24 02:33:03,032 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:03,032 DEBUG --- stderr --- 2024-06-24 02:33:03,032 DEBUG 2024-06-24 02:33:03,032 INFO 2024-06-24 02:33:03,032 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-250/rc.txt 2024-06-24 02:33:03,843 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 02:33:03,844 DEBUG --- stdout --- 2024-06-24 02:33:03,844 DEBUG 4 /results/orders/login-tree-250/rc.txt 2024-06-24 02:33:03,844 DEBUG --- stderr --- 2024-06-24 02:33:03,844 DEBUG 2024-06-24 02:33:03,844 INFO - Download file (size 4k) 2024-06-24 02:33:03,844 INFO 2024-06-24 02:33:03,844 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-24 02:33:03,844 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:04,145 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:04,145 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:04,145 DEBUG --- stdout --- 2024-06-24 02:33:04,145 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:04,145 DEBUG --- stderr --- 2024-06-24 02:33:04,145 DEBUG source : /results/orders/login-tree-250/rc.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt 2024-06-24 02:33:04,145 INFO 2024-06-24 02:33:04,145 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-24 02:33:04,146 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:04,444 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:04,444 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:04,444 DEBUG --- stdout --- 2024-06-24 02:33:04,445 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:04,445 DEBUG --- stderr --- 2024-06-24 02:33:04,445 DEBUG 2024-06-24 02:33:04,445 INFO 2024-06-24 02:33:04,445 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-250/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt 2024-06-24 02:33:04,445 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 02:33:05,177 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:05,178 DEBUG --- stdout --- 2024-06-24 02:33:05,178 DEBUG tar: Removing leading `/' from member names 2024-06-24 02:33:05,178 DEBUG --- stderr --- 2024-06-24 02:33:05,178 DEBUG 2024-06-24 02:33:05,178 INFO 2024-06-24 02:33:05,178 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-24 02:33:05,179 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:05,492 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:05,492 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:05,492 DEBUG --- stdout --- 2024-06-24 02:33:05,492 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:05,492 DEBUG --- stderr --- 2024-06-24 02:33:05,492 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 02:33:05,493 INFO 2024-06-24 02:33:05,493 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-24 02:33:05,493 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:05,815 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:05,815 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:05,815 DEBUG --- stdout --- 2024-06-24 02:33:05,815 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:05,815 DEBUG --- stderr --- 2024-06-24 02:33:05,815 DEBUG 2024-06-24 02:33:05,816 INFO 2024-06-24 02:33:05,816 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-250/stderr.txt" ]; then echo "/results/orders/login-tree-250/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stderr.txt file found" 2024-06-24 02:33:05,816 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 02:33:06,634 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 02:33:06,634 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:06,634 DEBUG --- stdout --- 2024-06-24 02:33:06,635 DEBUG /results/orders/login-tree-250/stderr.txt file found 2024-06-24 02:33:06,635 DEBUG --- stderr --- 2024-06-24 02:33:06,635 DEBUG 2024-06-24 02:33:06,635 INFO 2024-06-24 02:33:06,635 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-24 02:33:06,635 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:06,952 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:06,953 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:06,953 DEBUG --- stdout --- 2024-06-24 02:33:06,953 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:06,953 DEBUG --- stderr --- 2024-06-24 02:33:06,953 DEBUG 2024-06-24 02:33:06,953 INFO 2024-06-24 02:33:06,954 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-250/stderr.txt 2024-06-24 02:33:07,833 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 02:33:07,834 DEBUG --- stdout --- 2024-06-24 02:33:07,834 DEBUG 0 /results/orders/login-tree-250/stderr.txt 2024-06-24 02:33:07,834 DEBUG --- stderr --- 2024-06-24 02:33:07,834 DEBUG 2024-06-24 02:33:07,834 INFO - Download file (size 0k) 2024-06-24 02:33:07,834 INFO 2024-06-24 02:33:07,834 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-24 02:33:07,835 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:08,159 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:08,159 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:08,159 DEBUG --- stdout --- 2024-06-24 02:33:08,159 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:08,159 DEBUG --- stderr --- 2024-06-24 02:33:08,159 DEBUG source : /results/orders/login-tree-250/stderr.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt 2024-06-24 02:33:08,159 INFO 2024-06-24 02:33:08,160 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-24 02:33:08,160 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:08,482 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:08,482 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:08,482 DEBUG --- stdout --- 2024-06-24 02:33:08,482 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:08,482 DEBUG --- stderr --- 2024-06-24 02:33:08,483 DEBUG 2024-06-24 02:33:08,483 INFO 2024-06-24 02:33:08,483 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-250/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt 2024-06-24 02:33:08,483 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 02:33:09,225 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:09,226 DEBUG --- stdout --- 2024-06-24 02:33:09,226 DEBUG tar: Removing leading `/' from member names 2024-06-24 02:33:09,226 DEBUG --- stderr --- 2024-06-24 02:33:09,226 DEBUG 2024-06-24 02:33:09,226 INFO 2024-06-24 02:33:09,226 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-24 02:33:09,226 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:09,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:09,543 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:09,543 DEBUG --- stdout --- 2024-06-24 02:33:09,543 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:09,543 DEBUG --- stderr --- 2024-06-24 02:33:09,543 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 02:33:09,543 INFO 2024-06-24 02:33:09,543 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-24 02:33:09,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:09,866 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:09,866 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:09,866 DEBUG --- stdout --- 2024-06-24 02:33:09,866 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:09,866 DEBUG --- stderr --- 2024-06-24 02:33:09,866 DEBUG 2024-06-24 02:33:09,867 INFO 2024-06-24 02:33:09,867 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-250/stdout.txt" ]; then echo "/results/orders/login-tree-250/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stdout.txt file found" 2024-06-24 02:33:09,867 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 02:33:10,678 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 02:33:10,678 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:10,678 DEBUG --- stdout --- 2024-06-24 02:33:10,679 DEBUG /results/orders/login-tree-250/stdout.txt file found 2024-06-24 02:33:10,679 DEBUG --- stderr --- 2024-06-24 02:33:10,679 DEBUG 2024-06-24 02:33:10,679 INFO 2024-06-24 02:33:10,679 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-24 02:33:10,679 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:11,008 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:11,008 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:11,008 DEBUG --- stdout --- 2024-06-24 02:33:11,008 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:11,008 DEBUG --- stderr --- 2024-06-24 02:33:11,008 DEBUG 2024-06-24 02:33:11,008 INFO 2024-06-24 02:33:11,008 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-250/stdout.txt 2024-06-24 02:33:11,824 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 02:33:11,825 DEBUG --- stdout --- 2024-06-24 02:33:11,825 DEBUG 824 /results/orders/login-tree-250/stdout.txt 2024-06-24 02:33:11,825 DEBUG --- stderr --- 2024-06-24 02:33:11,825 DEBUG 2024-06-24 02:33:11,825 INFO - Download file (size 824k) 2024-06-24 02:33:11,826 INFO 2024-06-24 02:33:11,826 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-24 02:33:11,826 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:12,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:12,129 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:12,130 DEBUG --- stdout --- 2024-06-24 02:33:12,130 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:12,130 DEBUG --- stderr --- 2024-06-24 02:33:12,130 DEBUG source : /results/orders/login-tree-250/stdout.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt 2024-06-24 02:33:12,130 INFO 2024-06-24 02:33:12,130 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-24 02:33:12,130 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:12,434 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:12,434 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:12,434 DEBUG --- stdout --- 2024-06-24 02:33:12,434 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:12,434 DEBUG --- stderr --- 2024-06-24 02:33:12,434 DEBUG 2024-06-24 02:33:12,435 INFO 2024-06-24 02:33:12,435 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-250/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt 2024-06-24 02:33:12,435 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 02:33:13,535 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:13,535 DEBUG --- stdout --- 2024-06-24 02:33:13,535 DEBUG tar: Removing leading `/' from member names 2024-06-24 02:33:13,535 DEBUG --- stderr --- 2024-06-24 02:33:13,535 DEBUG ________________________________________________________________________________ [2024-06-24 02:33:13] login-tree-250 show_cmd : Display order command ________________________________________________________________________________ 2024-06-24 02:33:13,536 INFO 2024-06-24 02:33:13,536 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-24 02:33:13,536 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:13,853 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:13,854 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:13,854 DEBUG --- stdout --- 2024-06-24 02:33:13,854 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:13,854 DEBUG --- stderr --- 2024-06-24 02:33:13,854 DEBUG Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait. 2024-06-24 02:33:13,854 INFO 2024-06-24 02:33:13,854 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-24 02:33:13,854 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:14,168 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:14,169 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:14,169 DEBUG --- stdout --- 2024-06-24 02:33:14,169 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:14,169 DEBUG --- stderr --- 2024-06-24 02:33:14,169 DEBUG 2024-06-24 02:33:14,169 INFO 2024-06-24 02:33:14,170 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-250/cmd.txt" ]; then echo "/results/orders/login-tree-250/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-250/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/cmd.txt file found" 2024-06-24 02:33:14,170 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-24 02:33:14,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-24 02:33:14,984 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:14,985 DEBUG --- stdout --- 2024-06-24 02:33:14,985 DEBUG /results/orders/login-tree-250/cmd.txt file found 2024-06-24 02:33:14,985 DEBUG --- stderr --- 2024-06-24 02:33:14,985 DEBUG 2024-06-24 02:33:14,985 INFO 2024-06-24 02:33:14,985 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-24 02:33:14,985 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:15,287 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:15,288 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:15,288 DEBUG --- stdout --- 2024-06-24 02:33:15,288 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:15,288 DEBUG --- stderr --- 2024-06-24 02:33:15,288 DEBUG 2024-06-24 02:33:15,288 INFO 2024-06-24 02:33:15,288 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-250/cmd.txt 2024-06-24 02:33:16,091 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-24 02:33:16,093 DEBUG --- stdout --- 2024-06-24 02:33:16,093 DEBUG 4 /results/orders/login-tree-250/cmd.txt 2024-06-24 02:33:16,094 DEBUG --- stderr --- 2024-06-24 02:33:16,094 DEBUG 2024-06-24 02:33:16,094 INFO - Download file (size 4k) 2024-06-24 02:33:16,095 INFO 2024-06-24 02:33:16,095 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-24 02:33:16,095 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:16,418 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:16,418 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:16,418 DEBUG --- stdout --- 2024-06-24 02:33:16,418 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:16,418 DEBUG --- stderr --- 2024-06-24 02:33:16,418 DEBUG source : /results/orders/login-tree-250/cmd.txt @ overseer-0-5b6f9464fd-slbm8 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt 2024-06-24 02:33:16,419 INFO 2024-06-24 02:33:16,419 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-24 02:33:16,419 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-24 02:33:16,736 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-24 02:33:16,736 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:16,736 DEBUG --- stdout --- 2024-06-24 02:33:16,736 DEBUG overseer-0-5b6f9464fd-slbm8 2024-06-24 02:33:16,736 DEBUG --- stderr --- 2024-06-24 02:33:16,736 DEBUG 2024-06-24 02:33:16,737 INFO 2024-06-24 02:33:16,737 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-250/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt 2024-06-24 02:33:16,737 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-24 02:33:17,477 INFO [loop_until]: OK (rc = 0) 2024-06-24 02:33:17,478 DEBUG --- stdout --- 2024-06-24 02:33:17,478 DEBUG tar: Removing leading `/' from member names 2024-06-24 02:33:17,478 DEBUG --- stderr --- 2024-06-24 02:33:17,478 DEBUG 2024-06-24 02:33:17,479 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt is small (1 lines), printing all lines: 2024-06-24 02:33:17,479 DEBUG ----- output ----- 2024-06-24 02:33:17,479 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-250 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-24 02:33:17,479 INFO ________________________________________________________________________________ [2024-06-24 02:33:17] login-tree-250 show_rc : Display rc order ________________________________________________________________________________ 2024-06-24 02:33:17,480 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt is small (1 lines), printing all lines: 2024-06-24 02:33:17,480 DEBUG ----- output ----- 2024-06-24 02:33:17,480 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-24 02:33:17,480 INFO ________________________________________________________________________________ [2024-06-24 02:33:17] login-tree-250 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-24 02:33:17,482 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/05_login-tree-250/stdout.txt is 12289 lines): 2024-06-24 02:33:17,482 DEBUG ----- output ----- 2024-06-24 02:33:17,482 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-24 02:33:17,482 INFO Gatling 3.11.4 is available! (you're using 3.9.5) 2024-06-24 02:33:17,482 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-24 02:33:17,482 INFO 2024-06-24 02:33:17,483 INFO ================================================================================ 2024-06-24 02:33:17,483 INFO 2024-06-24 02:30:52 5s elapsed 2024-06-24 02:33:17,483 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,483 INFO > Global (OK=226 KO=0 ) 2024-06-24 02:33:17,483 INFO > restLoginInitiate (OK=103 KO=0 ) 2024-06-24 02:33:17,483 INFO > restLoginUsernamePasswordCallback (OK=98 KO=0 ) 2024-06-24 02:33:17,483 INFO > restloginProgressiveProfileCallback (OK=25 KO=0 ) 2024-06-24 02:33:17,483 INFO 2024-06-24 02:33:17,483 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,483 INFO [- ] 0% 2024-06-24 02:33:17,483 INFO waiting: 903043 / active: 8 / done: 97 2024-06-24 02:33:17,483 INFO ================================================================================ 2024-06-24 02:33:17,483 INFO 2024-06-24 02:33:17,483 INFO 2024-06-24 02:33:17,483 INFO ================================================================================ 2024-06-24 02:33:17,483 INFO 2024-06-24 02:30:57 10s elapsed 2024-06-24 02:33:17,483 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,483 INFO > Global (OK=936 KO=0 ) 2024-06-24 02:33:17,483 INFO > restLoginInitiate (OK=414 KO=0 ) 2024-06-24 02:33:17,483 INFO > restLoginUsernamePasswordCallback (OK=404 KO=0 ) 2024-06-24 02:33:17,483 INFO > restloginProgressiveProfileCallback (OK=118 KO=0 ) 2024-06-24 02:33:17,483 INFO 2024-06-24 02:33:17,483 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,483 INFO [- ] 0% 2024-06-24 02:33:17,483 INFO waiting: 902731 / active: 17 / done: 400 2024-06-24 02:33:17,484 INFO ================================================================================ 2024-06-24 02:33:17,484 INFO 2024-06-24 02:33:17,484 INFO 2024-06-24 02:33:17,484 INFO ================================================================================ 2024-06-24 02:33:17,484 INFO 2024-06-24 02:31:02 15s elapsed 2024-06-24 02:33:17,484 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,484 INFO > Global (OK=2102 KO=0 ) 2024-06-24 02:33:17,484 INFO > restLoginInitiate (OK=932 KO=0 ) 2024-06-24 02:33:17,484 INFO > restLoginUsernamePasswordCallback (OK=919 KO=0 ) 2024-06-24 02:33:17,484 INFO > restloginProgressiveProfileCallback (OK=251 KO=0 ) 2024-06-24 02:33:17,484 INFO 2024-06-24 02:33:17,484 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,484 INFO [- ] 0% 2024-06-24 02:33:17,484 INFO waiting: 902209 / active: 26 / done: 913 2024-06-24 02:33:17,484 INFO ================================================================================ 2024-06-24 02:33:17,484 INFO 2024-06-24 02:33:17,484 INFO 2024-06-24 02:33:17,484 INFO ================================================================================ 2024-06-24 02:33:17,484 INFO 2024-06-24 02:31:07 20s elapsed 2024-06-24 02:33:17,484 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,484 INFO > Global (OK=3734 KO=0 ) 2024-06-24 02:33:17,484 INFO > restLoginInitiate (OK=1660 KO=0 ) 2024-06-24 02:33:17,484 INFO > restLoginUsernamePasswordCallback (OK=1640 KO=0 ) 2024-06-24 02:33:17,484 INFO > restloginProgressiveProfileCallback (OK=434 KO=0 ) 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,485 INFO [- ] 0% 2024-06-24 02:33:17,485 INFO waiting: 901480 / active: 33 / done: 1635 2024-06-24 02:33:17,485 INFO ================================================================================ 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO ================================================================================ 2024-06-24 02:33:17,485 INFO 2024-06-24 02:31:12 25s elapsed 2024-06-24 02:33:17,485 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,485 INFO > Global (OK=5818 KO=0 ) 2024-06-24 02:33:17,485 INFO > restLoginInitiate (OK=2596 KO=0 ) 2024-06-24 02:33:17,485 INFO > restLoginUsernamePasswordCallback (OK=2570 KO=0 ) 2024-06-24 02:33:17,485 INFO > restloginProgressiveProfileCallback (OK=652 KO=0 ) 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,485 INFO [- ] 0% 2024-06-24 02:33:17,485 INFO waiting: 900541 / active: 50 / done: 2557 2024-06-24 02:33:17,485 INFO ================================================================================ 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO ================================================================================ 2024-06-24 02:33:17,485 INFO 2024-06-24 02:31:17 30s elapsed 2024-06-24 02:33:17,485 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,485 INFO > Global (OK=8396 KO=0 ) 2024-06-24 02:33:17,485 INFO > restLoginInitiate (OK=3740 KO=0 ) 2024-06-24 02:33:17,485 INFO > restLoginUsernamePasswordCallback (OK=3703 KO=0 ) 2024-06-24 02:33:17,485 INFO > restloginProgressiveProfileCallback (OK=953 KO=0 ) 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,485 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,485 INFO [- ] 0% 2024-06-24 02:33:17,485 INFO waiting: 899397 / active: 58 / done: 3693 2024-06-24 02:33:17,485 INFO ================================================================================ 2024-06-24 02:33:17,485 INFO 2024-06-24 02:33:17,486 INFO 2024-06-24 02:33:17,486 INFO ================================================================================ 2024-06-24 02:33:17,486 INFO 2024-06-24 02:31:22 35s elapsed 2024-06-24 02:33:17,486 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,486 INFO > Global (OK=11268 KO=0 ) 2024-06-24 02:33:17,486 INFO > restLoginInitiate (OK=5016 KO=0 ) 2024-06-24 02:33:17,486 INFO > restLoginUsernamePasswordCallback (OK=4959 KO=0 ) 2024-06-24 02:33:17,486 INFO > restloginProgressiveProfileCallback (OK=1293 KO=0 ) 2024-06-24 02:33:17,486 INFO 2024-06-24 02:33:17,486 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,486 INFO [- ] 0% 2024-06-24 02:33:17,486 INFO waiting: 898122 / active: 87 / done: 4939 2024-06-24 02:33:17,486 INFO ================================================================================ 2024-06-24 02:33:17,486 INFO [...] 2024-06-24 02:33:17,489 INFO 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO ================================================================================ 2024-06-24 02:33:17,490 INFO 2024-06-24 03:31:02 3615s elapsed 2024-06-24 02:33:17,490 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,490 INFO > Global (OK=2037696 KO=17 ) 2024-06-24 02:33:17,490 INFO > restLoginInitiate (OK=899387 KO=9 ) 2024-06-24 02:33:17,490 INFO > restLoginUsernamePasswordCallback (OK=899348 KO=5 ) 2024-06-24 02:33:17,490 INFO > restloginProgressiveProfileCallback (OK=238961 KO=3 ) 2024-06-24 02:33:17,490 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 02:33:17,490 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,490 INFO [#########################################################################-] 99% 2024-06-24 02:33:17,490 INFO waiting: 3743 / active: 55 / done: 899350 2024-06-24 02:33:17,490 INFO ================================================================================ 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO ================================================================================ 2024-06-24 02:33:17,490 INFO 2024-06-24 03:31:07 3620s elapsed 2024-06-24 02:33:17,490 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,490 INFO > Global (OK=2040541 KO=17 ) 2024-06-24 02:33:17,490 INFO > restLoginInitiate (OK=900662 KO=9 ) 2024-06-24 02:33:17,490 INFO > restLoginUsernamePasswordCallback (OK=900604 KO=5 ) 2024-06-24 02:33:17,490 INFO > restloginProgressiveProfileCallback (OK=239275 KO=3 ) 2024-06-24 02:33:17,490 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 02:33:17,490 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,490 INFO [#########################################################################-] 99% 2024-06-24 02:33:17,490 INFO waiting: 2461 / active: 87 / done: 900600 2024-06-24 02:33:17,490 INFO ================================================================================ 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO 2024-06-24 02:33:17,490 INFO ================================================================================ 2024-06-24 02:33:17,491 INFO 2024-06-24 03:31:12 3625s elapsed 2024-06-24 02:33:17,491 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,491 INFO > Global (OK=2043316 KO=17 ) 2024-06-24 02:33:17,491 INFO > restLoginInitiate (OK=901893 KO=9 ) 2024-06-24 02:33:17,491 INFO > restLoginUsernamePasswordCallback (OK=901838 KO=5 ) 2024-06-24 02:33:17,491 INFO > restloginProgressiveProfileCallback (OK=239585 KO=3 ) 2024-06-24 02:33:17,491 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 02:33:17,491 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 02:33:17,491 INFO 2024-06-24 02:33:17,491 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,491 INFO [#########################################################################-] 99% 2024-06-24 02:33:17,491 INFO waiting: 1230 / active: 74 / done: 901844 2024-06-24 02:33:17,491 INFO ================================================================================ 2024-06-24 02:33:17,491 INFO 2024-06-24 02:33:17,491 INFO 2024-06-24 02:33:17,491 INFO ================================================================================ 2024-06-24 02:33:17,491 INFO 2024-06-24 03:31:17 3630s elapsed 2024-06-24 02:33:17,491 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,491 INFO > Global (OK=2046114 KO=17 ) 2024-06-24 02:33:17,491 INFO > restLoginInitiate (OK=903127 KO=9 ) 2024-06-24 02:33:17,491 INFO > restLoginUsernamePasswordCallback (OK=903093 KO=5 ) 2024-06-24 02:33:17,491 INFO > restloginProgressiveProfileCallback (OK=239894 KO=3 ) 2024-06-24 02:33:17,491 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 02:33:17,491 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 02:33:17,491 INFO 2024-06-24 02:33:17,491 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,491 INFO [#########################################################################-] 99% 2024-06-24 02:33:17,491 INFO waiting: 0 / active: 54 / done: 903094 2024-06-24 02:33:17,491 INFO ================================================================================ 2024-06-24 02:33:17,491 INFO 2024-06-24 02:33:17,491 INFO 2024-06-24 02:33:17,491 INFO ================================================================================ 2024-06-24 02:33:17,491 INFO 2024-06-24 03:31:17 3630s elapsed 2024-06-24 02:33:17,491 INFO ---- Requests ------------------------------------------------------------------ 2024-06-24 02:33:17,491 INFO > Global (OK=2046192 KO=17 ) 2024-06-24 02:33:17,491 INFO > restLoginInitiate (OK=903139 KO=9 ) 2024-06-24 02:33:17,491 INFO > restLoginUsernamePasswordCallback (OK=903134 KO=5 ) 2024-06-24 02:33:17,491 INFO > restloginProgressiveProfileCallback (OK=239919 KO=3 ) 2024-06-24 02:33:17,492 INFO ---- Errors -------------------------------------------------------------------- 2024-06-24 02:33:17,492 INFO > status.find.is(200), but actually found 502 17 (100.0%) 2024-06-24 02:33:17,492 INFO 2024-06-24 02:33:17,492 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-24 02:33:17,492 INFO [##########################################################################]100% 2024-06-24 02:33:17,492 INFO waiting: 0 / active: 0 / done: 903148 2024-06-24 02:33:17,492 INFO ================================================================================ 2024-06-24 02:33:17,492 INFO 2024-06-24 02:33:17,492 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-24 02:33:17,492 INFO 2024-06-24 02:33:17,492 INFO ---------------------- 2024-06-24 02:33:17,492 INFO [24/Jun/2024 03:31:17] Create tar file login-tree-250.tar.gz 2024-06-24 02:33:17,492 INFO ---------------------- 2024-06-24 02:33:17,492 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-24 02:33:17,492 INFO RESULT_FOLDER=login-tree-250 2024-06-24 02:33:17,492 INFO SIMULATION_PATH=login-tree-250/loadtest-20240624013046863 2024-06-24 02:33:17,492 INFO PWD=/results/gatling/login-tree-250/loadtest-20240624013046863 2024-06-24 02:33:17,492 INFO [cmd] tar -czvf /results/gatling//login-tree-250.tar.gz --exclude=simulation.log * 2024-06-24 02:33:17,492 INFO /results/gatling 2024-06-24 02:33:17,492 INFO 2024-06-24 02:33:17,492 INFO ---------------------- 2024-06-24 02:33:17,492 INFO [24/Jun/2024 03:31:17] Update /results/gatling//latest 2024-06-24 02:33:17,492 INFO ---------------------- 2024-06-24 02:33:17,492 INFO 2024-06-24 02:33:17,492 INFO ---------------------- 2024-06-24 02:33:17,492 INFO [24/Jun/2024 03:31:17] Update /results/gatling//latest.tar.gz 2024-06-24 02:33:17,492 INFO ---------------------- 2024-06-24 02:33:17,492 INFO ________________________________________________________________________________ [2024-06-24 02:33:17] login-tree-250 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-24 02:33:17,493 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt is small (0 lines), printing all lines: 2024-06-24 02:33:17,493 DEBUG ----- output ----- 2024-06-24 02:33:17,493 DEBUG 2024-06-24 02:33:17,493 INFO ________________________________________________________________________________ [2024-06-24 02:33:17] login-tree-250 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-24 02:33:17] login-tree-250 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-24 02:33:17] login-tree-250 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped