--Task-- name: login-tree-50 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-10'] preceding_task: login-tree-10 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 50, '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 21:33:13] login-tree-50 pre : N/A ________________________________________________________________________________ 2024-06-11 21:33:13 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-11 21:33:13 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-50/stdout.txt Pod log : /results/orders/login-tree-50 2024-06-11 21:33:13,466 INFO 2024-06-11 21:33:13,466 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 21:33:13,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:13,769 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:13,769 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:13,769 DEBUG --- stdout --- 2024-06-11 21:33:13,769 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:13,769 DEBUG --- stderr --- 2024-06-11 21:33:13,769 DEBUG ________________________________________________________________________________ [2024-06-11 21:33:13] login-tree-50 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-11 21:33:13,770 INFO 2024-06-11 21:33:13,770 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 21:33:13,770 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:14,064 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:14,064 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:14,064 DEBUG --- stdout --- 2024-06-11 21:33:14,064 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:14,064 DEBUG --- stderr --- 2024-06-11 21:33:14,064 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-50 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-11 21:33:14] login-tree-50 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-11 21:33:14,065 INFO 2024-06-11 21:33:14,065 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 21:33:14,065 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:14,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:14,364 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:14,364 DEBUG --- stdout --- 2024-06-11 21:33:14,364 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:14,364 DEBUG --- stderr --- 2024-06-11 21:33:14,364 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/order.json { "name": "login-tree-50", "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-50 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-50", "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=50 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-11 21:33:14] login-tree-50 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 21:33:14,366 INFO 2024-06-11 21:33:14,366 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-11 21:33:14,366 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 21:33:14,600 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 21:33:14,600 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:14,600 DEBUG --- stdout --- 2024-06-11 21:33:14,600 DEBUG WAITING 2024-06-11 21:33:14,601 DEBUG --- stderr --- 2024-06-11 21:33:14,601 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-11 21:33:14] login-tree-50 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-11 21:33:14,601 INFO 2024-06-11 21:33:14,601 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 21:33:14,601 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:14,898 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:14,898 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:14,898 DEBUG --- stdout --- 2024-06-11 21:33:14,898 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:14,899 DEBUG --- stderr --- 2024-06-11 21:33:14,899 DEBUG 2024-06-11 21:33:14,899 INFO 2024-06-11 21:33:14,899 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/02_login-tree-50/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-11 21:33:14,899 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 21:33:16,252 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:16,252 DEBUG --- stdout --- 2024-06-11 21:33:16,252 DEBUG 2024-06-11 21:33:16,252 DEBUG --- stderr --- 2024-06-11 21:33:16,252 DEBUG Order has been successfully forwarded to overseer 2024-06-11 21:33:16 - INFO: Check if Gatling simulation started successfully 2024-06-11 21:33:16,253 INFO 2024-06-11 21:33:16,253 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 21:33:16,253 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 21:33:16,567 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 21:33:16,567 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:16,568 DEBUG --- stdout --- 2024-06-11 21:33:16,569 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 21:33:16,569 DEBUG --- stderr --- 2024-06-11 21:33:16,569 DEBUG 2024-06-11 21:33:16,569 INFO 2024-06-11 21:33:16,569 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-50/stdout.txt | grep "started..." 2024-06-11 21:33:16,569 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 21:33:17,317 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-11 21:33:23,091 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-11 21:33:23,091 INFO [loop_until]: OK (rc = 0) 2024-06-11 21:33:23,091 DEBUG --- stdout --- 2024-06-11 21:33:23,091 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 21:33:23,091 DEBUG --- stderr --- 2024-06-11 21:33:23,091 DEBUG ________________________________________________________________________________ [2024-06-11 21:33:23] login-tree-50 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-11 22:35:33] login-tree-50 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-11 22:35:33,534 INFO 2024-06-11 22:35:33,534 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:33,534 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:33,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:33,864 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:33,864 DEBUG --- stdout --- 2024-06-11 22:35:33,864 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:33,864 DEBUG --- stderr --- 2024-06-11 22:35:33,864 DEBUG 2024-06-11 22:35:33,864 INFO 2024-06-11 22:35:33,864 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 22:35:33,864 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 22:35:34,654 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:34,654 DEBUG --- stdout --- 2024-06-11 22:35:34,655 DEBUG 2024-06-11 22:35:34,655 DEBUG --- stderr --- 2024-06-11 22:35:34,655 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-11 22:35:34] login-tree-50 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-11 22:35:34 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/order.json 2024-06-11 22:35:34,655 INFO 2024-06-11 22:35:34,655 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:34,655 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:34,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:34,967 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:34,967 DEBUG --- stdout --- 2024-06-11 22:35:34,967 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:34,967 DEBUG --- stderr --- 2024-06-11 22:35:34,967 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 22:35:34,968 INFO 2024-06-11 22:35:34,968 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:34,968 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:35,282 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:35,282 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:35,282 DEBUG --- stdout --- 2024-06-11 22:35:35,282 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:35,282 DEBUG --- stderr --- 2024-06-11 22:35:35,282 DEBUG 2024-06-11 22:35:35,283 INFO 2024-06-11 22:35:35,283 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-50/rc.txt" ]; then echo "/results/orders/login-tree-50/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-50/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/rc.txt file found" 2024-06-11 22:35:35,283 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 22:35:36,099 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 22:35:36,099 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:36,100 DEBUG --- stdout --- 2024-06-11 22:35:36,101 DEBUG /results/orders/login-tree-50/rc.txt file found 2024-06-11 22:35:36,101 DEBUG --- stderr --- 2024-06-11 22:35:36,101 DEBUG 2024-06-11 22:35:36,102 INFO 2024-06-11 22:35:36,102 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:36,102 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:36,413 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:36,414 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:36,414 DEBUG --- stdout --- 2024-06-11 22:35:36,414 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:36,414 DEBUG --- stderr --- 2024-06-11 22:35:36,414 DEBUG 2024-06-11 22:35:36,414 INFO 2024-06-11 22:35:36,415 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-50/rc.txt 2024-06-11 22:35:37,236 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 22:35:37,236 DEBUG --- stdout --- 2024-06-11 22:35:37,236 DEBUG 4 /results/orders/login-tree-50/rc.txt 2024-06-11 22:35:37,236 DEBUG --- stderr --- 2024-06-11 22:35:37,236 DEBUG 2024-06-11 22:35:37,236 INFO - Download file (size 4k) 2024-06-11 22:35:37,237 INFO 2024-06-11 22:35:37,237 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:37,237 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:37,646 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:37,646 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:37,646 DEBUG --- stdout --- 2024-06-11 22:35:37,647 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:37,647 DEBUG --- stderr --- 2024-06-11 22:35:37,647 DEBUG source : /results/orders/login-tree-50/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/rc.txt 2024-06-11 22:35:37,647 INFO 2024-06-11 22:35:37,647 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:37,647 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:37,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:37,961 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:37,961 DEBUG --- stdout --- 2024-06-11 22:35:37,961 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:37,961 DEBUG --- stderr --- 2024-06-11 22:35:37,961 DEBUG 2024-06-11 22:35:37,962 INFO 2024-06-11 22:35:37,962 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-50/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/rc.txt 2024-06-11 22:35:37,962 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 22:35:38,705 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:38,706 DEBUG --- stdout --- 2024-06-11 22:35:38,706 DEBUG tar: Removing leading `/' from member names 2024-06-11 22:35:38,706 DEBUG --- stderr --- 2024-06-11 22:35:38,706 DEBUG 2024-06-11 22:35:38,706 INFO 2024-06-11 22:35:38,706 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:38,706 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:39,025 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:39,026 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:39,026 DEBUG --- stdout --- 2024-06-11 22:35:39,026 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:39,026 DEBUG --- stderr --- 2024-06-11 22:35:39,026 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 22:35:39,026 INFO 2024-06-11 22:35:39,026 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:39,026 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:39,339 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:39,339 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:39,339 DEBUG --- stdout --- 2024-06-11 22:35:39,339 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:39,339 DEBUG --- stderr --- 2024-06-11 22:35:39,339 DEBUG 2024-06-11 22:35:39,340 INFO 2024-06-11 22:35:39,340 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-50/stderr.txt" ]; then echo "/results/orders/login-tree-50/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-50/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/stderr.txt file found" 2024-06-11 22:35:39,340 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 22:35:40,166 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 22:35:40,166 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:40,166 DEBUG --- stdout --- 2024-06-11 22:35:40,166 DEBUG /results/orders/login-tree-50/stderr.txt file found 2024-06-11 22:35:40,166 DEBUG --- stderr --- 2024-06-11 22:35:40,166 DEBUG 2024-06-11 22:35:40,166 INFO 2024-06-11 22:35:40,166 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:40,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:40,462 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:40,462 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:40,462 DEBUG --- stdout --- 2024-06-11 22:35:40,462 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:40,462 DEBUG --- stderr --- 2024-06-11 22:35:40,462 DEBUG 2024-06-11 22:35:40,462 INFO 2024-06-11 22:35:40,463 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-50/stderr.txt 2024-06-11 22:35:41,265 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 22:35:41,266 DEBUG --- stdout --- 2024-06-11 22:35:41,267 DEBUG 0 /results/orders/login-tree-50/stderr.txt 2024-06-11 22:35:41,267 DEBUG --- stderr --- 2024-06-11 22:35:41,267 DEBUG 2024-06-11 22:35:41,267 INFO - Download file (size 0k) 2024-06-11 22:35:41,267 INFO 2024-06-11 22:35:41,267 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:41,268 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:41,576 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:41,577 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:41,577 DEBUG --- stdout --- 2024-06-11 22:35:41,577 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:41,577 DEBUG --- stderr --- 2024-06-11 22:35:41,577 DEBUG source : /results/orders/login-tree-50/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stderr.txt 2024-06-11 22:35:41,577 INFO 2024-06-11 22:35:41,577 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:41,577 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:41,878 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:41,878 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:41,878 DEBUG --- stdout --- 2024-06-11 22:35:41,878 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:41,878 DEBUG --- stderr --- 2024-06-11 22:35:41,879 DEBUG 2024-06-11 22:35:41,879 INFO 2024-06-11 22:35:41,879 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-50/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stderr.txt 2024-06-11 22:35:41,879 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 22:35:42,658 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:42,659 DEBUG --- stdout --- 2024-06-11 22:35:42,659 DEBUG tar: Removing leading `/' from member names 2024-06-11 22:35:42,659 DEBUG --- stderr --- 2024-06-11 22:35:42,659 DEBUG 2024-06-11 22:35:42,659 INFO 2024-06-11 22:35:42,659 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:42,659 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:42,953 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:42,953 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:42,953 DEBUG --- stdout --- 2024-06-11 22:35:42,953 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:42,953 DEBUG --- stderr --- 2024-06-11 22:35:42,953 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 22:35:42,954 INFO 2024-06-11 22:35:42,954 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:42,954 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:43,249 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:43,249 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:43,249 DEBUG --- stdout --- 2024-06-11 22:35:43,249 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:43,249 DEBUG --- stderr --- 2024-06-11 22:35:43,249 DEBUG 2024-06-11 22:35:43,250 INFO 2024-06-11 22:35:43,250 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-50/stdout.txt" ]; then echo "/results/orders/login-tree-50/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-50/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/stdout.txt file found" 2024-06-11 22:35:43,250 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 22:35:44,055 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 22:35:44,055 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:44,055 DEBUG --- stdout --- 2024-06-11 22:35:44,056 DEBUG /results/orders/login-tree-50/stdout.txt file found 2024-06-11 22:35:44,056 DEBUG --- stderr --- 2024-06-11 22:35:44,056 DEBUG 2024-06-11 22:35:44,056 INFO 2024-06-11 22:35:44,056 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:44,056 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:44,361 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:44,361 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:44,361 DEBUG --- stdout --- 2024-06-11 22:35:44,361 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:44,361 DEBUG --- stderr --- 2024-06-11 22:35:44,361 DEBUG 2024-06-11 22:35:44,361 INFO 2024-06-11 22:35:44,361 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-50/stdout.txt 2024-06-11 22:35:45,166 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 22:35:45,166 DEBUG --- stdout --- 2024-06-11 22:35:45,166 DEBUG 740 /results/orders/login-tree-50/stdout.txt 2024-06-11 22:35:45,167 DEBUG --- stderr --- 2024-06-11 22:35:45,167 DEBUG 2024-06-11 22:35:45,167 INFO - Download file (size 740k) 2024-06-11 22:35:45,167 INFO 2024-06-11 22:35:45,167 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:45,167 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:45,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:45,483 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:45,484 DEBUG --- stdout --- 2024-06-11 22:35:45,484 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:45,484 DEBUG --- stderr --- 2024-06-11 22:35:45,484 DEBUG source : /results/orders/login-tree-50/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stdout.txt 2024-06-11 22:35:45,484 INFO 2024-06-11 22:35:45,484 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 22:35:45,484 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:45,793 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:45,793 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:45,793 DEBUG --- stdout --- 2024-06-11 22:35:45,793 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:45,793 DEBUG --- stderr --- 2024-06-11 22:35:45,793 DEBUG 2024-06-11 22:35:45,794 INFO 2024-06-11 22:35:45,794 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-50/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stdout.txt 2024-06-11 22:35:45,794 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 22:35:46,869 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:46,870 DEBUG --- stdout --- 2024-06-11 22:35:46,870 DEBUG tar: Removing leading `/' from member names 2024-06-11 22:35:46,870 DEBUG --- stderr --- 2024-06-11 22:35:46,870 DEBUG ________________________________________________________________________________ [2024-06-11 22:35:46] login-tree-50 show_cmd : Display order command ________________________________________________________________________________ 2024-06-11 22:35:46,871 INFO 2024-06-11 22:35:46,871 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:46,871 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:47,175 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:47,175 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:47,175 DEBUG --- stdout --- 2024-06-11 22:35:47,175 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:47,175 DEBUG --- stderr --- 2024-06-11 22:35:47,175 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-11 22:35:47,175 INFO 2024-06-11 22:35:47,175 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:47,176 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:47,469 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:47,470 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:47,470 DEBUG --- stdout --- 2024-06-11 22:35:47,470 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:47,470 DEBUG --- stderr --- 2024-06-11 22:35:47,470 DEBUG 2024-06-11 22:35:47,470 INFO 2024-06-11 22:35:47,470 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-50/cmd.txt" ]; then echo "/results/orders/login-tree-50/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-50/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-50/cmd.txt file found" 2024-06-11 22:35:47,470 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 22:35:48,269 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 22:35:48,269 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:48,269 DEBUG --- stdout --- 2024-06-11 22:35:48,269 DEBUG /results/orders/login-tree-50/cmd.txt file found 2024-06-11 22:35:48,269 DEBUG --- stderr --- 2024-06-11 22:35:48,270 DEBUG 2024-06-11 22:35:48,270 INFO 2024-06-11 22:35:48,270 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:48,270 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:48,567 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:48,567 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:48,567 DEBUG --- stdout --- 2024-06-11 22:35:48,568 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:48,568 DEBUG --- stderr --- 2024-06-11 22:35:48,568 DEBUG 2024-06-11 22:35:48,568 INFO 2024-06-11 22:35:48,568 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-50/cmd.txt 2024-06-11 22:35:49,358 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-11 22:35:49,358 DEBUG --- stdout --- 2024-06-11 22:35:49,358 DEBUG 4 /results/orders/login-tree-50/cmd.txt 2024-06-11 22:35:49,358 DEBUG --- stderr --- 2024-06-11 22:35:49,359 DEBUG 2024-06-11 22:35:49,359 INFO - Download file (size 4k) 2024-06-11 22:35:49,359 INFO 2024-06-11 22:35:49,359 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 22:35:49,359 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:49,661 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:49,661 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:49,661 DEBUG --- stdout --- 2024-06-11 22:35:49,661 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:49,661 DEBUG --- stderr --- 2024-06-11 22:35:49,661 DEBUG source : /results/orders/login-tree-50/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/cmd.txt 2024-06-11 22:35:49,662 INFO 2024-06-11 22:35:49,662 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:49,662 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 22:35:49,959 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 22:35:49,959 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:49,959 DEBUG --- stdout --- 2024-06-11 22:35:49,959 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 22:35:49,959 DEBUG --- stderr --- 2024-06-11 22:35:49,959 DEBUG 2024-06-11 22:35:49,960 INFO 2024-06-11 22:35:49,960 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-50/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/cmd.txt 2024-06-11 22:35:49,960 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-11 22:35:50,685 INFO [loop_until]: OK (rc = 0) 2024-06-11 22:35:50,686 DEBUG --- stdout --- 2024-06-11 22:35:50,686 DEBUG tar: Removing leading `/' from member names 2024-06-11 22:35:50,686 DEBUG --- stderr --- 2024-06-11 22:35:50,686 DEBUG 2024-06-11 22:35:50,686 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/cmd.txt is small (1 lines), printing all lines: 2024-06-11 22:35:50,686 DEBUG ----- output ----- 2024-06-11 22:35:50,686 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-50 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-11 22:35:50,686 INFO ________________________________________________________________________________ [2024-06-11 22:35:50] login-tree-50 show_rc : Display rc order ________________________________________________________________________________ 2024-06-11 22:35:50,687 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/rc.txt is small (1 lines), printing all lines: 2024-06-11 22:35:50,687 DEBUG ----- output ----- 2024-06-11 22:35:50,687 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-11 22:35:50,687 INFO ________________________________________________________________________________ [2024-06-11 22:35:50] login-tree-50 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-11 22:35:50,689 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/02_login-tree-50/stdout.txt is 11611 lines): 2024-06-11 22:35:50,689 DEBUG ----- output ----- 2024-06-11 22:35:50,689 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-11 22:35:50,689 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-11 22:35:50,689 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO ================================================================================ 2024-06-11 22:35:50,689 INFO 2024-06-11 22:33:25 5s elapsed 2024-06-11 22:35:50,689 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,689 INFO > Global (OK=38 KO=0 ) 2024-06-11 22:35:50,689 INFO > restLoginInitiate (OK=20 KO=0 ) 2024-06-11 22:35:50,689 INFO > restLoginUsernamePasswordCallback (OK=18 KO=0 ) 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,689 INFO [- ] 0% 2024-06-11 22:35:50,689 INFO waiting: 180395 / active: 3 / done: 18 2024-06-11 22:35:50,689 INFO ================================================================================ 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO ================================================================================ 2024-06-11 22:35:50,689 INFO 2024-06-11 22:33:30 10s elapsed 2024-06-11 22:35:50,689 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,689 INFO > Global (OK=161 KO=0 ) 2024-06-11 22:35:50,689 INFO > restLoginInitiate (OK=82 KO=0 ) 2024-06-11 22:35:50,689 INFO > restLoginUsernamePasswordCallback (OK=79 KO=0 ) 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,689 INFO [- ] 0% 2024-06-11 22:35:50,689 INFO waiting: 180332 / active: 5 / done: 79 2024-06-11 22:35:50,689 INFO ================================================================================ 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO 2024-06-11 22:35:50,689 INFO ================================================================================ 2024-06-11 22:35:50,689 INFO 2024-06-11 22:33:35 15s elapsed 2024-06-11 22:35:50,689 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,689 INFO > Global (OK=366 KO=0 ) 2024-06-11 22:35:50,690 INFO > restLoginInitiate (OK=185 KO=0 ) 2024-06-11 22:35:50,690 INFO > restLoginUsernamePasswordCallback (OK=181 KO=0 ) 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,690 INFO [- ] 0% 2024-06-11 22:35:50,690 INFO waiting: 180228 / active: 7 / done: 181 2024-06-11 22:35:50,690 INFO ================================================================================ 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO ================================================================================ 2024-06-11 22:35:50,690 INFO 2024-06-11 22:33:40 20s elapsed 2024-06-11 22:35:50,690 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,690 INFO > Global (OK=654 KO=0 ) 2024-06-11 22:35:50,690 INFO > restLoginInitiate (OK=330 KO=0 ) 2024-06-11 22:35:50,690 INFO > restLoginUsernamePasswordCallback (OK=324 KO=0 ) 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,690 INFO [- ] 0% 2024-06-11 22:35:50,690 INFO waiting: 180082 / active: 10 / done: 324 2024-06-11 22:35:50,690 INFO ================================================================================ 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO ================================================================================ 2024-06-11 22:35:50,690 INFO 2024-06-11 22:33:45 25s elapsed 2024-06-11 22:35:50,690 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,690 INFO > Global (OK=1028 KO=0 ) 2024-06-11 22:35:50,690 INFO > restLoginInitiate (OK=517 KO=0 ) 2024-06-11 22:35:50,690 INFO > restLoginUsernamePasswordCallback (OK=511 KO=0 ) 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,690 INFO [- ] 0% 2024-06-11 22:35:50,690 INFO waiting: 179895 / active: 10 / done: 511 2024-06-11 22:35:50,690 INFO ================================================================================ 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO 2024-06-11 22:35:50,690 INFO ================================================================================ 2024-06-11 22:35:50,690 INFO 2024-06-11 22:33:50 30s elapsed 2024-06-11 22:35:50,691 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,691 INFO > Global (OK=1484 KO=0 ) 2024-06-11 22:35:50,691 INFO > restLoginInitiate (OK=746 KO=0 ) 2024-06-11 22:35:50,691 INFO > restLoginUsernamePasswordCallback (OK=737 KO=0 ) 2024-06-11 22:35:50,691 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-11 22:35:50,691 INFO 2024-06-11 22:35:50,691 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,691 INFO [- ] 0% 2024-06-11 22:35:50,691 INFO waiting: 179665 / active: 14 / done: 737 2024-06-11 22:35:50,691 INFO ================================================================================ 2024-06-11 22:35:50,691 INFO 2024-06-11 22:35:50,691 INFO 2024-06-11 22:35:50,691 INFO ================================================================================ 2024-06-11 22:35:50,691 INFO 2024-06-11 22:33:55 35s elapsed 2024-06-11 22:35:50,691 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,691 INFO > Global (OK=2009 KO=0 ) 2024-06-11 22:35:50,691 INFO > restLoginInitiate (OK=1010 KO=0 ) 2024-06-11 22:35:50,691 INFO > restLoginUsernamePasswordCallback (OK=998 KO=0 ) 2024-06-11 22:35:50,691 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-11 22:35:50,691 INFO 2024-06-11 22:35:50,691 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,691 INFO [- ] 0% 2024-06-11 22:35:50,691 INFO waiting: 179404 / active: 14 / done: 998 2024-06-11 22:35:50,691 INFO ================================================================================ 2024-06-11 22:35:50,691 INFO 2024-06-11 22:35:50,691 INFO 2024-06-11 22:35:50,691 INFO ================================================================================ 2024-06-11 22:35:50,691 INFO 2024-06-11 22:34:00 40s elapsed 2024-06-11 22:35:50,691 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,691 INFO [...] 2024-06-11 22:35:50,694 INFO 2024-06-11 22:35:50,694 INFO 2024-06-11 22:35:50,694 INFO ================================================================================ 2024-06-11 22:35:50,694 INFO 2024-06-11 23:33:35 3615s elapsed 2024-06-11 22:35:50,694 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,694 INFO > Global (OK=360920 KO=2 ) 2024-06-11 22:35:50,694 INFO > restLoginInitiate (OK=179690 KO=0 ) 2024-06-11 22:35:50,694 INFO > restLoginUsernamePasswordCallback (OK=179681 KO=2 ) 2024-06-11 22:35:50,694 INFO > restloginProgressiveProfileCallback (OK=1549 KO=0 ) 2024-06-11 22:35:50,694 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 22:35:50,694 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 22:35:50,694 INFO 2024-06-11 22:35:50,694 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,694 INFO [#########################################################################-] 99% 2024-06-11 22:35:50,695 INFO waiting: 714 / active: 19 / done: 179683 2024-06-11 22:35:50,695 INFO ================================================================================ 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO ================================================================================ 2024-06-11 22:35:50,695 INFO 2024-06-11 23:33:40 3620s elapsed 2024-06-11 22:35:50,695 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,695 INFO > Global (OK=361409 KO=2 ) 2024-06-11 22:35:50,695 INFO > restLoginInitiate (OK=179939 KO=0 ) 2024-06-11 22:35:50,695 INFO > restLoginUsernamePasswordCallback (OK=179917 KO=2 ) 2024-06-11 22:35:50,695 INFO > restloginProgressiveProfileCallback (OK=1553 KO=0 ) 2024-06-11 22:35:50,695 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 22:35:50,695 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,695 INFO [#########################################################################-] 99% 2024-06-11 22:35:50,695 INFO waiting: 471 / active: 26 / done: 179919 2024-06-11 22:35:50,695 INFO ================================================================================ 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO ================================================================================ 2024-06-11 22:35:50,695 INFO 2024-06-11 23:33:45 3625s elapsed 2024-06-11 22:35:50,695 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,695 INFO > Global (OK=361858 KO=2 ) 2024-06-11 22:35:50,695 INFO > restLoginInitiate (OK=180160 KO=0 ) 2024-06-11 22:35:50,695 INFO > restLoginUsernamePasswordCallback (OK=180140 KO=2 ) 2024-06-11 22:35:50,695 INFO > restloginProgressiveProfileCallback (OK=1558 KO=0 ) 2024-06-11 22:35:50,695 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 22:35:50,695 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,695 INFO [#########################################################################-] 99% 2024-06-11 22:35:50,695 INFO waiting: 253 / active: 21 / done: 180142 2024-06-11 22:35:50,695 INFO ================================================================================ 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO 2024-06-11 22:35:50,695 INFO ================================================================================ 2024-06-11 22:35:50,695 INFO 2024-06-11 23:33:50 3630s elapsed 2024-06-11 22:35:50,695 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,695 INFO > Global (OK=362376 KO=2 ) 2024-06-11 22:35:50,696 INFO > restLoginInitiate (OK=180411 KO=0 ) 2024-06-11 22:35:50,696 INFO > restLoginUsernamePasswordCallback (OK=180403 KO=2 ) 2024-06-11 22:35:50,696 INFO > restloginProgressiveProfileCallback (OK=1562 KO=0 ) 2024-06-11 22:35:50,696 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 22:35:50,696 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,696 INFO [#########################################################################-] 99% 2024-06-11 22:35:50,696 INFO waiting: 0 / active: 11 / done: 180405 2024-06-11 22:35:50,696 INFO ================================================================================ 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO ================================================================================ 2024-06-11 22:35:50,696 INFO 2024-06-11 23:33:50 3630s elapsed 2024-06-11 22:35:50,696 INFO ---- Requests ------------------------------------------------------------------ 2024-06-11 22:35:50,696 INFO > Global (OK=362392 KO=2 ) 2024-06-11 22:35:50,696 INFO > restLoginInitiate (OK=180416 KO=0 ) 2024-06-11 22:35:50,696 INFO > restLoginUsernamePasswordCallback (OK=180414 KO=2 ) 2024-06-11 22:35:50,696 INFO > restloginProgressiveProfileCallback (OK=1562 KO=0 ) 2024-06-11 22:35:50,696 INFO ---- Errors -------------------------------------------------------------------- 2024-06-11 22:35:50,696 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-11 22:35:50,696 INFO [##########################################################################]100% 2024-06-11 22:35:50,696 INFO waiting: 0 / active: 0 / done: 180416 2024-06-11 22:35:50,696 INFO ================================================================================ 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO ---------------------- 2024-06-11 22:35:50,696 INFO [11/Jun/2024 23:33:51] Create tar file login-tree-50.tar.gz 2024-06-11 22:35:50,696 INFO ---------------------- 2024-06-11 22:35:50,696 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-11 22:35:50,696 INFO RESULT_FOLDER=login-tree-50 2024-06-11 22:35:50,696 INFO SIMULATION_PATH=login-tree-50/loadtest-20240611213319854 2024-06-11 22:35:50,696 INFO PWD=/results/gatling/login-tree-50/loadtest-20240611213319854 2024-06-11 22:35:50,696 INFO [cmd] tar -czvf /results/gatling//login-tree-50.tar.gz --exclude=simulation.log * 2024-06-11 22:35:50,696 INFO /results/gatling 2024-06-11 22:35:50,696 INFO 2024-06-11 22:35:50,696 INFO ---------------------- 2024-06-11 22:35:50,697 INFO [11/Jun/2024 23:33:51] Update /results/gatling//latest 2024-06-11 22:35:50,697 INFO ---------------------- 2024-06-11 22:35:50,697 INFO 2024-06-11 22:35:50,697 INFO ---------------------- 2024-06-11 22:35:50,697 INFO [11/Jun/2024 23:33:51] Update /results/gatling//latest.tar.gz 2024-06-11 22:35:50,697 INFO ---------------------- 2024-06-11 22:35:50,697 INFO ________________________________________________________________________________ [2024-06-11 22:35:50] login-tree-50 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-11 22:35:50,697 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-50/stderr.txt is small (0 lines), printing all lines: 2024-06-11 22:35:50,697 DEBUG ----- output ----- 2024-06-11 22:35:50,697 DEBUG 2024-06-11 22:35:50,697 INFO ________________________________________________________________________________ [2024-06-11 22:35:50] login-tree-50 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 22:35:50] login-tree-50 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-11 22:35:50] login-tree-50 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped