--Task-- name: login-tree-10 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: [] preceding_task: None options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 10} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-25 04:36:02] login-tree-10 pre : N/A ________________________________________________________________________________ [2024-05-25 04:36:02] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-25 04:36:02] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-10/stdout.txt Pod log : /results/orders/login-tree-10 2024-05-25 04:36:02,774 INFO 2024-05-25 04:36:02,774 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 04:36:02,774 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:36:03,103 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:36:03,104 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:03,104 DEBUG --- stdout --- 2024-05-25 04:36:03,104 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:36:03,104 DEBUG --- stderr --- 2024-05-25 04:36:03,104 DEBUG [2024-05-25 04:36:03] - INFO: Get overseer pod resources requests memory 2024-05-25 04:36:03,105 INFO 2024-05-25 04:36:03,106 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-dfd5985cb-fzzf7 --output jsonpath={.spec.containers[].resources.requests.memory} 2024-05-25 04:36:03,106 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:36:03,509 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:03,509 DEBUG --- stdout --- 2024-05-25 04:36:03,509 DEBUG 4Gi 2024-05-25 04:36:03,509 DEBUG --- stderr --- 2024-05-25 04:36:03,509 DEBUG ________________________________________________________________________________ [2024-05-25 04:36:03] login-tree-10 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-25 04:36:03,510 INFO 2024-05-25 04:36:03,510 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 04:36:03,510 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:36:03,819 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:36:03,819 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:03,819 DEBUG --- stdout --- 2024-05-25 04:36:03,819 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:36:03,819 DEBUG --- stderr --- 2024-05-25 04:36:03,819 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-10 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-25 04:36:03] login-tree-10 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-25 04:36:03,820 INFO 2024-05-25 04:36:03,820 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 04:36:03,820 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:36:04,119 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:36:04,119 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:04,119 DEBUG --- stdout --- 2024-05-25 04:36:04,119 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:36:04,119 DEBUG --- stderr --- 2024-05-25 04:36:04,119 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json { "name": "login-tree-10", "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-10 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-10", "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=10 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-25 04:36:04] login-tree-10 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 04:36:04,121 INFO 2024-05-25 04:36:04,121 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-25 04:36:04,121 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 04:36:04,421 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 04:36:04,421 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:04,421 DEBUG --- stdout --- 2024-05-25 04:36:04,421 DEBUG WAITING 2024-05-25 04:36:04,421 DEBUG --- stderr --- 2024-05-25 04:36:04,421 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-05-25 04:36:04] login-tree-10 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-25 04:36:04,422 INFO 2024-05-25 04:36:04,422 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 04:36:04,422 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:36:04,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:36:04,727 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:04,727 DEBUG --- stdout --- 2024-05-25 04:36:04,727 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:36:04,727 DEBUG --- stderr --- 2024-05-25 04:36:04,727 DEBUG 2024-05-25 04:36:04,727 INFO 2024-05-25 04:36:04,727 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/01_login-tree-10/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-25 04:36:04,727 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 04:36:06,102 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:06,103 DEBUG --- stdout --- 2024-05-25 04:36:06,103 DEBUG 2024-05-25 04:36:06,103 DEBUG --- stderr --- 2024-05-25 04:36:06,103 DEBUG Order has been successfully forwarded to overseer [2024-05-25 04:36:06] - INFO: Check if Gatling simulation started successfully 2024-05-25 04:36:06,103 INFO 2024-05-25 04:36:06,103 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 04:36:06,104 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 04:36:06,419 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 04:36:06,419 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:06,419 DEBUG --- stdout --- 2024-05-25 04:36:06,419 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 04:36:06,420 DEBUG --- stderr --- 2024-05-25 04:36:06,420 DEBUG 2024-05-25 04:36:06,420 INFO 2024-05-25 04:36:06,420 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-10/stdout.txt | grep "started..." 2024-05-25 04:36:06,420 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-25 04:36:07,203 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-25 04:36:12,945 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-05-25 04:36:18,690 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-05-25 04:36:18,690 INFO [loop_until]: OK (rc = 0) 2024-05-25 04:36:18,690 DEBUG --- stdout --- 2024-05-25 04:36:18,690 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 05:36:08.239 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 05:36:12.079 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 05:36:12.081 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 05:36:13.661 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 04:36:18,691 DEBUG --- stderr --- 2024-05-25 04:36:18,691 DEBUG ________________________________________________________________________________ [2024-05-25 04:36:18] login-tree-10 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 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-05-25 05:38:28] login-tree-10 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-25 05:38:28,889 INFO 2024-05-25 05:38:28,890 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 05:38:28,890 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:29,222 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:29,223 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:29,223 DEBUG --- stdout --- 2024-05-25 05:38:29,223 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:29,223 DEBUG --- stderr --- 2024-05-25 05:38:29,223 DEBUG 2024-05-25 05:38:29,223 INFO 2024-05-25 05:38:29,223 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 05:38:29,223 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 05:38:30,014 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:30,016 DEBUG --- stdout --- 2024-05-25 05:38:30,016 DEBUG 2024-05-25 05:38:30,016 DEBUG --- stderr --- 2024-05-25 05:38:30,016 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-25 05:38:30] login-tree-10 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-25 05:38:30] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/order.json 2024-05-25 05:38:30,017 INFO 2024-05-25 05:38:30,017 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 05:38:30,017 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:30,331 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:30,331 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:30,331 DEBUG --- stdout --- 2024-05-25 05:38:30,331 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:30,331 DEBUG --- stderr --- 2024-05-25 05:38:30,331 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 05:38:30,332 INFO 2024-05-25 05:38:30,332 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 05:38:30,332 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:30,641 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:30,641 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:30,641 DEBUG --- stdout --- 2024-05-25 05:38:30,641 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:30,641 DEBUG --- stderr --- 2024-05-25 05:38:30,641 DEBUG 2024-05-25 05:38:30,642 INFO 2024-05-25 05:38:30,642 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-10/rc.txt" ]; then echo "/results/orders/login-tree-10/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-10/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/rc.txt file found" 2024-05-25 05:38:30,642 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 05:38:31,441 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 05:38:31,441 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:31,441 DEBUG --- stdout --- 2024-05-25 05:38:31,441 DEBUG /results/orders/login-tree-10/rc.txt file found 2024-05-25 05:38:31,441 DEBUG --- stderr --- 2024-05-25 05:38:31,441 DEBUG 2024-05-25 05:38:31,442 INFO 2024-05-25 05:38:31,442 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 05:38:31,442 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:31,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:31,752 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:31,753 DEBUG --- stdout --- 2024-05-25 05:38:31,753 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:31,753 DEBUG --- stderr --- 2024-05-25 05:38:31,753 DEBUG 2024-05-25 05:38:31,753 INFO 2024-05-25 05:38:31,753 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-10/rc.txt 2024-05-25 05:38:32,562 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 05:38:32,563 DEBUG --- stdout --- 2024-05-25 05:38:32,563 DEBUG 4 /results/orders/login-tree-10/rc.txt 2024-05-25 05:38:32,563 DEBUG --- stderr --- 2024-05-25 05:38:32,563 DEBUG 2024-05-25 05:38:32,563 INFO - Download file (size 4k) 2024-05-25 05:38:32,563 INFO 2024-05-25 05:38:32,563 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 05:38:32,563 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:32,867 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:32,867 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:32,867 DEBUG --- stdout --- 2024-05-25 05:38:32,867 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:32,867 DEBUG --- stderr --- 2024-05-25 05:38:32,867 DEBUG source : /results/orders/login-tree-10/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-05-25 05:38:32,867 INFO 2024-05-25 05:38:32,867 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 05:38:32,867 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:33,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:33,170 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:33,170 DEBUG --- stdout --- 2024-05-25 05:38:33,170 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:33,170 DEBUG --- stderr --- 2024-05-25 05:38:33,170 DEBUG 2024-05-25 05:38:33,170 INFO 2024-05-25 05:38:33,170 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-10/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt 2024-05-25 05:38:33,170 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 05:38:33,947 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:33,948 DEBUG --- stdout --- 2024-05-25 05:38:33,948 DEBUG tar: Removing leading `/' from member names 2024-05-25 05:38:33,948 DEBUG --- stderr --- 2024-05-25 05:38:33,948 DEBUG 2024-05-25 05:38:33,948 INFO 2024-05-25 05:38:33,948 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 05:38:33,948 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:34,253 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:34,254 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:34,254 DEBUG --- stdout --- 2024-05-25 05:38:34,254 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:34,254 DEBUG --- stderr --- 2024-05-25 05:38:34,254 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 05:38:34,254 INFO 2024-05-25 05:38:34,254 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 05:38:34,254 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:34,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:34,561 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:34,561 DEBUG --- stdout --- 2024-05-25 05:38:34,561 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:34,561 DEBUG --- stderr --- 2024-05-25 05:38:34,561 DEBUG 2024-05-25 05:38:34,562 INFO 2024-05-25 05:38:34,562 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-10/stderr.txt" ]; then echo "/results/orders/login-tree-10/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stderr.txt file found" 2024-05-25 05:38:34,562 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 05:38:35,373 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 05:38:35,374 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:35,374 DEBUG --- stdout --- 2024-05-25 05:38:35,374 DEBUG /results/orders/login-tree-10/stderr.txt file found 2024-05-25 05:38:35,374 DEBUG --- stderr --- 2024-05-25 05:38:35,374 DEBUG 2024-05-25 05:38:35,375 INFO 2024-05-25 05:38:35,375 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 05:38:35,375 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:35,679 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:35,679 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:35,679 DEBUG --- stdout --- 2024-05-25 05:38:35,679 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:35,679 DEBUG --- stderr --- 2024-05-25 05:38:35,679 DEBUG 2024-05-25 05:38:35,679 INFO 2024-05-25 05:38:35,680 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-10/stderr.txt 2024-05-25 05:38:36,477 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 05:38:36,477 DEBUG --- stdout --- 2024-05-25 05:38:36,477 DEBUG 0 /results/orders/login-tree-10/stderr.txt 2024-05-25 05:38:36,477 DEBUG --- stderr --- 2024-05-25 05:38:36,477 DEBUG 2024-05-25 05:38:36,478 INFO - Download file (size 0k) 2024-05-25 05:38:36,478 INFO 2024-05-25 05:38:36,478 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 05:38:36,478 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:36,784 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:36,784 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:36,784 DEBUG --- stdout --- 2024-05-25 05:38:36,784 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:36,784 DEBUG --- stderr --- 2024-05-25 05:38:36,784 DEBUG source : /results/orders/login-tree-10/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-05-25 05:38:36,785 INFO 2024-05-25 05:38:36,785 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 05:38:36,785 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:37,094 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:37,095 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:37,095 DEBUG --- stdout --- 2024-05-25 05:38:37,095 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:37,095 DEBUG --- stderr --- 2024-05-25 05:38:37,095 DEBUG 2024-05-25 05:38:37,095 INFO 2024-05-25 05:38:37,095 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-10/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt 2024-05-25 05:38:37,095 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 05:38:37,861 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:37,861 DEBUG --- stdout --- 2024-05-25 05:38:37,861 DEBUG tar: Removing leading `/' from member names 2024-05-25 05:38:37,861 DEBUG --- stderr --- 2024-05-25 05:38:37,861 DEBUG 2024-05-25 05:38:37,862 INFO 2024-05-25 05:38:37,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 05:38:37,862 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:38,176 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:38,176 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:38,176 DEBUG --- stdout --- 2024-05-25 05:38:38,176 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:38,176 DEBUG --- stderr --- 2024-05-25 05:38:38,176 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 05:38:38,177 INFO 2024-05-25 05:38:38,177 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 05:38:38,177 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:38,475 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:38,475 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:38,475 DEBUG --- stdout --- 2024-05-25 05:38:38,475 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:38,475 DEBUG --- stderr --- 2024-05-25 05:38:38,475 DEBUG 2024-05-25 05:38:38,476 INFO 2024-05-25 05:38:38,476 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-10/stdout.txt" ]; then echo "/results/orders/login-tree-10/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-10/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/stdout.txt file found" 2024-05-25 05:38:38,476 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 05:38:39,274 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 05:38:39,274 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:39,274 DEBUG --- stdout --- 2024-05-25 05:38:39,275 DEBUG /results/orders/login-tree-10/stdout.txt file found 2024-05-25 05:38:39,275 DEBUG --- stderr --- 2024-05-25 05:38:39,275 DEBUG 2024-05-25 05:38:39,275 INFO 2024-05-25 05:38:39,275 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 05:38:39,275 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:39,579 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:39,579 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:39,579 DEBUG --- stdout --- 2024-05-25 05:38:39,579 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:39,579 DEBUG --- stderr --- 2024-05-25 05:38:39,579 DEBUG 2024-05-25 05:38:39,579 INFO 2024-05-25 05:38:39,579 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-10/stdout.txt 2024-05-25 05:38:40,389 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 05:38:40,391 DEBUG --- stdout --- 2024-05-25 05:38:40,391 DEBUG 632 /results/orders/login-tree-10/stdout.txt 2024-05-25 05:38:40,391 DEBUG --- stderr --- 2024-05-25 05:38:40,391 DEBUG 2024-05-25 05:38:40,391 INFO - Download file (size 632k) 2024-05-25 05:38:40,392 INFO 2024-05-25 05:38:40,392 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 05:38:40,392 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:40,696 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:40,696 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:40,696 DEBUG --- stdout --- 2024-05-25 05:38:40,696 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:40,696 DEBUG --- stderr --- 2024-05-25 05:38:40,696 DEBUG source : /results/orders/login-tree-10/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-05-25 05:38:40,696 INFO 2024-05-25 05:38:40,696 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 05:38:40,697 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:40,999 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:41,000 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:41,000 DEBUG --- stdout --- 2024-05-25 05:38:41,000 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:41,000 DEBUG --- stderr --- 2024-05-25 05:38:41,000 DEBUG 2024-05-25 05:38:41,000 INFO 2024-05-25 05:38:41,001 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-10/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stdout.txt 2024-05-25 05:38:41,001 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 05:38:42,055 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:42,055 DEBUG --- stdout --- 2024-05-25 05:38:42,055 DEBUG tar: Removing leading `/' from member names 2024-05-25 05:38:42,055 DEBUG --- stderr --- 2024-05-25 05:38:42,056 DEBUG ________________________________________________________________________________ [2024-05-25 05:38:42] login-tree-10 show_cmd : Display order command ________________________________________________________________________________ 2024-05-25 05:38:42,056 INFO 2024-05-25 05:38:42,056 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-25 05:38:42,056 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:42,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:42,364 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:42,364 DEBUG --- stdout --- 2024-05-25 05:38:42,364 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:42,364 DEBUG --- stderr --- 2024-05-25 05:38:42,364 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-25 05:38:42,364 INFO 2024-05-25 05:38:42,364 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 05:38:42,364 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:42,667 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:42,668 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:42,668 DEBUG --- stdout --- 2024-05-25 05:38:42,668 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:42,668 DEBUG --- stderr --- 2024-05-25 05:38:42,668 DEBUG 2024-05-25 05:38:42,668 INFO 2024-05-25 05:38:42,668 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-10/cmd.txt" ]; then echo "/results/orders/login-tree-10/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-10/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-10/cmd.txt file found" 2024-05-25 05:38:42,668 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-25 05:38:43,476 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-25 05:38:43,476 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:43,476 DEBUG --- stdout --- 2024-05-25 05:38:43,476 DEBUG /results/orders/login-tree-10/cmd.txt file found 2024-05-25 05:38:43,476 DEBUG --- stderr --- 2024-05-25 05:38:43,476 DEBUG 2024-05-25 05:38:43,476 INFO 2024-05-25 05:38:43,476 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 05:38:43,477 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:43,790 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:43,790 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:43,790 DEBUG --- stdout --- 2024-05-25 05:38:43,790 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:43,790 DEBUG --- stderr --- 2024-05-25 05:38:43,790 DEBUG 2024-05-25 05:38:43,790 INFO 2024-05-25 05:38:43,790 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-10/cmd.txt 2024-05-25 05:38:44,594 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-25 05:38:44,594 DEBUG --- stdout --- 2024-05-25 05:38:44,595 DEBUG 4 /results/orders/login-tree-10/cmd.txt 2024-05-25 05:38:44,595 DEBUG --- stderr --- 2024-05-25 05:38:44,595 DEBUG 2024-05-25 05:38:44,595 INFO - Download file (size 4k) 2024-05-25 05:38:44,595 INFO 2024-05-25 05:38:44,595 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 05:38:44,595 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:44,899 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:44,900 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:44,900 DEBUG --- stdout --- 2024-05-25 05:38:44,900 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:44,900 DEBUG --- stderr --- 2024-05-25 05:38:44,900 DEBUG source : /results/orders/login-tree-10/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-05-25 05:38:44,900 INFO 2024-05-25 05:38:44,900 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 05:38:44,900 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-25 05:38:45,200 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-25 05:38:45,200 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:45,200 DEBUG --- stdout --- 2024-05-25 05:38:45,200 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-25 05:38:45,200 DEBUG --- stderr --- 2024-05-25 05:38:45,200 DEBUG 2024-05-25 05:38:45,201 INFO 2024-05-25 05:38:45,201 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-10/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt 2024-05-25 05:38:45,201 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-25 05:38:45,921 INFO [loop_until]: OK (rc = 0) 2024-05-25 05:38:45,921 DEBUG --- stdout --- 2024-05-25 05:38:45,921 DEBUG tar: Removing leading `/' from member names 2024-05-25 05:38:45,921 DEBUG --- stderr --- 2024-05-25 05:38:45,921 DEBUG 2024-05-25 05:38:45,921 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/cmd.txt is small (1 lines), printing all lines: 2024-05-25 05:38:45,921 DEBUG ----- output ----- 2024-05-25 05:38:45,921 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-10 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-25 05:38:45,921 INFO ________________________________________________________________________________ [2024-05-25 05:38:45] login-tree-10 show_rc : Display rc order ________________________________________________________________________________ 2024-05-25 05:38:45,922 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/rc.txt is small (1 lines), printing all lines: 2024-05-25 05:38:45,922 DEBUG ----- output ----- 2024-05-25 05:38:45,922 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-25 05:38:45,922 INFO ________________________________________________________________________________ [2024-05-25 05:38:45] login-tree-10 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-25 05:38:45,924 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/01_login-tree-10/stdout.txt is 10354 lines): 2024-05-25 05:38:45,924 DEBUG ----- output ----- 2024-05-25 05:38:45,924 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-25 05:38:45,924 INFO 05:36:08.239 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-05-25 05:38:45,924 INFO 05:36:12.079 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-05-25 05:38:45,924 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-25 05:38:45,924 INFO ^ 2024-05-25 05:38:45,924 INFO 05:36:12.081 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-05-25 05:38:45,924 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-25 05:38:45,924 INFO ^ 2024-05-25 05:38:45,924 INFO 05:36:13.661 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-05-25 05:38:45,924 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-05-25 05:38:45,924 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-25 05:38:45,924 INFO 2024-05-25 05:38:45,924 INFO ================================================================================ 2024-05-25 05:38:45,924 INFO 2024-05-25 05:36:21 5s elapsed 2024-05-25 05:38:45,924 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,924 INFO > Global (OK=7 KO=0 ) 2024-05-25 05:38:45,924 INFO > restLoginInitiate (OK=4 KO=0 ) 2024-05-25 05:38:45,924 INFO > restLoginUsernamePasswordCallback (OK=3 KO=0 ) 2024-05-25 05:38:45,924 INFO 2024-05-25 05:38:45,924 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,924 INFO [- ] 0% 2024-05-25 05:38:45,925 INFO waiting: 36555 / active: 2 / done: 3 2024-05-25 05:38:45,925 INFO ================================================================================ 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO ================================================================================ 2024-05-25 05:38:45,925 INFO 2024-05-25 05:36:26 10s elapsed 2024-05-25 05:38:45,925 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,925 INFO > Global (OK=32 KO=0 ) 2024-05-25 05:38:45,925 INFO > restLoginInitiate (OK=16 KO=0 ) 2024-05-25 05:38:45,925 INFO > restLoginUsernamePasswordCallback (OK=16 KO=0 ) 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,925 INFO [- ] 0% 2024-05-25 05:38:45,925 INFO waiting: 36543 / active: 1 / done: 16 2024-05-25 05:38:45,925 INFO ================================================================================ 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO ================================================================================ 2024-05-25 05:38:45,925 INFO 2024-05-25 05:36:31 15s elapsed 2024-05-25 05:38:45,925 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,925 INFO > Global (OK=74 KO=0 ) 2024-05-25 05:38:45,925 INFO > restLoginInitiate (OK=37 KO=0 ) 2024-05-25 05:38:45,925 INFO > restLoginUsernamePasswordCallback (OK=37 KO=0 ) 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,925 INFO [- ] 0% 2024-05-25 05:38:45,925 INFO waiting: 36522 / active: 1 / done: 37 2024-05-25 05:38:45,925 INFO ================================================================================ 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO ================================================================================ 2024-05-25 05:38:45,925 INFO 2024-05-25 05:36:36 20s elapsed 2024-05-25 05:38:45,925 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,925 INFO > Global (OK=132 KO=0 ) 2024-05-25 05:38:45,925 INFO > restLoginInitiate (OK=66 KO=0 ) 2024-05-25 05:38:45,925 INFO > restLoginUsernamePasswordCallback (OK=66 KO=0 ) 2024-05-25 05:38:45,925 INFO 2024-05-25 05:38:45,925 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,925 INFO [- ] 0% 2024-05-25 05:38:45,925 INFO waiting: 36493 / active: 1 / done: 66 2024-05-25 05:38:45,926 INFO ================================================================================ 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO ================================================================================ 2024-05-25 05:38:45,926 INFO 2024-05-25 05:36:41 25s elapsed 2024-05-25 05:38:45,926 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,926 INFO > Global (OK=208 KO=0 ) 2024-05-25 05:38:45,926 INFO > restLoginInitiate (OK=104 KO=0 ) 2024-05-25 05:38:45,926 INFO > restLoginUsernamePasswordCallback (OK=104 KO=0 ) 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,926 INFO [- ] 0% 2024-05-25 05:38:45,926 INFO waiting: 36455 / active: 1 / done: 104 2024-05-25 05:38:45,926 INFO ================================================================================ 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO ================================================================================ 2024-05-25 05:38:45,926 INFO 2024-05-25 05:36:46 30s elapsed 2024-05-25 05:38:45,926 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,926 INFO > Global (OK=300 KO=0 ) 2024-05-25 05:38:45,926 INFO > restLoginInitiate (OK=150 KO=0 ) 2024-05-25 05:38:45,926 INFO > restLoginUsernamePasswordCallback (OK=150 KO=0 ) 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,926 INFO [ ] 0% 2024-05-25 05:38:45,926 INFO waiting: 36410 / active: 0 / done: 150 2024-05-25 05:38:45,926 INFO ================================================================================ 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO ================================================================================ 2024-05-25 05:38:45,926 INFO 2024-05-25 05:36:51 35s elapsed 2024-05-25 05:38:45,926 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,926 INFO > Global (OK=390 KO=0 ) 2024-05-25 05:38:45,926 INFO > restLoginInitiate (OK=195 KO=0 ) 2024-05-25 05:38:45,926 INFO > restLoginUsernamePasswordCallback (OK=195 KO=0 ) 2024-05-25 05:38:45,926 INFO 2024-05-25 05:38:45,926 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,926 INFO [ ] 0% 2024-05-25 05:38:45,927 INFO waiting: 36365 / active: 0 / done: 195 2024-05-25 05:38:45,927 INFO [...] 2024-05-25 05:38:45,929 INFO 2024-05-25 05:38:45,929 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,929 INFO [######################################################################### ] 99% 2024-05-25 05:38:45,929 INFO waiting: 227 / active: 0 / done: 36333 2024-05-25 05:38:45,929 INFO ================================================================================ 2024-05-25 05:38:45,929 INFO 2024-05-25 05:38:45,929 INFO 2024-05-25 05:38:45,929 INFO ================================================================================ 2024-05-25 05:38:45,929 INFO 2024-05-25 06:36:26 3610s elapsed 2024-05-25 05:38:45,929 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,929 INFO > Global (OK=72774 KO=1 ) 2024-05-25 05:38:45,930 INFO > restLoginInitiate (OK=36387 KO=1 ) 2024-05-25 05:38:45,930 INFO > restLoginUsernamePasswordCallback (OK=36387 KO=0 ) 2024-05-25 05:38:45,930 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 05:38:45,930 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,930 INFO [######################################################################### ] 99% 2024-05-25 05:38:45,930 INFO waiting: 172 / active: 0 / done: 36388 2024-05-25 05:38:45,930 INFO ================================================================================ 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO ================================================================================ 2024-05-25 05:38:45,930 INFO 2024-05-25 06:36:31 3615s elapsed 2024-05-25 05:38:45,930 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,930 INFO > Global (OK=72862 KO=1 ) 2024-05-25 05:38:45,930 INFO > restLoginInitiate (OK=36431 KO=1 ) 2024-05-25 05:38:45,930 INFO > restLoginUsernamePasswordCallback (OK=36431 KO=0 ) 2024-05-25 05:38:45,930 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 05:38:45,930 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,930 INFO [#########################################################################-] 99% 2024-05-25 05:38:45,930 INFO waiting: 127 / active: 1 / done: 36432 2024-05-25 05:38:45,930 INFO ================================================================================ 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO ================================================================================ 2024-05-25 05:38:45,930 INFO 2024-05-25 06:36:36 3620s elapsed 2024-05-25 05:38:45,930 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,930 INFO > Global (OK=72942 KO=1 ) 2024-05-25 05:38:45,930 INFO > restLoginInitiate (OK=36471 KO=1 ) 2024-05-25 05:38:45,930 INFO > restLoginUsernamePasswordCallback (OK=36471 KO=0 ) 2024-05-25 05:38:45,930 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 05:38:45,930 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 05:38:45,930 INFO 2024-05-25 05:38:45,930 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,930 INFO [######################################################################### ] 99% 2024-05-25 05:38:45,930 INFO waiting: 88 / active: 0 / done: 36472 2024-05-25 05:38:45,931 INFO ================================================================================ 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO ================================================================================ 2024-05-25 05:38:45,931 INFO 2024-05-25 06:36:41 3625s elapsed 2024-05-25 05:38:45,931 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,931 INFO > Global (OK=73030 KO=1 ) 2024-05-25 05:38:45,931 INFO > restLoginInitiate (OK=36515 KO=1 ) 2024-05-25 05:38:45,931 INFO > restLoginUsernamePasswordCallback (OK=36515 KO=0 ) 2024-05-25 05:38:45,931 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 05:38:45,931 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,931 INFO [######################################################################### ] 99% 2024-05-25 05:38:45,931 INFO waiting: 44 / active: 0 / done: 36516 2024-05-25 05:38:45,931 INFO ================================================================================ 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO ================================================================================ 2024-05-25 05:38:45,931 INFO 2024-05-25 06:36:46 3630s elapsed 2024-05-25 05:38:45,931 INFO ---- Requests ------------------------------------------------------------------ 2024-05-25 05:38:45,931 INFO > Global (OK=73118 KO=1 ) 2024-05-25 05:38:45,931 INFO > restLoginInitiate (OK=36559 KO=1 ) 2024-05-25 05:38:45,931 INFO > restLoginUsernamePasswordCallback (OK=36559 KO=0 ) 2024-05-25 05:38:45,931 INFO ---- Errors -------------------------------------------------------------------- 2024-05-25 05:38:45,931 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-25 05:38:45,931 INFO [##########################################################################]100% 2024-05-25 05:38:45,931 INFO waiting: 0 / active: 0 / done: 36560 2024-05-25 05:38:45,931 INFO ================================================================================ 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-25 05:38:45,931 INFO 2024-05-25 05:38:45,931 INFO ---------------------- 2024-05-25 05:38:45,931 INFO [25/May/2024 06:36:46] Create tar file login-tree-10.tar.gz 2024-05-25 05:38:45,931 INFO ---------------------- 2024-05-25 05:38:45,931 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-25 05:38:45,931 INFO RESULT_FOLDER=login-tree-10 2024-05-25 05:38:45,931 INFO SIMULATION_PATH=login-tree-10/loadtest-20240525043615678 2024-05-25 05:38:45,931 INFO PWD=/results/gatling/login-tree-10/loadtest-20240525043615678 2024-05-25 05:38:45,932 INFO [cmd] tar -czvf /results/gatling//login-tree-10.tar.gz --exclude=simulation.log * 2024-05-25 05:38:45,932 INFO /results/gatling 2024-05-25 05:38:45,932 INFO 2024-05-25 05:38:45,932 INFO ---------------------- 2024-05-25 05:38:45,932 INFO [25/May/2024 06:36:46] Update /results/gatling//latest 2024-05-25 05:38:45,932 INFO ---------------------- 2024-05-25 05:38:45,932 INFO 2024-05-25 05:38:45,932 INFO ---------------------- 2024-05-25 05:38:45,932 INFO [25/May/2024 06:36:46] Update /results/gatling//latest.tar.gz 2024-05-25 05:38:45,932 INFO ---------------------- 2024-05-25 05:38:45,932 INFO ________________________________________________________________________________ [2024-05-25 05:38:45] login-tree-10 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-25 05:38:45,932 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/01_login-tree-10/stderr.txt is small (0 lines), printing all lines: 2024-05-25 05:38:45,932 DEBUG ----- output ----- 2024-05-25 05:38:45,932 DEBUG 2024-05-25 05:38:45,932 INFO ________________________________________________________________________________ [2024-05-25 05:38:45] login-tree-10 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 05:38:45] login-tree-10 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-25 05:38:45] login-tree-10 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped