--Task-- name: login-tree-300 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-200'] preceding_task: login-tree-200 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 300} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 07:44:08] login-tree-300 pre : N/A ________________________________________________________________________________ [2024-05-25 07:44:08] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 07:44:08] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-300/stdout.txt Pod log : /results/orders/login-tree-300 2024-05-25 07:44:08,511 INFO 2024-05-25 07:44:08,511 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:44:08,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:08,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:08,814 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:08,814 DEBUG --- stdout --- 2024-05-25 07:44:08,814 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:08,814 DEBUG --- stderr --- 2024-05-25 07:44:08,814 DEBUG ________________________________________________________________________________ [2024-05-25 07:44:08] login-tree-300 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 07:44:08,815 INFO 2024-05-25 07:44:08,815 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:44:08,815 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:09,125 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:09,125 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:09,125 DEBUG --- stdout --- 2024-05-25 07:44:09,125 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:09,125 DEBUG --- stderr --- 2024-05-25 07:44:09,125 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-3580" --results-folder /results/gatling/login-tree-300 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 07:44:09] login-tree-300 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 07:44:09,126 INFO 2024-05-25 07:44:09,126 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:44:09,126 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:09,438 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:09,439 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:09,439 DEBUG --- stdout --- 2024-05-25 07:44:09,439 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:09,439 DEBUG --- stderr --- 2024-05-25 07:44:09,439 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/order.json { "name": "login-tree-300", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-3580\" --results-folder /results/gatling/login-tree-300 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-300", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-3580.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=9537f3c44bdb077 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=300 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 07:44:09] login-tree-300 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-05-25 07:44:09,442 INFO 2024-05-25 07:44:09,442 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 07:44:09,442 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 07:44:09,691 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 07:44:09,691 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:09,691 DEBUG --- stdout --- 2024-05-25 07:44:09,691 DEBUG WAITING 2024-05-25 07:44:09,691 DEBUG --- stderr --- 2024-05-25 07:44:09,691 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 32 0 --:--:-- --:--:-- --:--:-- 32 ________________________________________________________________________________ [2024-05-25 07:44:09] login-tree-300 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 07:44:09,692 INFO 2024-05-25 07:44:09,692 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:44:09,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:09,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:09,994 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:09,994 DEBUG --- stdout --- 2024-05-25 07:44:09,994 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:09,994 DEBUG --- stderr --- 2024-05-25 07:44:09,994 DEBUG 2024-05-25 07:44:09,994 INFO 2024-05-25 07:44:09,995 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/04_login-tree-300/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 07:44:09,995 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 07:44:11,354 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:11,354 DEBUG --- stdout --- 2024-05-25 07:44:11,354 DEBUG 2024-05-25 07:44:11,354 DEBUG --- stderr --- 2024-05-25 07:44:11,354 DEBUG Order has been successfully forwarded to overseer [2024-05-25 07:44:11] - INFO: Check if Gatling simulation started successfully 2024-05-25 07:44:11,355 INFO 2024-05-25 07:44:11,355 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 07:44:11,355 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 07:44:11,664 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 07:44:11,664 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:11,664 DEBUG --- stdout --- 2024-05-25 07:44:11,665 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 07:44:11,665 DEBUG --- stderr --- 2024-05-25 07:44:11,665 DEBUG 2024-05-25 07:44:11,665 INFO 2024-05-25 07:44:11,665 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- head -n 25 /results/orders/login-tree-300/stdout.txt | grep "started..." 2024-05-25 07:44:11,665 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 07:44:12,397 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 07:44:18,138 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 07:44:18,138 INFO [loop_until]: OK (rc = 0) 2024-05-25 07:44:18,138 DEBUG --- stdout --- 2024-05-25 07:44:18,138 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 07:44:18,138 DEBUG --- stderr --- 2024-05-25 07:44:18,139 DEBUG ________________________________________________________________________________ [2024-05-25 07:44:18] login-tree-300 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 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-05-25 08:46:28] login-tree-300 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 08:46:28,651 INFO 2024-05-25 08:46:28,651 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:28,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:28,980 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:28,980 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:28,980 DEBUG --- stdout --- 2024-05-25 08:46:28,980 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:28,980 DEBUG --- stderr --- 2024-05-25 08:46:28,980 DEBUG 2024-05-25 08:46:28,981 INFO 2024-05-25 08:46:28,981 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 -- rm -f /results/orders/order.json 2024-05-25 08:46:28,981 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 08:46:29,778 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:29,778 DEBUG --- stdout --- 2024-05-25 08:46:29,778 DEBUG 2024-05-25 08:46:29,778 DEBUG --- stderr --- 2024-05-25 08:46:29,778 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 08:46:29] login-tree-300 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 08:46:29] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/order.json 2024-05-25 08:46:29,779 INFO 2024-05-25 08:46:29,779 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:29,780 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:30,098 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:30,098 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:30,098 DEBUG --- stdout --- 2024-05-25 08:46:30,098 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:30,098 DEBUG --- stderr --- 2024-05-25 08:46:30,098 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 08:46:30,098 INFO 2024-05-25 08:46:30,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-05-25 08:46:30,099 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:30,408 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:30,409 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:30,409 DEBUG --- stdout --- 2024-05-25 08:46:30,409 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:30,409 DEBUG --- stderr --- 2024-05-25 08:46:30,409 DEBUG 2024-05-25 08:46:30,409 INFO 2024-05-25 08:46:30,409 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-300/rc.txt" ]; then echo "/results/orders/login-tree-300/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-300/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/rc.txt file found" 2024-05-25 08:46:30,409 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 08:46:31,215 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 08:46:31,215 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:31,215 DEBUG --- stdout --- 2024-05-25 08:46:31,215 DEBUG /results/orders/login-tree-300/rc.txt file found 2024-05-25 08:46:31,216 DEBUG --- stderr --- 2024-05-25 08:46:31,216 DEBUG 2024-05-25 08:46:31,216 INFO 2024-05-25 08:46:31,216 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:31,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:31,519 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:31,519 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:31,519 DEBUG --- stdout --- 2024-05-25 08:46:31,519 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:31,519 DEBUG --- stderr --- 2024-05-25 08:46:31,519 DEBUG 2024-05-25 08:46:31,519 INFO 2024-05-25 08:46:31,519 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-300/rc.txt 2024-05-25 08:46:32,316 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 08:46:32,316 DEBUG --- stdout --- 2024-05-25 08:46:32,316 DEBUG 4 /results/orders/login-tree-300/rc.txt 2024-05-25 08:46:32,316 DEBUG --- stderr --- 2024-05-25 08:46:32,316 DEBUG 2024-05-25 08:46:32,316 INFO - Download file (size 4k) 2024-05-25 08:46:32,317 INFO 2024-05-25 08:46:32,317 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:32,317 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:32,622 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:32,623 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:32,623 DEBUG --- stdout --- 2024-05-25 08:46:32,623 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:32,623 DEBUG --- stderr --- 2024-05-25 08:46:32,623 DEBUG source : /results/orders/login-tree-300/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/rc.txt 2024-05-25 08:46:32,623 INFO 2024-05-25 08:46:32,623 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:32,623 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:32,939 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:32,939 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:32,939 DEBUG --- stdout --- 2024-05-25 08:46:32,939 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:32,939 DEBUG --- stderr --- 2024-05-25 08:46:32,939 DEBUG 2024-05-25 08:46:32,940 INFO 2024-05-25 08:46:32,940 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-300/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/rc.txt 2024-05-25 08:46:32,940 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 08:46:33,665 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:33,665 DEBUG --- stdout --- 2024-05-25 08:46:33,665 DEBUG tar: Removing leading `/' from member names 2024-05-25 08:46:33,665 DEBUG --- stderr --- 2024-05-25 08:46:33,665 DEBUG 2024-05-25 08:46:33,665 INFO 2024-05-25 08:46:33,666 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:33,666 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:33,965 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:33,965 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:33,965 DEBUG --- stdout --- 2024-05-25 08:46:33,965 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:33,965 DEBUG --- stderr --- 2024-05-25 08:46:33,965 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 08:46:33,965 INFO 2024-05-25 08:46:33,965 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:33,965 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:34,268 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:34,268 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:34,269 DEBUG --- stdout --- 2024-05-25 08:46:34,269 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:34,269 DEBUG --- stderr --- 2024-05-25 08:46:34,269 DEBUG 2024-05-25 08:46:34,269 INFO 2024-05-25 08:46:34,269 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-300/stderr.txt" ]; then echo "/results/orders/login-tree-300/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-300/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/stderr.txt file found" 2024-05-25 08:46:34,269 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 08:46:35,065 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 08:46:35,065 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:35,065 DEBUG --- stdout --- 2024-05-25 08:46:35,065 DEBUG /results/orders/login-tree-300/stderr.txt file found 2024-05-25 08:46:35,065 DEBUG --- stderr --- 2024-05-25 08:46:35,065 DEBUG 2024-05-25 08:46:35,066 INFO 2024-05-25 08:46:35,066 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:35,066 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:35,375 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:35,375 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:35,375 DEBUG --- stdout --- 2024-05-25 08:46:35,375 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:35,375 DEBUG --- stderr --- 2024-05-25 08:46:35,376 DEBUG 2024-05-25 08:46:35,376 INFO 2024-05-25 08:46:35,376 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-300/stderr.txt 2024-05-25 08:46:36,194 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 08:46:36,194 DEBUG --- stdout --- 2024-05-25 08:46:36,194 DEBUG 0 /results/orders/login-tree-300/stderr.txt 2024-05-25 08:46:36,194 DEBUG --- stderr --- 2024-05-25 08:46:36,194 DEBUG 2024-05-25 08:46:36,194 INFO - Download file (size 0k) 2024-05-25 08:46:36,194 INFO 2024-05-25 08:46:36,195 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:36,195 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:36,546 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:36,547 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:36,547 DEBUG --- stdout --- 2024-05-25 08:46:36,547 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:36,547 DEBUG --- stderr --- 2024-05-25 08:46:36,547 DEBUG source : /results/orders/login-tree-300/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stderr.txt 2024-05-25 08:46:36,547 INFO 2024-05-25 08:46:36,547 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:36,547 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:36,851 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:36,851 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:36,851 DEBUG --- stdout --- 2024-05-25 08:46:36,851 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:36,851 DEBUG --- stderr --- 2024-05-25 08:46:36,851 DEBUG 2024-05-25 08:46:36,851 INFO 2024-05-25 08:46:36,851 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-300/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stderr.txt 2024-05-25 08:46:36,852 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 08:46:37,568 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:37,568 DEBUG --- stdout --- 2024-05-25 08:46:37,568 DEBUG tar: Removing leading `/' from member names 2024-05-25 08:46:37,568 DEBUG --- stderr --- 2024-05-25 08:46:37,568 DEBUG 2024-05-25 08:46:37,569 INFO 2024-05-25 08:46:37,569 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:37,569 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:37,888 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:37,888 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:37,888 DEBUG --- stdout --- 2024-05-25 08:46:37,888 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:37,888 DEBUG --- stderr --- 2024-05-25 08:46:37,888 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 08:46:37,888 INFO 2024-05-25 08:46:37,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-05-25 08:46:37,888 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:38,193 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:38,193 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:38,193 DEBUG --- stdout --- 2024-05-25 08:46:38,193 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:38,193 DEBUG --- stderr --- 2024-05-25 08:46:38,193 DEBUG 2024-05-25 08:46:38,194 INFO 2024-05-25 08:46:38,194 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-300/stdout.txt" ]; then echo "/results/orders/login-tree-300/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-300/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/stdout.txt file found" 2024-05-25 08:46:38,194 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 08:46:38,998 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 08:46:39,000 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:39,000 DEBUG --- stdout --- 2024-05-25 08:46:39,000 DEBUG /results/orders/login-tree-300/stdout.txt file found 2024-05-25 08:46:39,000 DEBUG --- stderr --- 2024-05-25 08:46:39,000 DEBUG 2024-05-25 08:46:39,000 INFO 2024-05-25 08:46:39,000 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:39,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:39,301 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:39,301 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:39,301 DEBUG --- stdout --- 2024-05-25 08:46:39,301 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:39,301 DEBUG --- stderr --- 2024-05-25 08:46:39,301 DEBUG 2024-05-25 08:46:39,301 INFO 2024-05-25 08:46:39,302 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-300/stdout.txt 2024-05-25 08:46:40,103 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 08:46:40,103 DEBUG --- stdout --- 2024-05-25 08:46:40,104 DEBUG 648 /results/orders/login-tree-300/stdout.txt 2024-05-25 08:46:40,104 DEBUG --- stderr --- 2024-05-25 08:46:40,104 DEBUG 2024-05-25 08:46:40,104 INFO - Download file (size 648k) 2024-05-25 08:46:40,104 INFO 2024-05-25 08:46:40,104 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:40,104 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:40,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:40,408 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:40,408 DEBUG --- stdout --- 2024-05-25 08:46:40,408 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:40,408 DEBUG --- stderr --- 2024-05-25 08:46:40,408 DEBUG source : /results/orders/login-tree-300/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stdout.txt 2024-05-25 08:46:40,408 INFO 2024-05-25 08:46:40,408 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:40,409 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:40,715 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:40,715 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:40,715 DEBUG --- stdout --- 2024-05-25 08:46:40,715 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:40,715 DEBUG --- stderr --- 2024-05-25 08:46:40,715 DEBUG 2024-05-25 08:46:40,716 INFO 2024-05-25 08:46:40,716 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-300/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stdout.txt 2024-05-25 08:46:40,716 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 08:46:41,769 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:41,769 DEBUG --- stdout --- 2024-05-25 08:46:41,769 DEBUG tar: Removing leading `/' from member names 2024-05-25 08:46:41,769 DEBUG --- stderr --- 2024-05-25 08:46:41,769 DEBUG ________________________________________________________________________________ [2024-05-25 08:46:41] login-tree-300 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 08:46:41,770 INFO 2024-05-25 08:46:41,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-05-25 08:46:41,770 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:42,079 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:42,079 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:42,079 DEBUG --- stdout --- 2024-05-25 08:46:42,079 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:42,080 DEBUG --- stderr --- 2024-05-25 08:46:42,080 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 08:46:42,080 INFO 2024-05-25 08:46:42,080 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:42,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:42,389 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:42,389 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:42,389 DEBUG --- stdout --- 2024-05-25 08:46:42,389 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:42,389 DEBUG --- stderr --- 2024-05-25 08:46:42,389 DEBUG 2024-05-25 08:46:42,390 INFO 2024-05-25 08:46:42,390 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-300/cmd.txt" ]; then echo "/results/orders/login-tree-300/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-300/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-300/cmd.txt file found" 2024-05-25 08:46:42,390 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 08:46:43,194 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 08:46:43,194 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:43,194 DEBUG --- stdout --- 2024-05-25 08:46:43,194 DEBUG /results/orders/login-tree-300/cmd.txt file found 2024-05-25 08:46:43,194 DEBUG --- stderr --- 2024-05-25 08:46:43,194 DEBUG 2024-05-25 08:46:43,194 INFO 2024-05-25 08:46:43,194 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:43,194 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:43,498 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:43,498 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:43,498 DEBUG --- stdout --- 2024-05-25 08:46:43,498 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:43,499 DEBUG --- stderr --- 2024-05-25 08:46:43,499 DEBUG 2024-05-25 08:46:43,499 INFO 2024-05-25 08:46:43,499 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-dfd5985cb-fzzf7 --stdin -- du -k /results/orders/login-tree-300/cmd.txt 2024-05-25 08:46:44,295 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 08:46:44,297 DEBUG --- stdout --- 2024-05-25 08:46:44,297 DEBUG 4 /results/orders/login-tree-300/cmd.txt 2024-05-25 08:46:44,297 DEBUG --- stderr --- 2024-05-25 08:46:44,297 DEBUG 2024-05-25 08:46:44,297 INFO - Download file (size 4k) 2024-05-25 08:46:44,298 INFO 2024-05-25 08:46:44,298 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:44,298 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:44,603 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:44,603 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:44,603 DEBUG --- stdout --- 2024-05-25 08:46:44,603 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:44,604 DEBUG --- stderr --- 2024-05-25 08:46:44,604 DEBUG source : /results/orders/login-tree-300/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/cmd.txt 2024-05-25 08:46:44,604 INFO 2024-05-25 08:46:44,604 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 08:46:44,604 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 08:46:44,911 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 08:46:44,912 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:44,912 DEBUG --- stdout --- 2024-05-25 08:46:44,912 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 08:46:44,912 DEBUG --- stderr --- 2024-05-25 08:46:44,912 DEBUG 2024-05-25 08:46:44,912 INFO 2024-05-25 08:46:44,912 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-dfd5985cb-fzzf7:/results/orders/login-tree-300/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/cmd.txt 2024-05-25 08:46:44,912 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 08:46:45,642 INFO [loop_until]: OK (rc = 0) 2024-05-25 08:46:45,642 DEBUG --- stdout --- 2024-05-25 08:46:45,642 DEBUG tar: Removing leading `/' from member names 2024-05-25 08:46:45,642 DEBUG --- stderr --- 2024-05-25 08:46:45,642 DEBUG 2024-05-25 08:46:45,643 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/cmd.txt is small (1 lines), printing all lines: 2024-05-25 08:46:45,643 DEBUG ----- output ----- 2024-05-25 08:46:45,643 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-3580" --results-folder /results/gatling/login-tree-300 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 08:46:45,643 INFO ________________________________________________________________________________ [2024-05-25 08:46:45] login-tree-300 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 08:46:45,644 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/rc.txt is small (1 lines), printing all lines: 2024-05-25 08:46:45,644 DEBUG ----- output ----- 2024-05-25 08:46:45,644 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 08:46:45,644 INFO ________________________________________________________________________________ [2024-05-25 08:46:45] login-tree-300 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 08:46:45,645 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/04_login-tree-300/stdout.txt is 10536 lines): 2024-05-25 08:46:45,646 DEBUG ----- output ----- 2024-05-25 08:46:45,646 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 08:46:45,646 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 08:46:45,646 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO ================================================================================ 2024-05-25 08:46:45,646 INFO 2024-05-25 08:44:20 5s elapsed 2024-05-25 08:46:45,646 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,646 INFO > Global (OK=249 KO=0 ) 2024-05-25 08:46:45,646 INFO > restLoginInitiate (OK=125 KO=0 ) 2024-05-25 08:46:45,646 INFO > restLoginUsernamePasswordCallback (OK=124 KO=0 ) 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,646 INFO [- ] 0% 2024-05-25 08:46:45,646 INFO waiting: 1083588 / active: 2 / done: 124 2024-05-25 08:46:45,646 INFO ================================================================================ 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO ================================================================================ 2024-05-25 08:46:45,646 INFO 2024-05-25 08:44:25 10s elapsed 2024-05-25 08:46:45,646 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,646 INFO > Global (OK=998 KO=0 ) 2024-05-25 08:46:45,646 INFO > restLoginInitiate (OK=500 KO=0 ) 2024-05-25 08:46:45,646 INFO > restLoginUsernamePasswordCallback (OK=498 KO=0 ) 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,646 INFO [- ] 0% 2024-05-25 08:46:45,646 INFO waiting: 1083212 / active: 4 / done: 498 2024-05-25 08:46:45,646 INFO ================================================================================ 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,646 INFO ================================================================================ 2024-05-25 08:46:45,646 INFO 2024-05-25 08:44:30 15s elapsed 2024-05-25 08:46:45,646 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,646 INFO > Global (OK=2248 KO=0 ) 2024-05-25 08:46:45,646 INFO > restLoginInitiate (OK=1126 KO=0 ) 2024-05-25 08:46:45,646 INFO > restLoginUsernamePasswordCallback (OK=1122 KO=0 ) 2024-05-25 08:46:45,646 INFO 2024-05-25 08:46:45,647 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,647 INFO [- ] 0% 2024-05-25 08:46:45,647 INFO waiting: 1082586 / active: 6 / done: 1122 2024-05-25 08:46:45,647 INFO ================================================================================ 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO ================================================================================ 2024-05-25 08:46:45,647 INFO 2024-05-25 08:44:35 20s elapsed 2024-05-25 08:46:45,647 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,647 INFO > Global (OK=3997 KO=0 ) 2024-05-25 08:46:45,647 INFO > restLoginInitiate (OK=2001 KO=0 ) 2024-05-25 08:46:45,647 INFO > restLoginUsernamePasswordCallback (OK=1996 KO=0 ) 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,647 INFO [- ] 0% 2024-05-25 08:46:45,647 INFO waiting: 1081710 / active: 8 / done: 1996 2024-05-25 08:46:45,647 INFO ================================================================================ 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO ================================================================================ 2024-05-25 08:46:45,647 INFO 2024-05-25 08:44:40 25s elapsed 2024-05-25 08:46:45,647 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,647 INFO > Global (OK=6247 KO=0 ) 2024-05-25 08:46:45,647 INFO > restLoginInitiate (OK=3127 KO=0 ) 2024-05-25 08:46:45,647 INFO > restLoginUsernamePasswordCallback (OK=3120 KO=0 ) 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,647 INFO [- ] 0% 2024-05-25 08:46:45,647 INFO waiting: 1080584 / active: 10 / done: 3120 2024-05-25 08:46:45,647 INFO ================================================================================ 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO ================================================================================ 2024-05-25 08:46:45,647 INFO 2024-05-25 08:44:45 30s elapsed 2024-05-25 08:46:45,647 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,647 INFO > Global (OK=8990 KO=0 ) 2024-05-25 08:46:45,647 INFO > restLoginInitiate (OK=4497 KO=0 ) 2024-05-25 08:46:45,647 INFO > restLoginUsernamePasswordCallback (OK=4493 KO=0 ) 2024-05-25 08:46:45,647 INFO 2024-05-25 08:46:45,647 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,647 INFO [- ] 0% 2024-05-25 08:46:45,647 INFO waiting: 1079207 / active: 14 / done: 4493 2024-05-25 08:46:45,648 INFO ================================================================================ 2024-05-25 08:46:45,648 INFO 2024-05-25 08:46:45,648 INFO 2024-05-25 08:46:45,648 INFO ================================================================================ 2024-05-25 08:46:45,648 INFO 2024-05-25 08:44:50 35s elapsed 2024-05-25 08:46:45,648 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,648 INFO > Global (OK=11902 KO=0 ) 2024-05-25 08:46:45,648 INFO > restLoginInitiate (OK=5956 KO=0 ) 2024-05-25 08:46:45,648 INFO > restLoginUsernamePasswordCallback (OK=5946 KO=0 ) 2024-05-25 08:46:45,648 INFO 2024-05-25 08:46:45,648 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,648 INFO [- ] 0% 2024-05-25 08:46:45,648 INFO waiting: 1077754 / active: 14 / done: 5946 2024-05-25 08:46:45,648 INFO ================================================================================ 2024-05-25 08:46:45,648 INFO 2024-05-25 08:46:45,648 INFO 2024-05-25 08:46:45,648 INFO ================================================================================ 2024-05-25 08:46:45,648 INFO 2024-05-25 08:44:55 40s elapsed 2024-05-25 08:46:45,648 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,648 INFO > Global (OK=14941 KO=0 ) 2024-05-25 08:46:45,648 INFO > restLoginInitiate (OK=7473 KO=0 ) 2024-05-25 08:46:45,648 INFO [...] 2024-05-25 08:46:45,651 INFO 2024-05-25 08:46:45,651 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,651 INFO [#########################################################################-] 99% 2024-05-25 08:46:45,651 INFO waiting: 6024 / active: 12 / done: 1077678 2024-05-25 08:46:45,651 INFO ================================================================================ 2024-05-25 08:46:45,651 INFO 2024-05-25 08:46:45,651 INFO 2024-05-25 08:46:45,651 INFO ================================================================================ 2024-05-25 08:46:45,651 INFO 2024-05-25 09:44:30 3615s elapsed 2024-05-25 08:46:45,651 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,651 INFO > Global (OK=2158429 KO=2 ) 2024-05-25 08:46:45,651 INFO > restLoginInitiate (OK=1079222 KO=1 ) 2024-05-25 08:46:45,651 INFO > restLoginUsernamePasswordCallback (OK=1079207 KO=1 ) 2024-05-25 08:46:45,651 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 08:46:45,651 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 08:46:45,651 INFO 2024-05-25 08:46:45,651 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,651 INFO [#########################################################################-] 99% 2024-05-25 08:46:45,651 INFO waiting: 4486 / active: 19 / done: 1079209 2024-05-25 08:46:45,651 INFO ================================================================================ 2024-05-25 08:46:45,651 INFO 2024-05-25 08:46:45,651 INFO 2024-05-25 08:46:45,651 INFO ================================================================================ 2024-05-25 08:46:45,651 INFO 2024-05-25 09:44:35 3620s elapsed 2024-05-25 08:46:45,651 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,651 INFO > Global (OK=2161374 KO=2 ) 2024-05-25 08:46:45,651 INFO > restLoginInitiate (OK=1080695 KO=1 ) 2024-05-25 08:46:45,651 INFO > restLoginUsernamePasswordCallback (OK=1080679 KO=1 ) 2024-05-25 08:46:45,651 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 08:46:45,651 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,652 INFO [#########################################################################-] 99% 2024-05-25 08:46:45,652 INFO waiting: 3016 / active: 17 / done: 1080681 2024-05-25 08:46:45,652 INFO ================================================================================ 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO ================================================================================ 2024-05-25 08:46:45,652 INFO 2024-05-25 09:44:40 3625s elapsed 2024-05-25 08:46:45,652 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,652 INFO > Global (OK=2164463 KO=2 ) 2024-05-25 08:46:45,652 INFO > restLoginInitiate (OK=1082240 KO=1 ) 2024-05-25 08:46:45,652 INFO > restLoginUsernamePasswordCallback (OK=1082223 KO=1 ) 2024-05-25 08:46:45,652 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 08:46:45,652 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,652 INFO [#########################################################################-] 99% 2024-05-25 08:46:45,652 INFO waiting: 1470 / active: 19 / done: 1082225 2024-05-25 08:46:45,652 INFO ================================================================================ 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO ================================================================================ 2024-05-25 08:46:45,652 INFO 2024-05-25 09:44:45 3630s elapsed 2024-05-25 08:46:45,652 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,652 INFO > Global (OK=2167418 KO=2 ) 2024-05-25 08:46:45,652 INFO > restLoginInitiate (OK=1083713 KO=1 ) 2024-05-25 08:46:45,652 INFO > restLoginUsernamePasswordCallback (OK=1083705 KO=1 ) 2024-05-25 08:46:45,652 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 08:46:45,652 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,652 INFO [#########################################################################-] 99% 2024-05-25 08:46:45,652 INFO waiting: 0 / active: 7 / done: 1083707 2024-05-25 08:46:45,652 INFO ================================================================================ 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO 2024-05-25 08:46:45,652 INFO ================================================================================ 2024-05-25 08:46:45,652 INFO 2024-05-25 09:44:45 3630s elapsed 2024-05-25 08:46:45,652 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 08:46:45,652 INFO > Global (OK=2167425 KO=2 ) 2024-05-25 08:46:45,653 INFO > restLoginInitiate (OK=1083713 KO=1 ) 2024-05-25 08:46:45,653 INFO > restLoginUsernamePasswordCallback (OK=1083712 KO=1 ) 2024-05-25 08:46:45,653 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 08:46:45,653 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 08:46:45,653 INFO 2024-05-25 08:46:45,653 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 08:46:45,653 INFO [##########################################################################]100% 2024-05-25 08:46:45,653 INFO waiting: 0 / active: 0 / done: 1083714 2024-05-25 08:46:45,653 INFO ================================================================================ 2024-05-25 08:46:45,653 INFO 2024-05-25 08:46:45,653 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 08:46:45,653 INFO 2024-05-25 08:46:45,653 INFO ---------------------- 2024-05-25 08:46:45,653 INFO [25/May/2024 09:44:45] Create tar file login-tree-300.tar.gz 2024-05-25 08:46:45,653 INFO ---------------------- 2024-05-25 08:46:45,653 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 08:46:45,653 INFO RESULT_FOLDER=login-tree-300 2024-05-25 08:46:45,653 INFO SIMULATION_PATH=login-tree-300/loadtest-20240525074414687 2024-05-25 08:46:45,653 INFO PWD=/results/gatling/login-tree-300/loadtest-20240525074414687 2024-05-25 08:46:45,653 INFO [cmd] tar -czvf /results/gatling//login-tree-300.tar.gz --exclude=simulation.log * 2024-05-25 08:46:45,653 INFO /results/gatling 2024-05-25 08:46:45,653 INFO 2024-05-25 08:46:45,653 INFO ---------------------- 2024-05-25 08:46:45,653 INFO [25/May/2024 09:44:45] Update /results/gatling//latest 2024-05-25 08:46:45,653 INFO ---------------------- 2024-05-25 08:46:45,653 INFO 2024-05-25 08:46:45,653 INFO ---------------------- 2024-05-25 08:46:45,653 INFO [25/May/2024 09:44:45] Update /results/gatling//latest.tar.gz 2024-05-25 08:46:45,653 INFO ---------------------- 2024-05-25 08:46:45,653 INFO ________________________________________________________________________________ [2024-05-25 08:46:45] login-tree-300 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 08:46:45,654 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-300/stderr.txt is small (0 lines), printing all lines: 2024-05-25 08:46:45,654 DEBUG ----- output ----- 2024-05-25 08:46:45,654 DEBUG 2024-05-25 08:46:45,654 INFO ________________________________________________________________________________ [2024-05-25 08:46:45] login-tree-300 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-05-25 08:46:45] login-tree-300 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 08:46:45] login-tree-300 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped