--Task-- name: login-tree-100 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-50'] preceding_task: login-tree-50 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 100, '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-11 22:35:51] login-tree-100 pre : N/A ________________________________________________________________________________ 2024-06-11 22:35:51 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-11 22:35:51 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-100/stdout.txt Pod log : /results/orders/login-tree-100 2024-06-11 22:35:51,492 INFO 2024-06-11 22:35:51,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-11 22:35:51,493 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:51,793 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:51,793 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:51,795 DEBUG --- stdout --- 2024-06-11 22:35:51,795 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:51,795 DEBUG --- stderr --- 2024-06-11 22:35:51,795 DEBUG ________________________________________________________________________________ [2024-06-11 22:35:51] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-11 22:35:51,795 INFO 2024-06-11 22:35:51,796 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-11 22:35:51,796 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:52,097 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:52,097 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:52,097 DEBUG --- stdout --- 2024-06-11 22:35:52,097 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:52,097 DEBUG --- stderr --- 2024-06-11 22:35:52,097 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-p1protect" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-11 22:35:52] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-11 22:35:52,098 INFO 2024-06-11 22:35:52,098 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-11 22:35:52,098 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:52,442 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:52,443 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:52,443 DEBUG --- stdout --- 2024-06-11 22:35:52,443 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:52,443 DEBUG --- stderr --- 2024-06-11 22:35:52,443 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/order.json { "name": "login-tree-100", "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-p1protect\" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-100", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -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=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-11 22:35:52] login-tree-100 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-11 22:35:52,445 INFO 2024-06-11 22:35:52,445 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-11 22:35:52,445 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 22:35:52,717 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 22:35:52,717 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:52,717 DEBUG --- stdout --- 2024-06-11 22:35:52,717 DEBUG WAITING 2024-06-11 22:35:52,717 DEBUG --- stderr --- 2024-06-11 22:35:52,717 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-06-11 22:35:52] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-11 22:35:52,718 INFO 2024-06-11 22:35:52,718 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-11 22:35:52,718 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:53,022 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:53,023 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:53,023 DEBUG --- stdout --- 2024-06-11 22:35:53,023 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:53,023 DEBUG --- stderr --- 2024-06-11 22:35:53,023 DEBUG 2024-06-11 22:35:53,023 INFO 2024-06-11 22:35:53,023 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-11 22:35:53,023 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 22:35:54,388 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:54,388 DEBUG --- stdout --- 2024-06-11 22:35:54,388 DEBUG 2024-06-11 22:35:54,388 DEBUG --- stderr --- 2024-06-11 22:35:54,388 DEBUG Order has been successfully forwarded to overseer 2024-06-11 22:35:54 - INFO: Check if Gatling simulation started successfully 2024-06-11 22:35:54,388 INFO 2024-06-11 22:35:54,388 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-11 22:35:54,388 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:54,695 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:54,695 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:54,695 DEBUG --- stdout --- 2024-06-11 22:35:54,696 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:54,696 DEBUG --- stderr --- 2024-06-11 22:35:54,696 DEBUG 2024-06-11 22:35:54,696 INFO 2024-06-11 22:35:54,696 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-100/stdout.txt | grep "started..." 2024-06-11 22:35:54,696 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 22:35:55,423 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-11 22:36:01,174 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-11 22:36:01,174 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:36:01,174 DEBUG --- stdout --- 2024-06-11 22:36:01,174 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-11 22:36:01,174 DEBUG --- stderr --- 2024-06-11 22:36:01,174 DEBUG ________________________________________________________________________________ [2024-06-11 22:36:01] login-tree-100 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, 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-11 23:38:11] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-11 23:38:11,469 INFO 2024-06-11 23:38:11,469 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-11 23:38:11,469 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:11,800 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:11,800 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:11,800 DEBUG --- stdout --- 2024-06-11 23:38:11,800 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:11,800 DEBUG --- stderr --- 2024-06-11 23:38:11,800 DEBUG 2024-06-11 23:38:11,800 INFO 2024-06-11 23:38:11,801 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-11 23:38:11,801 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 23:38:12,578 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:12,578 DEBUG --- stdout --- 2024-06-11 23:38:12,578 DEBUG 2024-06-11 23:38:12,578 DEBUG --- stderr --- 2024-06-11 23:38:12,578 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-11 23:38:12] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-11 23:38:12 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/order.json 2024-06-11 23:38:12,579 INFO 2024-06-11 23:38:12,579 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-11 23:38:12,579 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:12,888 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:12,888 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:12,889 DEBUG --- stdout --- 2024-06-11 23:38:12,889 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:12,889 DEBUG --- stderr --- 2024-06-11 23:38:12,889 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 23:38:12,889 INFO 2024-06-11 23:38:12,889 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-11 23:38:12,889 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:13,239 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:13,239 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:13,239 DEBUG --- stdout --- 2024-06-11 23:38:13,239 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:13,239 DEBUG --- stderr --- 2024-06-11 23:38:13,240 DEBUG 2024-06-11 23:38:13,240 INFO 2024-06-11 23:38:13,240 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-100/rc.txt" ]; then echo "/results/orders/login-tree-100/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/rc.txt file found" 2024-06-11 23:38:13,240 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 23:38:14,075 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 23:38:14,075 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:14,075 DEBUG --- stdout --- 2024-06-11 23:38:14,075 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-06-11 23:38:14,076 DEBUG --- stderr --- 2024-06-11 23:38:14,076 DEBUG 2024-06-11 23:38:14,076 INFO 2024-06-11 23:38:14,076 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-11 23:38:14,076 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:14,392 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:14,392 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:14,392 DEBUG --- stdout --- 2024-06-11 23:38:14,392 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:14,392 DEBUG --- stderr --- 2024-06-11 23:38:14,392 DEBUG 2024-06-11 23:38:14,392 INFO 2024-06-11 23:38:14,392 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-100/rc.txt 2024-06-11 23:38:15,214 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 23:38:15,215 DEBUG --- stdout --- 2024-06-11 23:38:15,215 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-06-11 23:38:15,215 DEBUG --- stderr --- 2024-06-11 23:38:15,215 DEBUG 2024-06-11 23:38:15,215 INFO - Download file (size 4k) 2024-06-11 23:38:15,215 INFO 2024-06-11 23:38:15,215 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-11 23:38:15,215 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:15,530 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:15,530 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:15,530 DEBUG --- stdout --- 2024-06-11 23:38:15,530 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:15,530 DEBUG --- stderr --- 2024-06-11 23:38:15,530 DEBUG source : /results/orders/login-tree-100/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/rc.txt 2024-06-11 23:38:15,531 INFO 2024-06-11 23:38:15,531 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-11 23:38:15,531 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:15,841 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:15,842 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:15,842 DEBUG --- stdout --- 2024-06-11 23:38:15,842 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:15,842 DEBUG --- stderr --- 2024-06-11 23:38:15,842 DEBUG 2024-06-11 23:38:15,842 INFO 2024-06-11 23:38:15,842 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/rc.txt 2024-06-11 23:38:15,843 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 23:38:16,576 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:16,576 DEBUG --- stdout --- 2024-06-11 23:38:16,576 DEBUG tar: Removing leading `/' from member names 2024-06-11 23:38:16,576 DEBUG --- stderr --- 2024-06-11 23:38:16,576 DEBUG 2024-06-11 23:38:16,576 INFO 2024-06-11 23:38:16,576 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-11 23:38:16,576 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:16,887 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:16,887 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:16,887 DEBUG --- stdout --- 2024-06-11 23:38:16,887 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:16,888 DEBUG --- stderr --- 2024-06-11 23:38:16,888 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 23:38:16,888 INFO 2024-06-11 23:38:16,888 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-11 23:38:16,888 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:17,199 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:17,199 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:17,199 DEBUG --- stdout --- 2024-06-11 23:38:17,200 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:17,200 DEBUG --- stderr --- 2024-06-11 23:38:17,200 DEBUG 2024-06-11 23:38:17,200 INFO 2024-06-11 23:38:17,200 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-100/stderr.txt" ]; then echo "/results/orders/login-tree-100/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stderr.txt file found" 2024-06-11 23:38:17,200 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 23:38:18,004 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 23:38:18,004 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:18,005 DEBUG --- stdout --- 2024-06-11 23:38:18,005 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-06-11 23:38:18,005 DEBUG --- stderr --- 2024-06-11 23:38:18,005 DEBUG 2024-06-11 23:38:18,005 INFO 2024-06-11 23:38:18,005 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-11 23:38:18,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:18,302 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:18,303 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:18,303 DEBUG --- stdout --- 2024-06-11 23:38:18,303 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:18,303 DEBUG --- stderr --- 2024-06-11 23:38:18,303 DEBUG 2024-06-11 23:38:18,303 INFO 2024-06-11 23:38:18,303 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-100/stderr.txt 2024-06-11 23:38:19,102 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 23:38:19,102 DEBUG --- stdout --- 2024-06-11 23:38:19,103 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-06-11 23:38:19,103 DEBUG --- stderr --- 2024-06-11 23:38:19,103 DEBUG 2024-06-11 23:38:19,103 INFO - Download file (size 0k) 2024-06-11 23:38:19,103 INFO 2024-06-11 23:38:19,103 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 23:38:19,103 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:19,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:19,408 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:19,408 DEBUG --- stdout --- 2024-06-11 23:38:19,408 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:19,408 DEBUG --- stderr --- 2024-06-11 23:38:19,408 DEBUG source : /results/orders/login-tree-100/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stderr.txt 2024-06-11 23:38:19,409 INFO 2024-06-11 23:38:19,409 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-11 23:38:19,409 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:19,707 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:19,708 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:19,708 DEBUG --- stdout --- 2024-06-11 23:38:19,708 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:19,708 DEBUG --- stderr --- 2024-06-11 23:38:19,708 DEBUG 2024-06-11 23:38:19,708 INFO 2024-06-11 23:38:19,708 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stderr.txt 2024-06-11 23:38:19,709 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 23:38:20,432 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:20,433 DEBUG --- stdout --- 2024-06-11 23:38:20,433 DEBUG tar: Removing leading `/' from member names 2024-06-11 23:38:20,433 DEBUG --- stderr --- 2024-06-11 23:38:20,433 DEBUG 2024-06-11 23:38:20,433 INFO 2024-06-11 23:38:20,433 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-11 23:38:20,433 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:20,731 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:20,731 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:20,731 DEBUG --- stdout --- 2024-06-11 23:38:20,731 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:20,731 DEBUG --- stderr --- 2024-06-11 23:38:20,732 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 23:38:20,732 INFO 2024-06-11 23:38:20,732 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-11 23:38:20,732 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:21,035 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:21,036 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:21,036 DEBUG --- stdout --- 2024-06-11 23:38:21,036 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:21,036 DEBUG --- stderr --- 2024-06-11 23:38:21,036 DEBUG 2024-06-11 23:38:21,036 INFO 2024-06-11 23:38:21,036 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-100/stdout.txt" ]; then echo "/results/orders/login-tree-100/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stdout.txt file found" 2024-06-11 23:38:21,036 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 23:38:21,851 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 23:38:21,851 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:21,853 DEBUG --- stdout --- 2024-06-11 23:38:21,853 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-06-11 23:38:21,853 DEBUG --- stderr --- 2024-06-11 23:38:21,853 DEBUG 2024-06-11 23:38:21,853 INFO 2024-06-11 23:38:21,853 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-11 23:38:21,854 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:22,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:22,161 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:22,161 DEBUG --- stdout --- 2024-06-11 23:38:22,161 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:22,161 DEBUG --- stderr --- 2024-06-11 23:38:22,161 DEBUG 2024-06-11 23:38:22,161 INFO 2024-06-11 23:38:22,162 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-100/stdout.txt 2024-06-11 23:38:23,008 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 23:38:23,008 DEBUG --- stdout --- 2024-06-11 23:38:23,008 DEBUG 776 /results/orders/login-tree-100/stdout.txt 2024-06-11 23:38:23,008 DEBUG --- stderr --- 2024-06-11 23:38:23,008 DEBUG 2024-06-11 23:38:23,008 INFO - Download file (size 776k) 2024-06-11 23:38:23,009 INFO 2024-06-11 23:38:23,009 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-11 23:38:23,009 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:23,313 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:23,313 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:23,313 DEBUG --- stdout --- 2024-06-11 23:38:23,313 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:23,313 DEBUG --- stderr --- 2024-06-11 23:38:23,313 DEBUG source : /results/orders/login-tree-100/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stdout.txt 2024-06-11 23:38:23,313 INFO 2024-06-11 23:38:23,313 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-11 23:38:23,313 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:23,614 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:23,614 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:23,614 DEBUG --- stdout --- 2024-06-11 23:38:23,614 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:23,614 DEBUG --- stderr --- 2024-06-11 23:38:23,614 DEBUG 2024-06-11 23:38:23,614 INFO 2024-06-11 23:38:23,615 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stdout.txt 2024-06-11 23:38:23,615 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 23:38:24,687 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:24,687 DEBUG --- stdout --- 2024-06-11 23:38:24,687 DEBUG tar: Removing leading `/' from member names 2024-06-11 23:38:24,687 DEBUG --- stderr --- 2024-06-11 23:38:24,687 DEBUG ________________________________________________________________________________ [2024-06-11 23:38:24] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-06-11 23:38:24,688 INFO 2024-06-11 23:38:24,688 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-11 23:38:24,688 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:25,003 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:25,003 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:25,004 DEBUG --- stdout --- 2024-06-11 23:38:25,004 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:25,004 DEBUG --- stderr --- 2024-06-11 23:38:25,004 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 23:38:25,004 INFO 2024-06-11 23:38:25,004 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-11 23:38:25,004 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:25,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:25,312 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:25,312 DEBUG --- stdout --- 2024-06-11 23:38:25,312 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:25,312 DEBUG --- stderr --- 2024-06-11 23:38:25,312 DEBUG 2024-06-11 23:38:25,313 INFO 2024-06-11 23:38:25,313 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-100/cmd.txt" ]; then echo "/results/orders/login-tree-100/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/cmd.txt file found" 2024-06-11 23:38:25,313 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 23:38:26,121 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 23:38:26,122 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:26,122 DEBUG --- stdout --- 2024-06-11 23:38:26,122 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-06-11 23:38:26,122 DEBUG --- stderr --- 2024-06-11 23:38:26,122 DEBUG 2024-06-11 23:38:26,122 INFO 2024-06-11 23:38:26,122 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-11 23:38:26,123 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:26,443 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:26,443 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:26,443 DEBUG --- stdout --- 2024-06-11 23:38:26,444 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:26,444 DEBUG --- stderr --- 2024-06-11 23:38:26,444 DEBUG 2024-06-11 23:38:26,444 INFO 2024-06-11 23:38:26,444 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-100/cmd.txt 2024-06-11 23:38:27,257 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 23:38:27,257 DEBUG --- stdout --- 2024-06-11 23:38:27,258 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-06-11 23:38:27,258 DEBUG --- stderr --- 2024-06-11 23:38:27,258 DEBUG 2024-06-11 23:38:27,258 INFO - Download file (size 4k) 2024-06-11 23:38:27,259 INFO 2024-06-11 23:38:27,259 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-11 23:38:27,259 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:27,558 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:27,558 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:27,558 DEBUG --- stdout --- 2024-06-11 23:38:27,558 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:27,558 DEBUG --- stderr --- 2024-06-11 23:38:27,558 DEBUG source : /results/orders/login-tree-100/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/cmd.txt 2024-06-11 23:38:27,559 INFO 2024-06-11 23:38:27,559 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-11 23:38:27,559 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:27,856 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:27,856 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:27,856 DEBUG --- stdout --- 2024-06-11 23:38:27,856 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:27,856 DEBUG --- stderr --- 2024-06-11 23:38:27,856 DEBUG 2024-06-11 23:38:27,857 INFO 2024-06-11 23:38:27,857 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/cmd.txt 2024-06-11 23:38:27,857 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 23:38:28,577 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:28,577 DEBUG --- stdout --- 2024-06-11 23:38:28,577 DEBUG tar: Removing leading `/' from member names 2024-06-11 23:38:28,577 DEBUG --- stderr --- 2024-06-11 23:38:28,577 DEBUG 2024-06-11 23:38:28,577 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/cmd.txt is small (1 lines), printing all lines: 2024-06-11 23:38:28,577 DEBUG ----- output ----- 2024-06-11 23:38:28,577 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-p1protect" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-11 23:38:28,577 INFO ________________________________________________________________________________ [2024-06-11 23:38:28] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-06-11 23:38:28,578 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/rc.txt is small (1 lines), printing all lines: 2024-06-11 23:38:28,578 DEBUG ----- output ----- 2024-06-11 23:38:28,578 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-11 23:38:28,578 INFO ________________________________________________________________________________ [2024-06-11 23:38:28] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-11 23:38:28,580 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stdout.txt is 11902 lines): 2024-06-11 23:38:28,580 DEBUG ----- output ----- 2024-06-11 23:38:28,580 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-11 23:38:28,580 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-11 23:38:28,580 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-11 23:38:28,580 INFO 2024-06-11 23:38:28,580 INFO ================================================================================ 2024-06-11 23:38:28,580 INFO 2024-06-11 23:36:03 5s elapsed 2024-06-11 23:38:28,580 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,580 INFO > Global (OK=78 KO=0 ) 2024-06-11 23:38:28,580 INFO > restLoginInitiate (OK=40 KO=0 ) 2024-06-11 23:38:28,580 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-06-11 23:38:28,580 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-11 23:38:28,580 INFO 2024-06-11 23:38:28,580 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,580 INFO [- ] 0% 2024-06-11 23:38:28,581 INFO waiting: 361022 / active: 5 / done: 37 2024-06-11 23:38:28,581 INFO ================================================================================ 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO ================================================================================ 2024-06-11 23:38:28,581 INFO 2024-06-11 23:36:08 10s elapsed 2024-06-11 23:38:28,581 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,581 INFO > Global (OK=327 KO=0 ) 2024-06-11 23:38:28,581 INFO > restLoginInitiate (OK=164 KO=0 ) 2024-06-11 23:38:28,581 INFO > restLoginUsernamePasswordCallback (OK=158 KO=0 ) 2024-06-11 23:38:28,581 INFO > restloginProgressiveProfileCallback (OK=5 KO=0 ) 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,581 INFO [- ] 0% 2024-06-11 23:38:28,581 INFO waiting: 360897 / active: 9 / done: 158 2024-06-11 23:38:28,581 INFO ================================================================================ 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO ================================================================================ 2024-06-11 23:38:28,581 INFO 2024-06-11 23:36:13 15s elapsed 2024-06-11 23:38:28,581 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,581 INFO > Global (OK=741 KO=0 ) 2024-06-11 23:38:28,581 INFO > restLoginInitiate (OK=371 KO=0 ) 2024-06-11 23:38:28,581 INFO > restLoginUsernamePasswordCallback (OK=361 KO=0 ) 2024-06-11 23:38:28,581 INFO > restloginProgressiveProfileCallback (OK=9 KO=0 ) 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,581 INFO [- ] 0% 2024-06-11 23:38:28,581 INFO waiting: 360688 / active: 15 / done: 361 2024-06-11 23:38:28,581 INFO ================================================================================ 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO 2024-06-11 23:38:28,581 INFO ================================================================================ 2024-06-11 23:38:28,581 INFO 2024-06-11 23:36:18 20s elapsed 2024-06-11 23:38:28,581 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,581 INFO > Global (OK=1330 KO=0 ) 2024-06-11 23:38:28,581 INFO > restLoginInitiate (OK=661 KO=0 ) 2024-06-11 23:38:28,581 INFO > restLoginUsernamePasswordCallback (OK=650 KO=0 ) 2024-06-11 23:38:28,581 INFO > restloginProgressiveProfileCallback (OK=19 KO=0 ) 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,582 INFO [- ] 0% 2024-06-11 23:38:28,582 INFO waiting: 360396 / active: 19 / done: 649 2024-06-11 23:38:28,582 INFO ================================================================================ 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO ================================================================================ 2024-06-11 23:38:28,582 INFO 2024-06-11 23:36:23 25s elapsed 2024-06-11 23:38:28,582 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,582 INFO > Global (OK=2080 KO=0 ) 2024-06-11 23:38:28,582 INFO > restLoginInitiate (OK=1034 KO=0 ) 2024-06-11 23:38:28,582 INFO > restLoginUsernamePasswordCallback (OK=1020 KO=0 ) 2024-06-11 23:38:28,582 INFO > restloginProgressiveProfileCallback (OK=26 KO=0 ) 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,582 INFO [- ] 0% 2024-06-11 23:38:28,582 INFO waiting: 360021 / active: 23 / done: 1020 2024-06-11 23:38:28,582 INFO ================================================================================ 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO ================================================================================ 2024-06-11 23:38:28,582 INFO 2024-06-11 23:36:28 30s elapsed 2024-06-11 23:38:28,582 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,582 INFO > Global (OK=2995 KO=0 ) 2024-06-11 23:38:28,582 INFO > restLoginInitiate (OK=1491 KO=0 ) 2024-06-11 23:38:28,582 INFO > restLoginUsernamePasswordCallback (OK=1475 KO=0 ) 2024-06-11 23:38:28,582 INFO > restloginProgressiveProfileCallback (OK=29 KO=0 ) 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,582 INFO [- ] 0% 2024-06-11 23:38:28,582 INFO waiting: 359562 / active: 29 / done: 1473 2024-06-11 23:38:28,582 INFO ================================================================================ 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO 2024-06-11 23:38:28,582 INFO ================================================================================ 2024-06-11 23:38:28,582 INFO 2024-06-11 23:36:33 35s elapsed 2024-06-11 23:38:28,582 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,582 INFO > Global (OK=4046 KO=0 ) 2024-06-11 23:38:28,583 INFO > restLoginInitiate (OK=2013 KO=0 ) 2024-06-11 23:38:28,583 INFO > restLoginUsernamePasswordCallback (OK=1993 KO=0 ) 2024-06-11 23:38:28,583 INFO > restloginProgressiveProfileCallback (OK=40 KO=0 ) 2024-06-11 23:38:28,583 INFO 2024-06-11 23:38:28,583 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,583 INFO [- ] 0% 2024-06-11 23:38:28,583 INFO waiting: 359045 / active: 27 / done: 1992 2024-06-11 23:38:28,583 INFO ================================================================================ 2024-06-11 23:38:28,583 INFO [...] 2024-06-11 23:38:28,586 INFO 2024-06-11 23:38:28,586 INFO 2024-06-11 23:38:28,586 INFO ================================================================================ 2024-06-11 23:38:28,586 INFO 2024-06-12 00:36:13 3615s elapsed 2024-06-11 23:38:28,586 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,586 INFO > Global (OK=739283 KO=8 ) 2024-06-11 23:38:28,586 INFO > restLoginInitiate (OK=359583 KO=3 ) 2024-06-11 23:38:28,586 INFO > restLoginUsernamePasswordCallback (OK=359564 KO=4 ) 2024-06-11 23:38:28,586 INFO > restloginProgressiveProfileCallback (OK=20136 KO=1 ) 2024-06-11 23:38:28,586 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 23:38:28,586 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-06-11 23:38:28,586 INFO 2024-06-11 23:38:28,586 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,586 INFO [#########################################################################-] 99% 2024-06-11 23:38:28,586 INFO waiting: 1469 / active: 25 / done: 359570 2024-06-11 23:38:28,586 INFO ================================================================================ 2024-06-11 23:38:28,586 INFO 2024-06-11 23:38:28,586 INFO 2024-06-11 23:38:28,586 INFO ================================================================================ 2024-06-11 23:38:28,586 INFO 2024-06-12 00:36:18 3620s elapsed 2024-06-11 23:38:28,586 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,586 INFO > Global (OK=740350 KO=8 ) 2024-06-11 23:38:28,586 INFO > restLoginInitiate (OK=360096 KO=3 ) 2024-06-11 23:38:28,586 INFO > restLoginUsernamePasswordCallback (OK=360072 KO=4 ) 2024-06-11 23:38:28,586 INFO > restloginProgressiveProfileCallback (OK=20182 KO=1 ) 2024-06-11 23:38:28,586 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 23:38:28,586 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-06-11 23:38:28,586 INFO 2024-06-11 23:38:28,586 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,587 INFO [#########################################################################-] 99% 2024-06-11 23:38:28,587 INFO waiting: 954 / active: 32 / done: 360078 2024-06-11 23:38:28,587 INFO ================================================================================ 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO ================================================================================ 2024-06-11 23:38:28,587 INFO 2024-06-12 00:36:23 3625s elapsed 2024-06-11 23:38:28,587 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,587 INFO > Global (OK=741333 KO=8 ) 2024-06-11 23:38:28,587 INFO > restLoginInitiate (OK=360566 KO=3 ) 2024-06-11 23:38:28,587 INFO > restLoginUsernamePasswordCallback (OK=360544 KO=4 ) 2024-06-11 23:38:28,587 INFO > restloginProgressiveProfileCallback (OK=20223 KO=1 ) 2024-06-11 23:38:28,587 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 23:38:28,587 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,587 INFO [#########################################################################-] 99% 2024-06-11 23:38:28,587 INFO waiting: 483 / active: 31 / done: 360550 2024-06-11 23:38:28,587 INFO ================================================================================ 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO ================================================================================ 2024-06-11 23:38:28,587 INFO 2024-06-12 00:36:28 3630s elapsed 2024-06-11 23:38:28,587 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,587 INFO > Global (OK=742343 KO=8 ) 2024-06-11 23:38:28,587 INFO > restLoginInitiate (OK=361053 KO=3 ) 2024-06-11 23:38:28,587 INFO > restLoginUsernamePasswordCallback (OK=361034 KO=4 ) 2024-06-11 23:38:28,587 INFO > restloginProgressiveProfileCallback (OK=20256 KO=1 ) 2024-06-11 23:38:28,587 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 23:38:28,587 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,587 INFO [#########################################################################-] 99% 2024-06-11 23:38:28,587 INFO waiting: 0 / active: 24 / done: 361040 2024-06-11 23:38:28,587 INFO ================================================================================ 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO 2024-06-11 23:38:28,587 INFO ================================================================================ 2024-06-11 23:38:28,587 INFO 2024-06-12 00:36:28 3630s elapsed 2024-06-11 23:38:28,588 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 23:38:28,588 INFO > Global (OK=742377 KO=8 ) 2024-06-11 23:38:28,588 INFO > restLoginInitiate (OK=361061 KO=3 ) 2024-06-11 23:38:28,588 INFO > restLoginUsernamePasswordCallback (OK=361057 KO=4 ) 2024-06-11 23:38:28,588 INFO > restloginProgressiveProfileCallback (OK=20259 KO=1 ) 2024-06-11 23:38:28,588 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 23:38:28,588 INFO > status.find.is(200), but actually found 502 8 (100.0%) 2024-06-11 23:38:28,588 INFO 2024-06-11 23:38:28,588 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 23:38:28,588 INFO [##########################################################################]100% 2024-06-11 23:38:28,588 INFO waiting: 0 / active: 0 / done: 361064 2024-06-11 23:38:28,588 INFO ================================================================================ 2024-06-11 23:38:28,588 INFO 2024-06-11 23:38:28,588 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-11 23:38:28,588 INFO 2024-06-11 23:38:28,588 INFO ---------------------- 2024-06-11 23:38:28,588 INFO [12/Jun/2024 00:36:28] Create tar file login-tree-100.tar.gz 2024-06-11 23:38:28,588 INFO ---------------------- 2024-06-11 23:38:28,588 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-11 23:38:28,588 INFO RESULT_FOLDER=login-tree-100 2024-06-11 23:38:28,588 INFO SIMULATION_PATH=login-tree-100/loadtest-20240611223557979 2024-06-11 23:38:28,588 INFO PWD=/results/gatling/login-tree-100/loadtest-20240611223557979 2024-06-11 23:38:28,588 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-06-11 23:38:28,588 INFO /results/gatling 2024-06-11 23:38:28,588 INFO 2024-06-11 23:38:28,588 INFO ---------------------- 2024-06-11 23:38:28,588 INFO [12/Jun/2024 00:36:28] Update /results/gatling//latest 2024-06-11 23:38:28,588 INFO ---------------------- 2024-06-11 23:38:28,588 INFO 2024-06-11 23:38:28,588 INFO ---------------------- 2024-06-11 23:38:28,588 INFO [12/Jun/2024 00:36:28] Update /results/gatling//latest.tar.gz 2024-06-11 23:38:28,588 INFO ---------------------- 2024-06-11 23:38:28,588 INFO ________________________________________________________________________________ [2024-06-11 23:38:28] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-11 23:38:28,589 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/03_login-tree-100/stderr.txt is small (0 lines), printing all lines: 2024-06-11 23:38:28,589 DEBUG ----- output ----- 2024-06-11 23:38:28,589 DEBUG 2024-06-11 23:38:28,589 INFO ________________________________________________________________________________ [2024-06-11 23:38:28] login-tree-100 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-11 23:38:28] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-11 23:38:28] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped