--Task-- name: login-tree-600 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-500'] preceding_task: login-tree-500 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 600} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 02:09:42] login-tree-600 pre : N/A ________________________________________________________________________________ [2024-05-25 02:09:42] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 02:09:42] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-600/stdout.txt Pod log : /results/orders/login-tree-600 2024-05-25 02:09:42,872 INFO 2024-05-25 02:09:42,872 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 02:09:42,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:43,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:43,178 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:43,178 DEBUG --- stdout --- 2024-05-25 02:09:43,178 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:43,178 DEBUG --- stderr --- 2024-05-25 02:09:43,178 DEBUG ________________________________________________________________________________ [2024-05-25 02:09:43] login-tree-600 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 02:09:43,179 INFO 2024-05-25 02:09:43,179 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 02:09:43,179 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:43,479 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:43,480 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:43,480 DEBUG --- stdout --- 2024-05-25 02:09:43,480 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:43,480 DEBUG --- stderr --- 2024-05-25 02:09:43,480 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-600 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 02:09:43] login-tree-600 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 02:09:43,481 INFO 2024-05-25 02:09:43,481 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 02:09:43,481 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:43,780 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:43,780 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:43,780 DEBUG --- stdout --- 2024-05-25 02:09:43,780 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:43,780 DEBUG --- stderr --- 2024-05-25 02:09:43,780 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/order.json { "name": "login-tree-600", "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-600 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-600", "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=600 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 02:09:43] login-tree-600 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 02:09:43,783 INFO 2024-05-25 02:09:43,783 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 02:09:43,783 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 02:09:44,031 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 02:09:44,031 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:44,031 DEBUG --- stdout --- 2024-05-25 02:09:44,031 DEBUG WAITING 2024-05-25 02:09:44,031 DEBUG --- stderr --- 2024-05-25 02:09:44,031 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 02:09:44] login-tree-600 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 02:09:44,032 INFO 2024-05-25 02:09:44,032 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 02:09:44,032 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:44,334 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:44,334 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:44,334 DEBUG --- stdout --- 2024-05-25 02:09:44,334 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:44,334 DEBUG --- stderr --- 2024-05-25 02:09:44,334 DEBUG 2024-05-25 02:09:44,335 INFO 2024-05-25 02:09:44,335 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/07_login-tree-600/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 02:09:44,335 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 02:09:45,694 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:45,694 DEBUG --- stdout --- 2024-05-25 02:09:45,694 DEBUG 2024-05-25 02:09:45,694 DEBUG --- stderr --- 2024-05-25 02:09:45,694 DEBUG Order has been successfully forwarded to overseer [2024-05-25 02:09:45] - INFO: Check if Gatling simulation started successfully 2024-05-25 02:09:45,695 INFO 2024-05-25 02:09:45,695 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 02:09:45,695 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 02:09:45,997 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 02:09:45,998 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:45,998 DEBUG --- stdout --- 2024-05-25 02:09:45,998 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 02:09:45,999 DEBUG --- stderr --- 2024-05-25 02:09:45,999 DEBUG 2024-05-25 02:09:45,999 INFO 2024-05-25 02:09:45,999 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-600/stdout.txt | grep "started..." 2024-05-25 02:09:45,999 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 02:09:46,725 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 02:09:52,466 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-05-25 02:09:52,466 INFO [loop_until]: OK (rc = 0) 2024-05-25 02:09:52,466 DEBUG --- stdout --- 2024-05-25 02:09:52,466 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 02:09:52,466 DEBUG --- stderr --- 2024-05-25 02:09:52,466 DEBUG ________________________________________________________________________________ [2024-05-25 02:09:52] login-tree-600 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-05-25 03:12:02] login-tree-600 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 03:12:03,001 INFO 2024-05-25 03:12:03,002 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 03:12:03,002 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:03,403 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:03,403 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:03,404 DEBUG --- stdout --- 2024-05-25 03:12:03,404 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:03,404 DEBUG --- stderr --- 2024-05-25 03:12:03,404 DEBUG 2024-05-25 03:12:03,404 INFO 2024-05-25 03:12:03,404 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 03:12:03,404 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 03:12:04,210 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:04,210 DEBUG --- stdout --- 2024-05-25 03:12:04,210 DEBUG 2024-05-25 03:12:04,211 DEBUG --- stderr --- 2024-05-25 03:12:04,211 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 03:12:04] login-tree-600 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 03:12:04] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/order.json 2024-05-25 03:12:04,212 INFO 2024-05-25 03:12:04,212 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 03:12:04,212 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:04,512 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:04,512 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:04,512 DEBUG --- stdout --- 2024-05-25 03:12:04,512 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:04,512 DEBUG --- stderr --- 2024-05-25 03:12:04,512 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 03:12:04,513 INFO 2024-05-25 03:12:04,513 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 03:12:04,513 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:04,822 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:04,822 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:04,822 DEBUG --- stdout --- 2024-05-25 03:12:04,822 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:04,822 DEBUG --- stderr --- 2024-05-25 03:12:04,822 DEBUG 2024-05-25 03:12:04,823 INFO 2024-05-25 03:12:04,823 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-600/rc.txt" ]; then echo "/results/orders/login-tree-600/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-600/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/rc.txt file found" 2024-05-25 03:12:04,823 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 03:12:05,633 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 03:12:05,633 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:05,634 DEBUG --- stdout --- 2024-05-25 03:12:05,634 DEBUG /results/orders/login-tree-600/rc.txt file found 2024-05-25 03:12:05,634 DEBUG --- stderr --- 2024-05-25 03:12:05,634 DEBUG 2024-05-25 03:12:05,634 INFO 2024-05-25 03:12:05,634 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 03:12:05,634 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:05,946 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:05,946 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:05,946 DEBUG --- stdout --- 2024-05-25 03:12:05,946 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:05,946 DEBUG --- stderr --- 2024-05-25 03:12:05,946 DEBUG 2024-05-25 03:12:05,946 INFO 2024-05-25 03:12:05,947 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-600/rc.txt 2024-05-25 03:12:06,749 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 03:12:06,749 DEBUG --- stdout --- 2024-05-25 03:12:06,749 DEBUG 4 /results/orders/login-tree-600/rc.txt 2024-05-25 03:12:06,749 DEBUG --- stderr --- 2024-05-25 03:12:06,749 DEBUG 2024-05-25 03:12:06,749 INFO - Download file (size 4k) 2024-05-25 03:12:06,750 INFO 2024-05-25 03:12:06,750 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 03:12:06,750 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:07,058 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:07,058 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:07,058 DEBUG --- stdout --- 2024-05-25 03:12:07,058 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:07,058 DEBUG --- stderr --- 2024-05-25 03:12:07,058 DEBUG source : /results/orders/login-tree-600/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/rc.txt 2024-05-25 03:12:07,059 INFO 2024-05-25 03:12:07,059 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 03:12:07,059 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:07,371 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:07,371 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:07,371 DEBUG --- stdout --- 2024-05-25 03:12:07,371 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:07,371 DEBUG --- stderr --- 2024-05-25 03:12:07,371 DEBUG 2024-05-25 03:12:07,371 INFO 2024-05-25 03:12:07,372 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-600/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/rc.txt 2024-05-25 03:12:07,372 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 03:12:08,185 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:08,185 DEBUG --- stdout --- 2024-05-25 03:12:08,185 DEBUG tar: Removing leading `/' from member names 2024-05-25 03:12:08,185 DEBUG --- stderr --- 2024-05-25 03:12:08,185 DEBUG 2024-05-25 03:12:08,186 INFO 2024-05-25 03:12:08,186 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 03:12:08,186 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:08,498 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:08,498 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:08,498 DEBUG --- stdout --- 2024-05-25 03:12:08,498 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:08,498 DEBUG --- stderr --- 2024-05-25 03:12:08,498 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 03:12:08,498 INFO 2024-05-25 03:12:08,498 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 03:12:08,499 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:08,810 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:08,810 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:08,810 DEBUG --- stdout --- 2024-05-25 03:12:08,810 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:08,810 DEBUG --- stderr --- 2024-05-25 03:12:08,811 DEBUG 2024-05-25 03:12:08,811 INFO 2024-05-25 03:12:08,811 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-600/stderr.txt" ]; then echo "/results/orders/login-tree-600/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-600/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/stderr.txt file found" 2024-05-25 03:12:08,811 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 03:12:09,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 03:12:09,618 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:09,618 DEBUG --- stdout --- 2024-05-25 03:12:09,618 DEBUG /results/orders/login-tree-600/stderr.txt file found 2024-05-25 03:12:09,618 DEBUG --- stderr --- 2024-05-25 03:12:09,618 DEBUG 2024-05-25 03:12:09,618 INFO 2024-05-25 03:12:09,619 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 03:12:09,619 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:09,928 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:09,928 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:09,928 DEBUG --- stdout --- 2024-05-25 03:12:09,928 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:09,928 DEBUG --- stderr --- 2024-05-25 03:12:09,928 DEBUG 2024-05-25 03:12:09,928 INFO 2024-05-25 03:12:09,929 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-600/stderr.txt 2024-05-25 03:12:10,727 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 03:12:10,727 DEBUG --- stdout --- 2024-05-25 03:12:10,727 DEBUG 0 /results/orders/login-tree-600/stderr.txt 2024-05-25 03:12:10,727 DEBUG --- stderr --- 2024-05-25 03:12:10,727 DEBUG 2024-05-25 03:12:10,727 INFO - Download file (size 0k) 2024-05-25 03:12:10,728 INFO 2024-05-25 03:12:10,728 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 03:12:10,728 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:11,038 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:11,039 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:11,039 DEBUG --- stdout --- 2024-05-25 03:12:11,039 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:11,039 DEBUG --- stderr --- 2024-05-25 03:12:11,039 DEBUG source : /results/orders/login-tree-600/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stderr.txt 2024-05-25 03:12:11,039 INFO 2024-05-25 03:12:11,039 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 03:12:11,039 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:11,345 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:11,345 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:11,345 DEBUG --- stdout --- 2024-05-25 03:12:11,345 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:11,345 DEBUG --- stderr --- 2024-05-25 03:12:11,345 DEBUG 2024-05-25 03:12:11,346 INFO 2024-05-25 03:12:11,346 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-600/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stderr.txt 2024-05-25 03:12:11,346 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 03:12:12,129 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:12,129 DEBUG --- stdout --- 2024-05-25 03:12:12,129 DEBUG tar: Removing leading `/' from member names 2024-05-25 03:12:12,129 DEBUG --- stderr --- 2024-05-25 03:12:12,129 DEBUG 2024-05-25 03:12:12,130 INFO 2024-05-25 03:12:12,130 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 03:12:12,130 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:12,445 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:12,446 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:12,446 DEBUG --- stdout --- 2024-05-25 03:12:12,446 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:12,446 DEBUG --- stderr --- 2024-05-25 03:12:12,446 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 03:12:12,446 INFO 2024-05-25 03:12:12,446 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 03:12:12,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:12,758 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:12,758 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:12,758 DEBUG --- stdout --- 2024-05-25 03:12:12,758 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:12,759 DEBUG --- stderr --- 2024-05-25 03:12:12,759 DEBUG 2024-05-25 03:12:12,759 INFO 2024-05-25 03:12:12,759 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-600/stdout.txt" ]; then echo "/results/orders/login-tree-600/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-600/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/stdout.txt file found" 2024-05-25 03:12:12,759 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 03:12:13,569 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 03:12:13,569 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:13,569 DEBUG --- stdout --- 2024-05-25 03:12:13,569 DEBUG /results/orders/login-tree-600/stdout.txt file found 2024-05-25 03:12:13,569 DEBUG --- stderr --- 2024-05-25 03:12:13,569 DEBUG 2024-05-25 03:12:13,569 INFO 2024-05-25 03:12:13,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 03:12:13,569 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:13,877 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:13,877 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:13,877 DEBUG --- stdout --- 2024-05-25 03:12:13,877 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:13,877 DEBUG --- stderr --- 2024-05-25 03:12:13,877 DEBUG 2024-05-25 03:12:13,877 INFO 2024-05-25 03:12:13,877 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-600/stdout.txt 2024-05-25 03:12:14,686 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 03:12:14,687 DEBUG --- stdout --- 2024-05-25 03:12:14,687 DEBUG 652 /results/orders/login-tree-600/stdout.txt 2024-05-25 03:12:14,687 DEBUG --- stderr --- 2024-05-25 03:12:14,687 DEBUG 2024-05-25 03:12:14,687 INFO - Download file (size 652k) 2024-05-25 03:12:14,688 INFO 2024-05-25 03:12:14,688 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 03:12:14,688 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:14,999 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:15,000 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:15,000 DEBUG --- stdout --- 2024-05-25 03:12:15,000 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:15,000 DEBUG --- stderr --- 2024-05-25 03:12:15,000 DEBUG source : /results/orders/login-tree-600/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stdout.txt 2024-05-25 03:12:15,000 INFO 2024-05-25 03:12:15,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 03:12:15,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:15,312 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:15,312 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:15,312 DEBUG --- stdout --- 2024-05-25 03:12:15,312 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:15,312 DEBUG --- stderr --- 2024-05-25 03:12:15,312 DEBUG 2024-05-25 03:12:15,313 INFO 2024-05-25 03:12:15,313 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-600/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stdout.txt 2024-05-25 03:12:15,313 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 03:12:16,380 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:16,381 DEBUG --- stdout --- 2024-05-25 03:12:16,381 DEBUG tar: Removing leading `/' from member names 2024-05-25 03:12:16,381 DEBUG --- stderr --- 2024-05-25 03:12:16,381 DEBUG ________________________________________________________________________________ [2024-05-25 03:12:16] login-tree-600 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 03:12:16,383 INFO 2024-05-25 03:12:16,383 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 03:12:16,383 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:16,747 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:16,747 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:16,747 DEBUG --- stdout --- 2024-05-25 03:12:16,747 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:16,747 DEBUG --- stderr --- 2024-05-25 03:12:16,747 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 03:12:16,747 INFO 2024-05-25 03:12:16,747 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 03:12:16,747 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:17,048 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:17,048 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:17,048 DEBUG --- stdout --- 2024-05-25 03:12:17,048 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:17,048 DEBUG --- stderr --- 2024-05-25 03:12:17,048 DEBUG 2024-05-25 03:12:17,049 INFO 2024-05-25 03:12:17,049 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-600/cmd.txt" ]; then echo "/results/orders/login-tree-600/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-600/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-600/cmd.txt file found" 2024-05-25 03:12:17,049 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 03:12:17,861 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 03:12:17,862 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:17,862 DEBUG --- stdout --- 2024-05-25 03:12:17,862 DEBUG /results/orders/login-tree-600/cmd.txt file found 2024-05-25 03:12:17,862 DEBUG --- stderr --- 2024-05-25 03:12:17,862 DEBUG 2024-05-25 03:12:17,862 INFO 2024-05-25 03:12:17,862 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 03:12:17,862 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:18,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:18,169 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:18,169 DEBUG --- stdout --- 2024-05-25 03:12:18,169 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:18,169 DEBUG --- stderr --- 2024-05-25 03:12:18,169 DEBUG 2024-05-25 03:12:18,169 INFO 2024-05-25 03:12:18,169 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-600/cmd.txt 2024-05-25 03:12:18,969 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 03:12:18,969 DEBUG --- stdout --- 2024-05-25 03:12:18,969 DEBUG 4 /results/orders/login-tree-600/cmd.txt 2024-05-25 03:12:18,969 DEBUG --- stderr --- 2024-05-25 03:12:18,969 DEBUG 2024-05-25 03:12:18,969 INFO - Download file (size 4k) 2024-05-25 03:12:18,970 INFO 2024-05-25 03:12:18,970 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 03:12:18,970 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:19,275 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:19,275 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:19,275 DEBUG --- stdout --- 2024-05-25 03:12:19,275 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:19,275 DEBUG --- stderr --- 2024-05-25 03:12:19,275 DEBUG source : /results/orders/login-tree-600/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/cmd.txt 2024-05-25 03:12:19,276 INFO 2024-05-25 03:12:19,276 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 03:12:19,276 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 03:12:19,587 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 03:12:19,587 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:19,587 DEBUG --- stdout --- 2024-05-25 03:12:19,587 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 03:12:19,587 DEBUG --- stderr --- 2024-05-25 03:12:19,587 DEBUG 2024-05-25 03:12:19,587 INFO 2024-05-25 03:12:19,587 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-600/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/cmd.txt 2024-05-25 03:12:19,588 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 03:12:20,319 INFO [loop_until]: OK (rc = 0) 2024-05-25 03:12:20,319 DEBUG --- stdout --- 2024-05-25 03:12:20,319 DEBUG tar: Removing leading `/' from member names 2024-05-25 03:12:20,319 DEBUG --- stderr --- 2024-05-25 03:12:20,319 DEBUG 2024-05-25 03:12:20,320 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/cmd.txt is small (1 lines), printing all lines: 2024-05-25 03:12:20,320 DEBUG ----- output ----- 2024-05-25 03:12:20,320 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-600 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 03:12:20,320 INFO ________________________________________________________________________________ [2024-05-25 03:12:20] login-tree-600 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 03:12:20,320 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/rc.txt is small (1 lines), printing all lines: 2024-05-25 03:12:20,320 DEBUG ----- output ----- 2024-05-25 03:12:20,320 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 03:12:20,320 INFO ________________________________________________________________________________ [2024-05-25 03:12:20] login-tree-600 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 03:12:20,322 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/07_login-tree-600/stdout.txt is 10609 lines): 2024-05-25 03:12:20,322 DEBUG ----- output ----- 2024-05-25 03:12:20,322 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 03:12:20,322 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 03:12:20,322 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 03:12:20,322 INFO 2024-05-25 03:12:20,322 INFO ================================================================================ 2024-05-25 03:12:20,322 INFO 2024-05-25 03:09:54 5s elapsed 2024-05-25 03:12:20,322 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,322 INFO > Global (OK=500 KO=0 ) 2024-05-25 03:12:20,322 INFO > restLoginInitiate (OK=251 KO=0 ) 2024-05-25 03:12:20,322 INFO > restLoginUsernamePasswordCallback (OK=249 KO=0 ) 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,323 INFO [- ] 0% 2024-05-25 03:12:20,323 INFO waiting: 2165304 / active: 3 / done: 249 2024-05-25 03:12:20,323 INFO ================================================================================ 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ================================================================================ 2024-05-25 03:12:20,323 INFO 2024-05-25 03:09:59 10s elapsed 2024-05-25 03:12:20,323 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,323 INFO > Global (OK=1999 KO=0 ) 2024-05-25 03:12:20,323 INFO > restLoginInitiate (OK=1002 KO=0 ) 2024-05-25 03:12:20,323 INFO > restLoginUsernamePasswordCallback (OK=997 KO=0 ) 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,323 INFO [- ] 0% 2024-05-25 03:12:20,323 INFO waiting: 2164552 / active: 7 / done: 997 2024-05-25 03:12:20,323 INFO ================================================================================ 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ================================================================================ 2024-05-25 03:12:20,323 INFO 2024-05-25 03:10:04 15s elapsed 2024-05-25 03:12:20,323 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,323 INFO > Global (OK=4496 KO=0 ) 2024-05-25 03:12:20,323 INFO > restLoginInitiate (OK=2252 KO=0 ) 2024-05-25 03:12:20,323 INFO > restLoginUsernamePasswordCallback (OK=2244 KO=0 ) 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,323 INFO [- ] 0% 2024-05-25 03:12:20,323 INFO waiting: 2163301 / active: 11 / done: 2244 2024-05-25 03:12:20,323 INFO ================================================================================ 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ================================================================================ 2024-05-25 03:12:20,323 INFO 2024-05-25 03:10:09 20s elapsed 2024-05-25 03:12:20,323 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,323 INFO > Global (OK=7996 KO=0 ) 2024-05-25 03:12:20,323 INFO > restLoginInitiate (OK=4003 KO=0 ) 2024-05-25 03:12:20,323 INFO > restLoginUsernamePasswordCallback (OK=3993 KO=0 ) 2024-05-25 03:12:20,323 INFO 2024-05-25 03:12:20,323 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,323 INFO [- ] 0% 2024-05-25 03:12:20,324 INFO waiting: 2161549 / active: 14 / done: 3993 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:10:14 25s elapsed 2024-05-25 03:12:20,324 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,324 INFO > Global (OK=12496 KO=0 ) 2024-05-25 03:12:20,324 INFO > restLoginInitiate (OK=6255 KO=0 ) 2024-05-25 03:12:20,324 INFO > restLoginUsernamePasswordCallback (OK=6241 KO=0 ) 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,324 INFO [- ] 0% 2024-05-25 03:12:20,324 INFO waiting: 2159297 / active: 18 / done: 6241 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:10:19 30s elapsed 2024-05-25 03:12:20,324 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,324 INFO > Global (OK=17970 KO=0 ) 2024-05-25 03:12:20,324 INFO > restLoginInitiate (OK=8993 KO=0 ) 2024-05-25 03:12:20,324 INFO > restLoginUsernamePasswordCallback (OK=8977 KO=0 ) 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,324 INFO [- ] 0% 2024-05-25 03:12:20,324 INFO waiting: 2156547 / active: 32 / done: 8977 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:10:24 35s elapsed 2024-05-25 03:12:20,324 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,324 INFO > Global (OK=23822 KO=0 ) 2024-05-25 03:12:20,324 INFO > restLoginInitiate (OK=11920 KO=0 ) 2024-05-25 03:12:20,324 INFO > restLoginUsernamePasswordCallback (OK=11902 KO=0 ) 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,324 INFO [- ] 0% 2024-05-25 03:12:20,324 INFO waiting: 2153633 / active: 21 / done: 11902 2024-05-25 03:12:20,324 INFO ================================================================================ 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,324 INFO 2024-05-25 03:12:20,325 INFO ================================================================================ 2024-05-25 03:12:20,325 INFO 2024-05-25 03:10:29 40s elapsed 2024-05-25 03:12:20,325 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,325 INFO > Global (OK=29752 KO=0 ) 2024-05-25 03:12:20,325 INFO > restLoginInitiate (OK=14886 KO=0 ) 2024-05-25 03:12:20,325 INFO [...] 2024-05-25 03:12:20,327 INFO 2024-05-25 03:12:20,328 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,328 INFO [#########################################################################-] 99% 2024-05-25 03:12:20,328 INFO waiting: 11826 / active: 372 / done: 2153358 2024-05-25 03:12:20,328 INFO ================================================================================ 2024-05-25 03:12:20,328 INFO 2024-05-25 03:12:20,328 INFO 2024-05-25 03:12:20,328 INFO ================================================================================ 2024-05-25 03:12:20,328 INFO 2024-05-25 04:10:04 3615s elapsed 2024-05-25 03:12:20,328 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,328 INFO > Global (OK=4313305 KO=2 ) 2024-05-25 03:12:20,328 INFO > restLoginInitiate (OK=2156663 KO=2 ) 2024-05-25 03:12:20,328 INFO > restLoginUsernamePasswordCallback (OK=2156642 KO=0 ) 2024-05-25 03:12:20,328 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 03:12:20,328 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 03:12:20,328 INFO 2024-05-25 03:12:20,328 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,328 INFO [#########################################################################-] 99% 2024-05-25 03:12:20,328 INFO waiting: 8886 / active: 26 / done: 2156644 2024-05-25 03:12:20,328 INFO ================================================================================ 2024-05-25 03:12:20,328 INFO 2024-05-25 03:12:20,328 INFO 2024-05-25 03:12:20,328 INFO ================================================================================ 2024-05-25 03:12:20,328 INFO 2024-05-25 04:10:09 3620s elapsed 2024-05-25 03:12:20,328 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,328 INFO > Global (OK=4319114 KO=2 ) 2024-05-25 03:12:20,328 INFO > restLoginInitiate (OK=2159565 KO=2 ) 2024-05-25 03:12:20,328 INFO > restLoginUsernamePasswordCallback (OK=2159549 KO=0 ) 2024-05-25 03:12:20,328 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 03:12:20,328 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 03:12:20,328 INFO 2024-05-25 03:12:20,328 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,329 INFO [#########################################################################-] 99% 2024-05-25 03:12:20,329 INFO waiting: 5983 / active: 22 / done: 2159551 2024-05-25 03:12:20,329 INFO ================================================================================ 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO ================================================================================ 2024-05-25 03:12:20,329 INFO 2024-05-25 04:10:14 3625s elapsed 2024-05-25 03:12:20,329 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,329 INFO > Global (OK=4323782 KO=2 ) 2024-05-25 03:12:20,329 INFO > restLoginInitiate (OK=2162566 KO=2 ) 2024-05-25 03:12:20,329 INFO > restLoginUsernamePasswordCallback (OK=2161216 KO=0 ) 2024-05-25 03:12:20,329 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 03:12:20,329 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,329 INFO [#########################################################################-] 99% 2024-05-25 03:12:20,329 INFO waiting: 2954 / active: 1384 / done: 2161218 2024-05-25 03:12:20,329 INFO ================================================================================ 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO ================================================================================ 2024-05-25 03:12:20,329 INFO 2024-05-25 04:10:19 3630s elapsed 2024-05-25 03:12:20,329 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,329 INFO > Global (OK=4330476 KO=2 ) 2024-05-25 03:12:20,329 INFO > restLoginInitiate (OK=2165553 KO=2 ) 2024-05-25 03:12:20,329 INFO > restLoginUsernamePasswordCallback (OK=2164923 KO=0 ) 2024-05-25 03:12:20,329 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 03:12:20,329 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,329 INFO [#########################################################################-] 99% 2024-05-25 03:12:20,329 INFO waiting: 0 / active: 631 / done: 2164925 2024-05-25 03:12:20,329 INFO ================================================================================ 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO 2024-05-25 03:12:20,329 INFO ================================================================================ 2024-05-25 03:12:20,330 INFO 2024-05-25 04:10:20 3630s elapsed 2024-05-25 03:12:20,330 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 03:12:20,330 INFO > Global (OK=4331108 KO=2 ) 2024-05-25 03:12:20,330 INFO > restLoginInitiate (OK=2165554 KO=2 ) 2024-05-25 03:12:20,330 INFO > restLoginUsernamePasswordCallback (OK=2165554 KO=0 ) 2024-05-25 03:12:20,330 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 03:12:20,330 INFO > status.find.is(200), but actually found 502 2 (100.0%) 2024-05-25 03:12:20,330 INFO 2024-05-25 03:12:20,330 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 03:12:20,330 INFO [##########################################################################]100% 2024-05-25 03:12:20,330 INFO waiting: 0 / active: 0 / done: 2165556 2024-05-25 03:12:20,330 INFO ================================================================================ 2024-05-25 03:12:20,330 INFO 2024-05-25 03:12:20,330 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 03:12:20,330 INFO 2024-05-25 03:12:20,330 INFO ---------------------- 2024-05-25 03:12:20,330 INFO [25/May/2024 04:10:20] Create tar file login-tree-600.tar.gz 2024-05-25 03:12:20,330 INFO ---------------------- 2024-05-25 03:12:20,330 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 03:12:20,330 INFO RESULT_FOLDER=login-tree-600 2024-05-25 03:12:20,330 INFO SIMULATION_PATH=login-tree-600/loadtest-20240525020949399 2024-05-25 03:12:20,330 INFO PWD=/results/gatling/login-tree-600/loadtest-20240525020949399 2024-05-25 03:12:20,330 INFO [cmd] tar -czvf /results/gatling//login-tree-600.tar.gz --exclude=simulation.log * 2024-05-25 03:12:20,330 INFO /results/gatling 2024-05-25 03:12:20,330 INFO 2024-05-25 03:12:20,330 INFO ---------------------- 2024-05-25 03:12:20,330 INFO [25/May/2024 04:10:20] Update /results/gatling//latest 2024-05-25 03:12:20,330 INFO ---------------------- 2024-05-25 03:12:20,330 INFO 2024-05-25 03:12:20,330 INFO ---------------------- 2024-05-25 03:12:20,330 INFO [25/May/2024 04:10:20] Update /results/gatling//latest.tar.gz 2024-05-25 03:12:20,330 INFO ---------------------- 2024-05-25 03:12:20,330 INFO ________________________________________________________________________________ [2024-05-25 03:12:20] login-tree-600 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 03:12:20,331 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-600/stderr.txt is small (0 lines), printing all lines: 2024-05-25 03:12:20,331 DEBUG ----- output ----- 2024-05-25 03:12:20,331 DEBUG 2024-05-25 03:12:20,331 INFO ________________________________________________________________________________ [2024-05-25 03:12:20] login-tree-600 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 03:12:20] login-tree-600 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 03:12:20] login-tree-600 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped