--Task-- name: login-tree-100 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-10'] preceding_task: login-tree-10 options: {'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd', 'rampup-duration': 30, 'testCase': 'idc.login', 'testMode': 'open', 'throughput': 100} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-05-24 20:56:27] login-tree-100 pre : N/A ________________________________________________________________________________ [2024-05-24 20:56:27] - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) [2024-05-24 20:56:27] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-100/stdout.txt Pod log : /results/orders/login-tree-100 2024-05-24 20:56:27,861 INFO 2024-05-24 20:56:27,861 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-24 20:56:27,861 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:28,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:28,171 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:28,171 DEBUG --- stdout --- 2024-05-24 20:56:28,172 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:28,172 DEBUG --- stderr --- 2024-05-24 20:56:28,172 DEBUG ________________________________________________________________________________ [2024-05-24 20:56:28] login-tree-100 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-05-24 20:56:28,172 INFO 2024-05-24 20:56:28,172 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-24 20:56:28,172 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:28,481 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:28,481 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:28,481 DEBUG --- stdout --- 2024-05-24 20:56:28,481 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:28,481 DEBUG --- stderr --- 2024-05-24 20:56:28,481 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-100 --extra-run-jvm-options "-Xmx12G" --no-reports ________________________________________________________________________________ [2024-05-24 20:56:28] login-tree-100 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-05-24 20:56:28,482 INFO 2024-05-24 20:56:28,482 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-24 20:56:28,482 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:28,795 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:28,796 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:28,796 DEBUG --- stdout --- 2024-05-24 20:56:28,796 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:28,796 DEBUG --- stderr --- 2024-05-24 20:56:28,796 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json { "name": "login-tree-100", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-3580\" --results-folder /results/gatling/login-tree-100 --extra-run-jvm-options \"-Xmx12G\" --no-reports", "out-dir": "/results/orders/login-tree-100", "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=100 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-05-24 20:56:28] login-tree-100 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-05-24 20:56:28,798 INFO 2024-05-24 20:56:28,798 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-05-24 20:56:28,798 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 20:56:29,047 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 20:56:29,047 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:29,047 DEBUG --- stdout --- 2024-05-24 20:56:29,047 DEBUG WAITING 2024-05-24 20:56:29,047 DEBUG --- stderr --- 2024-05-24 20:56:29,048 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-24 20:56:29] login-tree-100 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-05-24 20:56:29,048 INFO 2024-05-24 20:56:29,048 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-24 20:56:29,048 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:29,362 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:29,363 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:29,363 DEBUG --- stdout --- 2024-05-24 20:56:29,363 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:29,363 DEBUG --- stderr --- 2024-05-24 20:56:29,363 DEBUG 2024-05-24 20:56:29,363 INFO 2024-05-24 20:56:29,363 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json overseer-0-dfd5985cb-fzzf7:/results/orders/order.json 2024-05-24 20:56:29,363 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 20:56:30,719 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:30,719 DEBUG --- stdout --- 2024-05-24 20:56:30,719 DEBUG 2024-05-24 20:56:30,719 DEBUG --- stderr --- 2024-05-24 20:56:30,719 DEBUG Order has been successfully forwarded to overseer [2024-05-24 20:56:30] - INFO: Check if Gatling simulation started successfully 2024-05-24 20:56:30,719 INFO 2024-05-24 20:56:30,719 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-24 20:56:30,719 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 20:56:31,028 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 20:56:31,028 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:31,028 DEBUG --- stdout --- 2024-05-24 20:56:31,028 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 20:56:31,028 DEBUG --- stderr --- 2024-05-24 20:56:31,028 DEBUG 2024-05-24 20:56:31,028 INFO 2024-05-24 20:56:31,028 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-100/stdout.txt | grep "started..." 2024-05-24 20:56:31,028 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-05-24 20:56:31,765 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-05-24 20:56:37,514 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-05-24 20:56:43,256 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-05-24 20:56:43,256 INFO [loop_until]: OK (rc = 0) 2024-05-24 20:56:43,256 DEBUG --- stdout --- 2024-05-24 20:56:43,256 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 21:56:32.838 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 21:56:36.686 [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"); ^ 21:56:36.688 [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"); ^ 21:56:38.223 [WARN ] i.g.c.ZincCompiler$ - three warnings found Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 20:56:43,256 DEBUG --- stderr --- 2024-05-24 20:56:43,256 DEBUG ________________________________________________________________________________ [2024-05-24 20:56:43] login-tree-100 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 01s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 02s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 03s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 04s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 05s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 06s, retry Order is running since 44m 06s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 08s, 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-24 21:58:53] login-tree-100 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-05-24 21:58:53,050 INFO 2024-05-24 21:58:53,050 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-24 21:58:53,050 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:53,383 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:53,383 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:53,383 DEBUG --- stdout --- 2024-05-24 21:58:53,383 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:53,383 DEBUG --- stderr --- 2024-05-24 21:58:53,383 DEBUG 2024-05-24 21:58:53,384 INFO 2024-05-24 21:58:53,384 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-24 21:58:53,384 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 21:58:54,173 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:54,173 DEBUG --- stdout --- 2024-05-24 21:58:54,173 DEBUG 2024-05-24 21:58:54,173 DEBUG --- stderr --- 2024-05-24 21:58:54,173 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-05-24 21:58:54] login-tree-100 download_logs : Download individual order files ________________________________________________________________________________ [2024-05-24 21:58:54] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/order.json 2024-05-24 21:58:54,175 INFO 2024-05-24 21:58:54,175 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 21:58:54,175 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:54,482 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:54,482 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:54,482 DEBUG --- stdout --- 2024-05-24 21:58:54,482 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:54,482 DEBUG --- stderr --- 2024-05-24 21:58:54,482 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 21:58:54,482 INFO 2024-05-24 21:58:54,482 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-24 21:58:54,483 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:54,788 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:54,790 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:54,790 DEBUG --- stdout --- 2024-05-24 21:58:54,790 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:54,790 DEBUG --- stderr --- 2024-05-24 21:58:54,790 DEBUG 2024-05-24 21:58:54,791 INFO 2024-05-24 21:58:54,791 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-100/rc.txt" ]; then echo "/results/orders/login-tree-100/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-100/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/rc.txt file found" 2024-05-24 21:58:54,791 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 21:58:55,592 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 21:58:55,592 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:55,592 DEBUG --- stdout --- 2024-05-24 21:58:55,592 DEBUG /results/orders/login-tree-100/rc.txt file found 2024-05-24 21:58:55,592 DEBUG --- stderr --- 2024-05-24 21:58:55,592 DEBUG 2024-05-24 21:58:55,592 INFO 2024-05-24 21:58:55,593 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-24 21:58:55,593 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:55,892 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:55,892 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:55,892 DEBUG --- stdout --- 2024-05-24 21:58:55,892 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:55,893 DEBUG --- stderr --- 2024-05-24 21:58:55,893 DEBUG 2024-05-24 21:58:55,893 INFO 2024-05-24 21:58:55,893 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-100/rc.txt 2024-05-24 21:58:56,691 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 21:58:56,691 DEBUG --- stdout --- 2024-05-24 21:58:56,691 DEBUG 4 /results/orders/login-tree-100/rc.txt 2024-05-24 21:58:56,691 DEBUG --- stderr --- 2024-05-24 21:58:56,691 DEBUG 2024-05-24 21:58:56,691 INFO - Download file (size 4k) 2024-05-24 21:58:56,692 INFO 2024-05-24 21:58:56,692 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 21:58:56,692 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:56,994 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:56,994 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:56,994 DEBUG --- stdout --- 2024-05-24 21:58:56,994 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:56,994 DEBUG --- stderr --- 2024-05-24 21:58:56,994 DEBUG source : /results/orders/login-tree-100/rc.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-05-24 21:58:56,995 INFO 2024-05-24 21:58:56,995 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-24 21:58:56,995 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:57,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:57,299 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:57,300 DEBUG --- stdout --- 2024-05-24 21:58:57,300 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:57,300 DEBUG --- stderr --- 2024-05-24 21:58:57,300 DEBUG 2024-05-24 21:58:57,300 INFO 2024-05-24 21:58:57,300 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-100/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt 2024-05-24 21:58:57,300 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 21:58:58,031 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:58,031 DEBUG --- stdout --- 2024-05-24 21:58:58,031 DEBUG tar: Removing leading `/' from member names 2024-05-24 21:58:58,031 DEBUG --- stderr --- 2024-05-24 21:58:58,031 DEBUG 2024-05-24 21:58:58,032 INFO 2024-05-24 21:58:58,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-24 21:58:58,032 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:58,329 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:58,329 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:58,329 DEBUG --- stdout --- 2024-05-24 21:58:58,329 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:58,329 DEBUG --- stderr --- 2024-05-24 21:58:58,329 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 21:58:58,330 INFO 2024-05-24 21:58:58,330 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-24 21:58:58,330 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:58,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:58,632 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:58,632 DEBUG --- stdout --- 2024-05-24 21:58:58,632 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:58,632 DEBUG --- stderr --- 2024-05-24 21:58:58,632 DEBUG 2024-05-24 21:58:58,633 INFO 2024-05-24 21:58:58,633 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-100/stderr.txt" ]; then echo "/results/orders/login-tree-100/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stderr.txt file found" 2024-05-24 21:58:58,633 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 21:58:59,428 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 21:58:59,428 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:59,428 DEBUG --- stdout --- 2024-05-24 21:58:59,428 DEBUG /results/orders/login-tree-100/stderr.txt file found 2024-05-24 21:58:59,428 DEBUG --- stderr --- 2024-05-24 21:58:59,428 DEBUG 2024-05-24 21:58:59,428 INFO 2024-05-24 21:58:59,428 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-24 21:58:59,428 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:58:59,736 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:58:59,737 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:58:59,737 DEBUG --- stdout --- 2024-05-24 21:58:59,737 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:58:59,737 DEBUG --- stderr --- 2024-05-24 21:58:59,737 DEBUG 2024-05-24 21:58:59,737 INFO 2024-05-24 21:58:59,737 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-100/stderr.txt 2024-05-24 21:59:00,537 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 21:59:00,537 DEBUG --- stdout --- 2024-05-24 21:59:00,537 DEBUG 0 /results/orders/login-tree-100/stderr.txt 2024-05-24 21:59:00,537 DEBUG --- stderr --- 2024-05-24 21:59:00,537 DEBUG 2024-05-24 21:59:00,537 INFO - Download file (size 0k) 2024-05-24 21:59:00,537 INFO 2024-05-24 21:59:00,538 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-24 21:59:00,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:00,842 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:00,842 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:00,842 DEBUG --- stdout --- 2024-05-24 21:59:00,842 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:00,842 DEBUG --- stderr --- 2024-05-24 21:59:00,842 DEBUG source : /results/orders/login-tree-100/stderr.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-05-24 21:59:00,843 INFO 2024-05-24 21:59:00,843 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-24 21:59:00,843 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:01,141 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:01,142 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:01,142 DEBUG --- stdout --- 2024-05-24 21:59:01,142 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:01,142 DEBUG --- stderr --- 2024-05-24 21:59:01,142 DEBUG 2024-05-24 21:59:01,142 INFO 2024-05-24 21:59:01,142 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-100/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt 2024-05-24 21:59:01,142 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 21:59:01,871 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:01,871 DEBUG --- stdout --- 2024-05-24 21:59:01,872 DEBUG tar: Removing leading `/' from member names 2024-05-24 21:59:01,872 DEBUG --- stderr --- 2024-05-24 21:59:01,872 DEBUG 2024-05-24 21:59:01,872 INFO 2024-05-24 21:59:01,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-24 21:59:01,872 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:02,177 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:02,177 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:02,177 DEBUG --- stdout --- 2024-05-24 21:59:02,177 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:02,178 DEBUG --- stderr --- 2024-05-24 21:59:02,178 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 21:59:02,178 INFO 2024-05-24 21:59:02,178 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-24 21:59:02,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:02,490 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:02,490 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:02,490 DEBUG --- stdout --- 2024-05-24 21:59:02,490 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:02,490 DEBUG --- stderr --- 2024-05-24 21:59:02,490 DEBUG 2024-05-24 21:59:02,491 INFO 2024-05-24 21:59:02,491 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-100/stdout.txt" ]; then echo "/results/orders/login-tree-100/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-100/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/stdout.txt file found" 2024-05-24 21:59:02,491 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 21:59:03,289 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 21:59:03,289 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:03,289 DEBUG --- stdout --- 2024-05-24 21:59:03,289 DEBUG /results/orders/login-tree-100/stdout.txt file found 2024-05-24 21:59:03,289 DEBUG --- stderr --- 2024-05-24 21:59:03,289 DEBUG 2024-05-24 21:59:03,290 INFO 2024-05-24 21:59:03,290 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-24 21:59:03,290 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:03,591 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:03,591 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:03,591 DEBUG --- stdout --- 2024-05-24 21:59:03,591 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:03,591 DEBUG --- stderr --- 2024-05-24 21:59:03,591 DEBUG 2024-05-24 21:59:03,591 INFO 2024-05-24 21:59:03,592 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-100/stdout.txt 2024-05-24 21:59:04,403 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 21:59:04,403 DEBUG --- stdout --- 2024-05-24 21:59:04,403 DEBUG 640 /results/orders/login-tree-100/stdout.txt 2024-05-24 21:59:04,403 DEBUG --- stderr --- 2024-05-24 21:59:04,403 DEBUG 2024-05-24 21:59:04,403 INFO - Download file (size 640k) 2024-05-24 21:59:04,404 INFO 2024-05-24 21:59:04,404 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-24 21:59:04,404 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:04,711 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:04,711 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:04,711 DEBUG --- stdout --- 2024-05-24 21:59:04,711 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:04,711 DEBUG --- stderr --- 2024-05-24 21:59:04,711 DEBUG source : /results/orders/login-tree-100/stdout.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-05-24 21:59:04,712 INFO 2024-05-24 21:59:04,712 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-24 21:59:04,712 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:05,015 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:05,017 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:05,017 DEBUG --- stdout --- 2024-05-24 21:59:05,017 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:05,017 DEBUG --- stderr --- 2024-05-24 21:59:05,017 DEBUG 2024-05-24 21:59:05,018 INFO 2024-05-24 21:59:05,018 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-100/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt 2024-05-24 21:59:05,018 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 21:59:06,078 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:06,079 DEBUG --- stdout --- 2024-05-24 21:59:06,079 DEBUG tar: Removing leading `/' from member names 2024-05-24 21:59:06,079 DEBUG --- stderr --- 2024-05-24 21:59:06,079 DEBUG ________________________________________________________________________________ [2024-05-24 21:59:06] login-tree-100 show_cmd : Display order command ________________________________________________________________________________ 2024-05-24 21:59:06,080 INFO 2024-05-24 21:59:06,080 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-05-24 21:59:06,080 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:06,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:06,380 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:06,380 DEBUG --- stdout --- 2024-05-24 21:59:06,380 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:06,380 DEBUG --- stderr --- 2024-05-24 21:59:06,380 DEBUG Checking pod: overseer-0-dfd5985cb-fzzf7 for file exists, if not wait. 2024-05-24 21:59:06,380 INFO 2024-05-24 21:59:06,380 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-24 21:59:06,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:06,705 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:06,705 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:06,705 DEBUG --- stdout --- 2024-05-24 21:59:06,705 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:06,705 DEBUG --- stderr --- 2024-05-24 21:59:06,705 DEBUG 2024-05-24 21:59:06,706 INFO 2024-05-24 21:59:06,706 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-100/cmd.txt" ]; then echo "/results/orders/login-tree-100/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-100/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-100/cmd.txt file found" 2024-05-24 21:59:06,706 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-05-24 21:59:07,506 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-05-24 21:59:07,506 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:07,506 DEBUG --- stdout --- 2024-05-24 21:59:07,506 DEBUG /results/orders/login-tree-100/cmd.txt file found 2024-05-24 21:59:07,506 DEBUG --- stderr --- 2024-05-24 21:59:07,506 DEBUG 2024-05-24 21:59:07,507 INFO 2024-05-24 21:59:07,507 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-24 21:59:07,507 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:07,821 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:07,821 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:07,821 DEBUG --- stdout --- 2024-05-24 21:59:07,821 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:07,821 DEBUG --- stderr --- 2024-05-24 21:59:07,821 DEBUG 2024-05-24 21:59:07,821 INFO 2024-05-24 21:59:07,822 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-100/cmd.txt 2024-05-24 21:59:08,690 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-05-24 21:59:08,690 DEBUG --- stdout --- 2024-05-24 21:59:08,690 DEBUG 4 /results/orders/login-tree-100/cmd.txt 2024-05-24 21:59:08,690 DEBUG --- stderr --- 2024-05-24 21:59:08,690 DEBUG 2024-05-24 21:59:08,690 INFO - Download file (size 4k) 2024-05-24 21:59:08,691 INFO 2024-05-24 21:59:08,691 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-24 21:59:08,691 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:08,995 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:08,996 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:08,996 DEBUG --- stdout --- 2024-05-24 21:59:08,996 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:08,996 DEBUG --- stderr --- 2024-05-24 21:59:08,996 DEBUG source : /results/orders/login-tree-100/cmd.txt @ overseer-0-dfd5985cb-fzzf7 destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-05-24 21:59:08,996 INFO 2024-05-24 21:59:08,996 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-24 21:59:08,996 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-05-24 21:59:09,297 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-05-24 21:59:09,297 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:09,297 DEBUG --- stdout --- 2024-05-24 21:59:09,297 DEBUG overseer-0-dfd5985cb-fzzf7 2024-05-24 21:59:09,297 DEBUG --- stderr --- 2024-05-24 21:59:09,297 DEBUG 2024-05-24 21:59:09,298 INFO 2024-05-24 21:59:09,298 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-100/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt 2024-05-24 21:59:09,298 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-05-24 21:59:10,022 INFO [loop_until]: OK (rc = 0) 2024-05-24 21:59:10,022 DEBUG --- stdout --- 2024-05-24 21:59:10,022 DEBUG tar: Removing leading `/' from member names 2024-05-24 21:59:10,022 DEBUG --- stderr --- 2024-05-24 21:59:10,022 DEBUG 2024-05-24 21:59:10,022 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/cmd.txt is small (1 lines), printing all lines: 2024-05-24 21:59:10,022 DEBUG ----- output ----- 2024-05-24 21:59:10,022 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-100 --extra-run-jvm-options "-Xmx12G" --no-reports 2024-05-24 21:59:10,023 INFO ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-100 show_rc : Display rc order ________________________________________________________________________________ 2024-05-24 21:59:10,023 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/rc.txt is small (1 lines), printing all lines: 2024-05-24 21:59:10,023 DEBUG ----- output ----- 2024-05-24 21:59:10,023 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-05-24 21:59:10,023 INFO ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-100 show_stdout : Display stdout order ________________________________________________________________________________ 2024-05-24 21:59:10,025 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stdout.txt is 10457 lines): 2024-05-24 21:59:10,025 DEBUG ----- output ----- 2024-05-24 21:59:10,025 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-05-24 21:59:10,025 INFO 21:56:32.838 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-05-24 21:59:10,025 INFO 21:56:36.686 [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-24 21:59:10,025 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-24 21:59:10,025 INFO ^ 2024-05-24 21:59:10,025 INFO 21:56:36.688 [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-24 21:59:10,025 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-05-24 21:59:10,025 INFO ^ 2024-05-24 21:59:10,025 INFO 21:56:38.223 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-05-24 21:59:10,025 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-05-24 21:59:10,025 INFO 2024-05-24 21:59:10,025 INFO ================================================================================ 2024-05-24 21:59:10,025 INFO 2024-05-24 21:56:46 5s elapsed 2024-05-24 21:59:10,025 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,025 INFO > Global (OK=83 KO=0 ) 2024-05-24 21:59:10,025 INFO > restLoginInitiate (OK=42 KO=0 ) 2024-05-24 21:59:10,025 INFO > restLoginUsernamePasswordCallback (OK=41 KO=0 ) 2024-05-24 21:59:10,025 INFO 2024-05-24 21:59:10,026 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,026 INFO [- ] 0% 2024-05-24 21:59:10,026 INFO waiting: 361036 / active: 1 / done: 41 2024-05-24 21:59:10,026 INFO ================================================================================ 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO ================================================================================ 2024-05-24 21:59:10,026 INFO 2024-05-24 21:56:51 10s elapsed 2024-05-24 21:59:10,026 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,026 INFO > Global (OK=333 KO=0 ) 2024-05-24 21:59:10,026 INFO > restLoginInitiate (OK=167 KO=0 ) 2024-05-24 21:59:10,026 INFO > restLoginUsernamePasswordCallback (OK=166 KO=0 ) 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,026 INFO [- ] 0% 2024-05-24 21:59:10,026 INFO waiting: 360911 / active: 1 / done: 166 2024-05-24 21:59:10,026 INFO ================================================================================ 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO ================================================================================ 2024-05-24 21:59:10,026 INFO 2024-05-24 21:56:56 15s elapsed 2024-05-24 21:59:10,026 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,026 INFO > Global (OK=750 KO=0 ) 2024-05-24 21:59:10,026 INFO > restLoginInitiate (OK=376 KO=0 ) 2024-05-24 21:59:10,026 INFO > restLoginUsernamePasswordCallback (OK=374 KO=0 ) 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,026 INFO [- ] 0% 2024-05-24 21:59:10,026 INFO waiting: 360702 / active: 2 / done: 374 2024-05-24 21:59:10,026 INFO ================================================================================ 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO 2024-05-24 21:59:10,026 INFO ================================================================================ 2024-05-24 21:59:10,026 INFO 2024-05-24 21:57:01 20s elapsed 2024-05-24 21:59:10,026 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,026 INFO > Global (OK=1332 KO=0 ) 2024-05-24 21:59:10,026 INFO > restLoginInitiate (OK=667 KO=0 ) 2024-05-24 21:59:10,026 INFO > restLoginUsernamePasswordCallback (OK=665 KO=0 ) 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,027 INFO [- ] 0% 2024-05-24 21:59:10,027 INFO waiting: 360410 / active: 3 / done: 665 2024-05-24 21:59:10,027 INFO ================================================================================ 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ================================================================================ 2024-05-24 21:59:10,027 INFO 2024-05-24 21:57:06 25s elapsed 2024-05-24 21:59:10,027 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,027 INFO > Global (OK=2082 KO=0 ) 2024-05-24 21:59:10,027 INFO > restLoginInitiate (OK=1042 KO=0 ) 2024-05-24 21:59:10,027 INFO > restLoginUsernamePasswordCallback (OK=1040 KO=0 ) 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,027 INFO [- ] 0% 2024-05-24 21:59:10,027 INFO waiting: 360035 / active: 3 / done: 1040 2024-05-24 21:59:10,027 INFO ================================================================================ 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ================================================================================ 2024-05-24 21:59:10,027 INFO 2024-05-24 21:57:11 30s elapsed 2024-05-24 21:59:10,027 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,027 INFO > Global (OK=2998 KO=0 ) 2024-05-24 21:59:10,027 INFO > restLoginInitiate (OK=1499 KO=0 ) 2024-05-24 21:59:10,027 INFO > restLoginUsernamePasswordCallback (OK=1499 KO=0 ) 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,027 INFO [- ] 0% 2024-05-24 21:59:10,027 INFO waiting: 359578 / active: 1 / done: 1499 2024-05-24 21:59:10,027 INFO ================================================================================ 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ================================================================================ 2024-05-24 21:59:10,027 INFO 2024-05-24 21:57:16 35s elapsed 2024-05-24 21:59:10,027 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,027 INFO > Global (OK=3979 KO=0 ) 2024-05-24 21:59:10,027 INFO > restLoginInitiate (OK=1991 KO=0 ) 2024-05-24 21:59:10,027 INFO > restLoginUsernamePasswordCallback (OK=1988 KO=0 ) 2024-05-24 21:59:10,027 INFO 2024-05-24 21:59:10,027 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,027 INFO [- ] 0% 2024-05-24 21:59:10,028 INFO waiting: 359086 / active: 4 / done: 1988 2024-05-24 21:59:10,028 INFO ================================================================================ 2024-05-24 21:59:10,028 INFO [...] 2024-05-24 21:59:10,030 INFO 2024-05-24 21:59:10,030 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,030 INFO [#########################################################################-] 99% 2024-05-24 21:59:10,030 INFO waiting: 2025 / active: 6 / done: 359047 2024-05-24 21:59:10,030 INFO ================================================================================ 2024-05-24 21:59:10,030 INFO 2024-05-24 21:59:10,030 INFO 2024-05-24 21:59:10,031 INFO ================================================================================ 2024-05-24 21:59:10,031 INFO 2024-05-24 22:56:56 3615s elapsed 2024-05-24 21:59:10,031 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,031 INFO > Global (OK=719144 KO=5 ) 2024-05-24 21:59:10,031 INFO > restLoginInitiate (OK=359573 KO=4 ) 2024-05-24 21:59:10,031 INFO > restLoginUsernamePasswordCallback (OK=359571 KO=1 ) 2024-05-24 21:59:10,031 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 21:59:10,031 INFO > status.find.is(200), but actually found 502 5 (100.0%) 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,031 INFO [#########################################################################-] 99% 2024-05-24 21:59:10,031 INFO waiting: 1500 / active: 2 / done: 359576 2024-05-24 21:59:10,031 INFO ================================================================================ 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO ================================================================================ 2024-05-24 21:59:10,031 INFO 2024-05-24 22:57:01 3620s elapsed 2024-05-24 21:59:10,031 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,031 INFO > Global (OK=720108 KO=5 ) 2024-05-24 21:59:10,031 INFO > restLoginInitiate (OK=360056 KO=4 ) 2024-05-24 21:59:10,031 INFO > restLoginUsernamePasswordCallback (OK=360052 KO=1 ) 2024-05-24 21:59:10,031 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 21:59:10,031 INFO > status.find.is(200), but actually found 502 5 (100.0%) 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,031 INFO [#########################################################################-] 99% 2024-05-24 21:59:10,031 INFO waiting: 1017 / active: 4 / done: 360057 2024-05-24 21:59:10,031 INFO ================================================================================ 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO ================================================================================ 2024-05-24 21:59:10,031 INFO 2024-05-24 22:57:06 3625s elapsed 2024-05-24 21:59:10,031 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,031 INFO > Global (OK=721140 KO=5 ) 2024-05-24 21:59:10,031 INFO > restLoginInitiate (OK=360572 KO=4 ) 2024-05-24 21:59:10,031 INFO > restLoginUsernamePasswordCallback (OK=360568 KO=1 ) 2024-05-24 21:59:10,031 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 21:59:10,031 INFO > status.find.is(200), but actually found 502 5 (100.0%) 2024-05-24 21:59:10,031 INFO 2024-05-24 21:59:10,031 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,032 INFO [#########################################################################-] 99% 2024-05-24 21:59:10,032 INFO waiting: 502 / active: 3 / done: 360573 2024-05-24 21:59:10,032 INFO ================================================================================ 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO ================================================================================ 2024-05-24 21:59:10,032 INFO 2024-05-24 22:57:11 3630s elapsed 2024-05-24 21:59:10,032 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,032 INFO > Global (OK=722144 KO=5 ) 2024-05-24 21:59:10,032 INFO > restLoginInitiate (OK=361074 KO=4 ) 2024-05-24 21:59:10,032 INFO > restLoginUsernamePasswordCallback (OK=361070 KO=1 ) 2024-05-24 21:59:10,032 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 21:59:10,032 INFO > status.find.is(200), but actually found 502 5 (100.0%) 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,032 INFO [#########################################################################-] 99% 2024-05-24 21:59:10,032 INFO waiting: 0 / active: 3 / done: 361075 2024-05-24 21:59:10,032 INFO ================================================================================ 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO ================================================================================ 2024-05-24 21:59:10,032 INFO 2024-05-24 22:57:11 3630s elapsed 2024-05-24 21:59:10,032 INFO ---- Requests ------------------------------------------------------------------ 2024-05-24 21:59:10,032 INFO > Global (OK=722147 KO=5 ) 2024-05-24 21:59:10,032 INFO > restLoginInitiate (OK=361074 KO=4 ) 2024-05-24 21:59:10,032 INFO > restLoginUsernamePasswordCallback (OK=361073 KO=1 ) 2024-05-24 21:59:10,032 INFO ---- Errors -------------------------------------------------------------------- 2024-05-24 21:59:10,032 INFO > status.find.is(200), but actually found 502 5 (100.0%) 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO ---- idc.login ----------------------------------------------------------------- 2024-05-24 21:59:10,032 INFO [##########################################################################]100% 2024-05-24 21:59:10,032 INFO waiting: 0 / active: 0 / done: 361078 2024-05-24 21:59:10,032 INFO ================================================================================ 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-05-24 21:59:10,032 INFO 2024-05-24 21:59:10,032 INFO ---------------------- 2024-05-24 21:59:10,032 INFO [24/May/2024 22:57:11] Create tar file login-tree-100.tar.gz 2024-05-24 21:59:10,032 INFO ---------------------- 2024-05-24 21:59:10,032 INFO RESULTS_FOLDER=/results/gatling/ 2024-05-24 21:59:10,033 INFO RESULT_FOLDER=login-tree-100 2024-05-24 21:59:10,033 INFO SIMULATION_PATH=login-tree-100/loadtest-20240524205640394 2024-05-24 21:59:10,033 INFO PWD=/results/gatling/login-tree-100/loadtest-20240524205640394 2024-05-24 21:59:10,033 INFO [cmd] tar -czvf /results/gatling//login-tree-100.tar.gz --exclude=simulation.log * 2024-05-24 21:59:10,033 INFO /results/gatling 2024-05-24 21:59:10,033 INFO 2024-05-24 21:59:10,033 INFO ---------------------- 2024-05-24 21:59:10,033 INFO [24/May/2024 22:57:11] Update /results/gatling//latest 2024-05-24 21:59:10,033 INFO ---------------------- 2024-05-24 21:59:10,033 INFO 2024-05-24 21:59:10,033 INFO ---------------------- 2024-05-24 21:59:10,033 INFO [24/May/2024 22:57:11] Update /results/gatling//latest.tar.gz 2024-05-24 21:59:10,033 INFO ---------------------- 2024-05-24 21:59:10,033 INFO ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-100 show_stderr : Display stderr order ________________________________________________________________________________ 2024-05-24 21:59:10,033 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/02_login-tree-100/stderr.txt is small (0 lines), printing all lines: 2024-05-24 21:59:10,033 DEBUG ----- output ----- 2024-05-24 21:59:10,033 DEBUG 2024-05-24 21:59:10,033 INFO ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-100 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-100 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-05-24 21:59:10] login-tree-100 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped