--Task-- name: login-tree-150 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-100'] preceding_task: login-tree-100 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 150, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-11 23:38:29] login-tree-150 pre : N/A ________________________________________________________________________________ 2024-06-11 23:38:29 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-11 23:38:29 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-150/stdout.txt Pod log : /results/orders/login-tree-150 2024-06-11 23:38:29,399 INFO 2024-06-11 23:38:29,399 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 23:38:29,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:29,729 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:29,729 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:29,730 DEBUG --- stdout --- 2024-06-11 23:38:29,730 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:29,730 DEBUG --- stderr --- 2024-06-11 23:38:29,730 DEBUG ________________________________________________________________________________ [2024-06-11 23:38:29] login-tree-150 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-11 23:38:29,730 INFO 2024-06-11 23:38:29,730 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 23:38:29,730 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:30,059 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:30,060 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:30,060 DEBUG --- stdout --- 2024-06-11 23:38:30,060 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:30,060 DEBUG --- stderr --- 2024-06-11 23:38:30,060 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1protect" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-11 23:38:30] login-tree-150 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-11 23:38:30,061 INFO 2024-06-11 23:38:30,061 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 23:38:30,061 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:30,375 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:30,375 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:30,375 DEBUG --- stdout --- 2024-06-11 23:38:30,375 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:30,375 DEBUG --- stderr --- 2024-06-11 23:38:30,375 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/order.json { "name": "login-tree-150", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-150", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -Dlogin_tree=Login -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=150 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-11 23:38:30] login-tree-150 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-11 23:38:30,377 INFO 2024-06-11 23:38:30,377 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-11 23:38:30,377 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 23:38:30,613 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-11 23:38:30,613 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:30,613 DEBUG --- stdout --- 2024-06-11 23:38:30,613 DEBUG WAITING 2024-06-11 23:38:30,613 DEBUG --- stderr --- 2024-06-11 23:38:30,614 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-06-11 23:38:30] login-tree-150 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-11 23:38:30,614 INFO 2024-06-11 23:38:30,614 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 23:38:30,614 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:30,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:30,927 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:30,927 DEBUG --- stdout --- 2024-06-11 23:38:30,927 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:30,927 DEBUG --- stderr --- 2024-06-11 23:38:30,928 DEBUG 2024-06-11 23:38:30,928 INFO 2024-06-11 23:38:30,928 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-11 23:38:30,928 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-11 23:38:32,288 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:32,289 DEBUG --- stdout --- 2024-06-11 23:38:32,290 DEBUG 2024-06-11 23:38:32,291 DEBUG --- stderr --- 2024-06-11 23:38:32,291 DEBUG Order has been successfully forwarded to overseer 2024-06-11 23:38:32 - INFO: Check if Gatling simulation started successfully 2024-06-11 23:38:32,291 INFO 2024-06-11 23:38:32,291 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-11 23:38:32,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-11 23:38:32,593 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-11 23:38:32,593 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:32,593 DEBUG --- stdout --- 2024-06-11 23:38:32,593 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-11 23:38:32,593 DEBUG --- stderr --- 2024-06-11 23:38:32,593 DEBUG 2024-06-11 23:38:32,594 INFO 2024-06-11 23:38:32,594 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-150/stdout.txt | grep "started..." 2024-06-11 23:38:32,594 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-11 23:38:33,372 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-11 23:38:39,119 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-11 23:38:39,119 INFO [loop_until]: OK (rc = 0) 2024-06-11 23:38:39,119 DEBUG --- stdout --- 2024-06-11 23:38:39,119 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-11 23:38:39,119 DEBUG --- stderr --- 2024-06-11 23:38:39,119 DEBUG ________________________________________________________________________________ [2024-06-11 23:38:39] login-tree-150 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m 01s, retry Order is running since 8m 01s, retry Order is running since 10m 01s, retry Order is running since 12m 02s, retry Order is running since 14m 02s, retry Order is running since 16m 02s, retry Order is running since 18m 03s, retry Order is running since 20m 03s, retry Order is running since 22m 03s, retry Order is running since 24m 04s, retry Order is running since 26m 04s, retry Order is running since 28m 04s, retry Order is running since 30m 05s, retry Order is running since 32m 05s, retry Order is running since 34m 05s, retry Order is running since 36m 06s, retry Order is running since 38m 06s, retry Order is running since 40m 06s, retry Order is running since 42m 07s, retry Order is running since 44m 07s, retry Order is running since 46m 07s, retry Order is running since 48m 08s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 09s, retry Order is running since 56m 09s, retry Order is running since 58m 09s, retry Order is running since 1h, retry PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m) ________________________________________________________________________________ [2024-06-12 00:40:49] login-tree-150 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-12 00:40:49,410 INFO 2024-06-12 00:40:49,410 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:49,410 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:49,735 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:49,735 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:49,736 DEBUG --- stdout --- 2024-06-12 00:40:49,736 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:49,736 DEBUG --- stderr --- 2024-06-12 00:40:49,736 DEBUG 2024-06-12 00:40:49,736 INFO 2024-06-12 00:40:49,736 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-12 00:40:49,736 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 00:40:50,509 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:50,510 DEBUG --- stdout --- 2024-06-12 00:40:50,510 DEBUG 2024-06-12 00:40:50,510 DEBUG --- stderr --- 2024-06-12 00:40:50,510 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-12 00:40:50] login-tree-150 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-12 00:40:50 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/order.json 2024-06-12 00:40:50,511 INFO 2024-06-12 00:40:50,511 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:50,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:50,809 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:50,809 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:50,809 DEBUG --- stdout --- 2024-06-12 00:40:50,809 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:50,809 DEBUG --- stderr --- 2024-06-12 00:40:50,809 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 00:40:50,809 INFO 2024-06-12 00:40:50,809 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:50,809 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:51,108 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:51,108 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:51,108 DEBUG --- stdout --- 2024-06-12 00:40:51,108 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:51,108 DEBUG --- stderr --- 2024-06-12 00:40:51,108 DEBUG 2024-06-12 00:40:51,109 INFO 2024-06-12 00:40:51,109 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/rc.txt" ]; then echo "/results/orders/login-tree-150/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-150/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/rc.txt file found" 2024-06-12 00:40:51,109 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 00:40:51,907 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 00:40:51,908 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:51,908 DEBUG --- stdout --- 2024-06-12 00:40:51,908 DEBUG /results/orders/login-tree-150/rc.txt file found 2024-06-12 00:40:51,908 DEBUG --- stderr --- 2024-06-12 00:40:51,908 DEBUG 2024-06-12 00:40:51,908 INFO 2024-06-12 00:40:51,908 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:51,908 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:52,204 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:52,205 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:52,205 DEBUG --- stdout --- 2024-06-12 00:40:52,205 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:52,205 DEBUG --- stderr --- 2024-06-12 00:40:52,205 DEBUG 2024-06-12 00:40:52,205 INFO 2024-06-12 00:40:52,205 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/rc.txt 2024-06-12 00:40:53,061 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 00:40:53,061 DEBUG --- stdout --- 2024-06-12 00:40:53,061 DEBUG 4 /results/orders/login-tree-150/rc.txt 2024-06-12 00:40:53,061 DEBUG --- stderr --- 2024-06-12 00:40:53,061 DEBUG 2024-06-12 00:40:53,061 INFO - Download file (size 4k) 2024-06-12 00:40:53,062 INFO 2024-06-12 00:40:53,062 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:53,062 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:53,380 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:53,380 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:53,382 DEBUG --- stdout --- 2024-06-12 00:40:53,382 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:53,382 DEBUG --- stderr --- 2024-06-12 00:40:53,382 DEBUG source : /results/orders/login-tree-150/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/rc.txt 2024-06-12 00:40:53,382 INFO 2024-06-12 00:40:53,382 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:53,383 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:53,697 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:53,697 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:53,697 DEBUG --- stdout --- 2024-06-12 00:40:53,697 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:53,697 DEBUG --- stderr --- 2024-06-12 00:40:53,697 DEBUG 2024-06-12 00:40:53,697 INFO 2024-06-12 00:40:53,698 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/rc.txt 2024-06-12 00:40:53,698 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 00:40:54,439 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:54,439 DEBUG --- stdout --- 2024-06-12 00:40:54,439 DEBUG tar: Removing leading `/' from member names 2024-06-12 00:40:54,439 DEBUG --- stderr --- 2024-06-12 00:40:54,439 DEBUG 2024-06-12 00:40:54,440 INFO 2024-06-12 00:40:54,440 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:54,440 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:54,755 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:54,756 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:54,756 DEBUG --- stdout --- 2024-06-12 00:40:54,756 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:54,756 DEBUG --- stderr --- 2024-06-12 00:40:54,756 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 00:40:54,756 INFO 2024-06-12 00:40:54,756 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:54,756 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:55,063 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:55,063 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:55,063 DEBUG --- stdout --- 2024-06-12 00:40:55,064 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:55,064 DEBUG --- stderr --- 2024-06-12 00:40:55,064 DEBUG 2024-06-12 00:40:55,064 INFO 2024-06-12 00:40:55,064 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/stderr.txt" ]; then echo "/results/orders/login-tree-150/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-150/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/stderr.txt file found" 2024-06-12 00:40:55,064 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 00:40:55,861 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 00:40:55,861 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:55,861 DEBUG --- stdout --- 2024-06-12 00:40:55,861 DEBUG /results/orders/login-tree-150/stderr.txt file found 2024-06-12 00:40:55,861 DEBUG --- stderr --- 2024-06-12 00:40:55,861 DEBUG 2024-06-12 00:40:55,862 INFO 2024-06-12 00:40:55,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-06-12 00:40:55,862 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:56,171 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:56,171 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:56,171 DEBUG --- stdout --- 2024-06-12 00:40:56,171 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:56,171 DEBUG --- stderr --- 2024-06-12 00:40:56,171 DEBUG 2024-06-12 00:40:56,171 INFO 2024-06-12 00:40:56,172 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/stderr.txt 2024-06-12 00:40:56,976 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 00:40:56,977 DEBUG --- stdout --- 2024-06-12 00:40:56,977 DEBUG 0 /results/orders/login-tree-150/stderr.txt 2024-06-12 00:40:56,977 DEBUG --- stderr --- 2024-06-12 00:40:56,977 DEBUG 2024-06-12 00:40:56,977 INFO - Download file (size 0k) 2024-06-12 00:40:56,977 INFO 2024-06-12 00:40:56,977 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:56,977 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:57,283 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:57,283 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:57,283 DEBUG --- stdout --- 2024-06-12 00:40:57,283 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:57,283 DEBUG --- stderr --- 2024-06-12 00:40:57,284 DEBUG source : /results/orders/login-tree-150/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stderr.txt 2024-06-12 00:40:57,284 INFO 2024-06-12 00:40:57,284 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:57,284 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:57,598 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:57,598 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:57,598 DEBUG --- stdout --- 2024-06-12 00:40:57,598 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:57,598 DEBUG --- stderr --- 2024-06-12 00:40:57,598 DEBUG 2024-06-12 00:40:57,599 INFO 2024-06-12 00:40:57,599 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stderr.txt 2024-06-12 00:40:57,599 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 00:40:58,332 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:58,332 DEBUG --- stdout --- 2024-06-12 00:40:58,332 DEBUG tar: Removing leading `/' from member names 2024-06-12 00:40:58,332 DEBUG --- stderr --- 2024-06-12 00:40:58,332 DEBUG 2024-06-12 00:40:58,333 INFO 2024-06-12 00:40:58,333 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:58,333 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:58,634 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:58,634 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:58,634 DEBUG --- stdout --- 2024-06-12 00:40:58,634 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:58,634 DEBUG --- stderr --- 2024-06-12 00:40:58,634 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 00:40:58,634 INFO 2024-06-12 00:40:58,634 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:58,635 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:40:58,935 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:40:58,935 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:58,935 DEBUG --- stdout --- 2024-06-12 00:40:58,935 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:40:58,935 DEBUG --- stderr --- 2024-06-12 00:40:58,935 DEBUG 2024-06-12 00:40:58,936 INFO 2024-06-12 00:40:58,936 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/stdout.txt" ]; then echo "/results/orders/login-tree-150/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-150/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/stdout.txt file found" 2024-06-12 00:40:58,936 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 00:40:59,742 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 00:40:59,743 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:40:59,743 DEBUG --- stdout --- 2024-06-12 00:40:59,743 DEBUG /results/orders/login-tree-150/stdout.txt file found 2024-06-12 00:40:59,743 DEBUG --- stderr --- 2024-06-12 00:40:59,743 DEBUG 2024-06-12 00:40:59,743 INFO 2024-06-12 00:40:59,743 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:40:59,743 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:00,050 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:00,051 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:00,051 DEBUG --- stdout --- 2024-06-12 00:41:00,051 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:00,051 DEBUG --- stderr --- 2024-06-12 00:41:00,051 DEBUG 2024-06-12 00:41:00,051 INFO 2024-06-12 00:41:00,051 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/stdout.txt 2024-06-12 00:41:00,861 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 00:41:00,861 DEBUG --- stdout --- 2024-06-12 00:41:00,861 DEBUG 652 /results/orders/login-tree-150/stdout.txt 2024-06-12 00:41:00,861 DEBUG --- stderr --- 2024-06-12 00:41:00,861 DEBUG 2024-06-12 00:41:00,861 INFO - Download file (size 652k) 2024-06-12 00:41:00,862 INFO 2024-06-12 00:41:00,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-06-12 00:41:00,862 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:01,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:01,170 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:01,170 DEBUG --- stdout --- 2024-06-12 00:41:01,170 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:01,170 DEBUG --- stderr --- 2024-06-12 00:41:01,170 DEBUG source : /results/orders/login-tree-150/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stdout.txt 2024-06-12 00:41:01,170 INFO 2024-06-12 00:41:01,170 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:01,170 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:01,483 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:01,483 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:01,483 DEBUG --- stdout --- 2024-06-12 00:41:01,483 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:01,483 DEBUG --- stderr --- 2024-06-12 00:41:01,483 DEBUG 2024-06-12 00:41:01,484 INFO 2024-06-12 00:41:01,484 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stdout.txt 2024-06-12 00:41:01,484 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 00:41:02,549 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:02,550 DEBUG --- stdout --- 2024-06-12 00:41:02,550 DEBUG tar: Removing leading `/' from member names 2024-06-12 00:41:02,550 DEBUG --- stderr --- 2024-06-12 00:41:02,550 DEBUG ________________________________________________________________________________ [2024-06-12 00:41:02] login-tree-150 show_cmd : Display order command ________________________________________________________________________________ 2024-06-12 00:41:02,551 INFO 2024-06-12 00:41:02,551 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:02,551 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:02,860 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:02,861 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:02,861 DEBUG --- stdout --- 2024-06-12 00:41:02,861 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:02,861 DEBUG --- stderr --- 2024-06-12 00:41:02,861 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 00:41:02,861 INFO 2024-06-12 00:41:02,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-06-12 00:41:02,861 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:03,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:03,170 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:03,170 DEBUG --- stdout --- 2024-06-12 00:41:03,170 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:03,170 DEBUG --- stderr --- 2024-06-12 00:41:03,170 DEBUG 2024-06-12 00:41:03,170 INFO 2024-06-12 00:41:03,170 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-150/cmd.txt" ]; then echo "/results/orders/login-tree-150/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-150/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-150/cmd.txt file found" 2024-06-12 00:41:03,171 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 00:41:03,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 00:41:03,984 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:03,985 DEBUG --- stdout --- 2024-06-12 00:41:03,985 DEBUG /results/orders/login-tree-150/cmd.txt file found 2024-06-12 00:41:03,985 DEBUG --- stderr --- 2024-06-12 00:41:03,985 DEBUG 2024-06-12 00:41:03,986 INFO 2024-06-12 00:41:03,986 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:03,986 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:04,292 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:04,293 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:04,293 DEBUG --- stdout --- 2024-06-12 00:41:04,293 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:04,293 DEBUG --- stderr --- 2024-06-12 00:41:04,293 DEBUG 2024-06-12 00:41:04,293 INFO 2024-06-12 00:41:04,293 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-150/cmd.txt 2024-06-12 00:41:05,098 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 00:41:05,098 DEBUG --- stdout --- 2024-06-12 00:41:05,098 DEBUG 4 /results/orders/login-tree-150/cmd.txt 2024-06-12 00:41:05,098 DEBUG --- stderr --- 2024-06-12 00:41:05,098 DEBUG 2024-06-12 00:41:05,098 INFO - Download file (size 4k) 2024-06-12 00:41:05,098 INFO 2024-06-12 00:41:05,098 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:05,098 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:05,405 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:05,405 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:05,405 DEBUG --- stdout --- 2024-06-12 00:41:05,405 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:05,405 DEBUG --- stderr --- 2024-06-12 00:41:05,405 DEBUG source : /results/orders/login-tree-150/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/cmd.txt 2024-06-12 00:41:05,406 INFO 2024-06-12 00:41:05,406 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:05,406 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:05,711 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:05,711 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:05,711 DEBUG --- stdout --- 2024-06-12 00:41:05,711 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:05,711 DEBUG --- stderr --- 2024-06-12 00:41:05,711 DEBUG 2024-06-12 00:41:05,712 INFO 2024-06-12 00:41:05,712 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-150/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/cmd.txt 2024-06-12 00:41:05,712 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 00:41:06,444 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:06,444 DEBUG --- stdout --- 2024-06-12 00:41:06,444 DEBUG tar: Removing leading `/' from member names 2024-06-12 00:41:06,444 DEBUG --- stderr --- 2024-06-12 00:41:06,444 DEBUG 2024-06-12 00:41:06,445 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/cmd.txt is small (1 lines), printing all lines: 2024-06-12 00:41:06,445 DEBUG ----- output ----- 2024-06-12 00:41:06,445 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1protect" --results-folder /results/gatling/login-tree-150 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-12 00:41:06,445 INFO ________________________________________________________________________________ [2024-06-12 00:41:06] login-tree-150 show_rc : Display rc order ________________________________________________________________________________ 2024-06-12 00:41:06,445 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/rc.txt is small (1 lines), printing all lines: 2024-06-12 00:41:06,445 DEBUG ----- output ----- 2024-06-12 00:41:06,445 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-12 00:41:06,445 INFO ________________________________________________________________________________ [2024-06-12 00:41:06] login-tree-150 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-12 00:41:06,447 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stdout.txt is 10537 lines): 2024-06-12 00:41:06,447 DEBUG ----- output ----- 2024-06-12 00:41:06,447 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-12 00:41:06,447 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-12 00:41:06,447 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-12 00:41:06,447 INFO 2024-06-12 00:41:06,447 INFO ================================================================================ 2024-06-12 00:41:06,447 INFO 2024-06-12 00:38:41 5s elapsed 2024-06-12 00:41:06,447 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,447 INFO > Global (OK=118 KO=0 ) 2024-06-12 00:41:06,447 INFO > restLoginInitiate (OK=60 KO=0 ) 2024-06-12 00:41:06,447 INFO > restLoginUsernamePasswordCallback (OK=57 KO=0 ) 2024-06-12 00:41:06,447 INFO > restloginProgressiveProfileCallback (OK=1 KO=0 ) 2024-06-12 00:41:06,447 INFO 2024-06-12 00:41:06,447 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,447 INFO [- ] 0% 2024-06-12 00:41:06,448 INFO waiting: 542215 / active: 7 / done: 56 2024-06-12 00:41:06,448 INFO ================================================================================ 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO ================================================================================ 2024-06-12 00:41:06,448 INFO 2024-06-12 00:38:46 10s elapsed 2024-06-12 00:41:06,448 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,448 INFO > Global (OK=500 KO=0 ) 2024-06-12 00:41:06,448 INFO > restLoginInitiate (OK=246 KO=0 ) 2024-06-12 00:41:06,448 INFO > restLoginUsernamePasswordCallback (OK=239 KO=0 ) 2024-06-12 00:41:06,448 INFO > restloginProgressiveProfileCallback (OK=15 KO=0 ) 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,448 INFO [- ] 0% 2024-06-12 00:41:06,448 INFO waiting: 542027 / active: 12 / done: 239 2024-06-12 00:41:06,448 INFO ================================================================================ 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO ================================================================================ 2024-06-12 00:41:06,448 INFO 2024-06-12 00:38:51 15s elapsed 2024-06-12 00:41:06,448 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,448 INFO > Global (OK=1135 KO=0 ) 2024-06-12 00:41:06,448 INFO > restLoginInitiate (OK=556 KO=0 ) 2024-06-12 00:41:06,448 INFO > restLoginUsernamePasswordCallback (OK=542 KO=0 ) 2024-06-12 00:41:06,448 INFO > restloginProgressiveProfileCallback (OK=37 KO=0 ) 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,448 INFO [- ] 0% 2024-06-12 00:41:06,448 INFO waiting: 541715 / active: 22 / done: 541 2024-06-12 00:41:06,448 INFO ================================================================================ 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,448 INFO ================================================================================ 2024-06-12 00:41:06,448 INFO 2024-06-12 00:38:56 20s elapsed 2024-06-12 00:41:06,448 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,448 INFO > Global (OK=2042 KO=0 ) 2024-06-12 00:41:06,448 INFO > restLoginInitiate (OK=991 KO=0 ) 2024-06-12 00:41:06,448 INFO > restLoginUsernamePasswordCallback (OK=975 KO=0 ) 2024-06-12 00:41:06,448 INFO > restloginProgressiveProfileCallback (OK=76 KO=0 ) 2024-06-12 00:41:06,448 INFO 2024-06-12 00:41:06,449 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,449 INFO [- ] 0% 2024-06-12 00:41:06,449 INFO waiting: 541276 / active: 32 / done: 970 2024-06-12 00:41:06,449 INFO ================================================================================ 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO ================================================================================ 2024-06-12 00:41:06,449 INFO 2024-06-12 00:39:01 25s elapsed 2024-06-12 00:41:06,449 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,449 INFO > Global (OK=3213 KO=0 ) 2024-06-12 00:41:06,449 INFO > restLoginInitiate (OK=1551 KO=0 ) 2024-06-12 00:41:06,449 INFO > restLoginUsernamePasswordCallback (OK=1530 KO=0 ) 2024-06-12 00:41:06,449 INFO > restloginProgressiveProfileCallback (OK=132 KO=0 ) 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,449 INFO [- ] 0% 2024-06-12 00:41:06,449 INFO waiting: 540714 / active: 34 / done: 1530 2024-06-12 00:41:06,449 INFO ================================================================================ 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO ================================================================================ 2024-06-12 00:41:06,449 INFO 2024-06-12 00:39:06 30s elapsed 2024-06-12 00:41:06,449 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,449 INFO > Global (OK=4643 KO=0 ) 2024-06-12 00:41:06,449 INFO > restLoginInitiate (OK=2236 KO=0 ) 2024-06-12 00:41:06,449 INFO > restLoginUsernamePasswordCallback (OK=2212 KO=0 ) 2024-06-12 00:41:06,449 INFO > restloginProgressiveProfileCallback (OK=195 KO=0 ) 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,449 INFO [- ] 0% 2024-06-12 00:41:06,449 INFO waiting: 540027 / active: 40 / done: 2211 2024-06-12 00:41:06,449 INFO ================================================================================ 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO 2024-06-12 00:41:06,449 INFO ================================================================================ 2024-06-12 00:41:06,449 INFO 2024-06-12 00:39:11 35s elapsed 2024-06-12 00:41:06,449 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,449 INFO > Global (OK=6223 KO=0 ) 2024-06-12 00:41:06,449 INFO > restLoginInitiate (OK=2998 KO=0 ) 2024-06-12 00:41:06,449 INFO > restLoginUsernamePasswordCallback (OK=2974 KO=0 ) 2024-06-12 00:41:06,449 INFO > restloginProgressiveProfileCallback (OK=251 KO=0 ) 2024-06-12 00:41:06,450 INFO 2024-06-12 00:41:06,450 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,450 INFO [- ] 0% 2024-06-12 00:41:06,450 INFO waiting: 539257 / active: 52 / done: 2969 2024-06-12 00:41:06,450 INFO ================================================================================ 2024-06-12 00:41:06,450 INFO [...] 2024-06-12 00:41:06,452 INFO 2024-06-12 00:41:06,452 INFO 2024-06-12 00:41:06,452 INFO ================================================================================ 2024-06-12 00:41:06,452 INFO 2024-06-12 01:38:51 3615s elapsed 2024-06-12 00:41:06,452 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,452 INFO > Global (OK=1162971 KO=1 ) 2024-06-12 00:41:06,452 INFO > restLoginInitiate (OK=540010 KO=0 ) 2024-06-12 00:41:06,452 INFO > restLoginUsernamePasswordCallback (OK=539981 KO=1 ) 2024-06-12 00:41:06,452 INFO > restloginProgressiveProfileCallback (OK=82980 KO=0 ) 2024-06-12 00:41:06,452 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 00:41:06,452 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,453 INFO [#########################################################################-] 99% 2024-06-12 00:41:06,453 INFO waiting: 2246 / active: 56 / done: 539976 2024-06-12 00:41:06,453 INFO ================================================================================ 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO ================================================================================ 2024-06-12 00:41:06,453 INFO 2024-06-12 01:38:56 3620s elapsed 2024-06-12 00:41:06,453 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,453 INFO > Global (OK=1164575 KO=1 ) 2024-06-12 00:41:06,453 INFO > restLoginInitiate (OK=540733 KO=0 ) 2024-06-12 00:41:06,453 INFO > restLoginUsernamePasswordCallback (OK=540715 KO=1 ) 2024-06-12 00:41:06,453 INFO > restloginProgressiveProfileCallback (OK=83127 KO=0 ) 2024-06-12 00:41:06,453 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 00:41:06,453 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,453 INFO [#########################################################################-] 99% 2024-06-12 00:41:06,453 INFO waiting: 1532 / active: 35 / done: 540711 2024-06-12 00:41:06,453 INFO ================================================================================ 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO ================================================================================ 2024-06-12 00:41:06,453 INFO 2024-06-12 01:39:01 3625s elapsed 2024-06-12 00:41:06,453 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,453 INFO > Global (OK=1166288 KO=1 ) 2024-06-12 00:41:06,453 INFO > restLoginInitiate (OK=541524 KO=0 ) 2024-06-12 00:41:06,453 INFO > restLoginUsernamePasswordCallback (OK=541489 KO=1 ) 2024-06-12 00:41:06,453 INFO > restloginProgressiveProfileCallback (OK=83275 KO=0 ) 2024-06-12 00:41:06,453 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 00:41:06,453 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,453 INFO [#########################################################################-] 99% 2024-06-12 00:41:06,453 INFO waiting: 737 / active: 56 / done: 541485 2024-06-12 00:41:06,453 INFO ================================================================================ 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,453 INFO 2024-06-12 00:41:06,454 INFO ================================================================================ 2024-06-12 00:41:06,454 INFO 2024-06-12 01:39:06 3630s elapsed 2024-06-12 00:41:06,454 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,454 INFO > Global (OK=1167952 KO=1 ) 2024-06-12 00:41:06,454 INFO > restLoginInitiate (OK=542264 KO=0 ) 2024-06-12 00:41:06,454 INFO > restLoginUsernamePasswordCallback (OK=542240 KO=1 ) 2024-06-12 00:41:06,454 INFO > restloginProgressiveProfileCallback (OK=83448 KO=0 ) 2024-06-12 00:41:06,454 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 00:41:06,454 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-12 00:41:06,454 INFO 2024-06-12 00:41:06,454 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,454 INFO [#########################################################################-] 99% 2024-06-12 00:41:06,454 INFO waiting: 0 / active: 41 / done: 542237 2024-06-12 00:41:06,454 INFO ================================================================================ 2024-06-12 00:41:06,454 INFO 2024-06-12 00:41:06,454 INFO 2024-06-12 00:41:06,454 INFO ================================================================================ 2024-06-12 00:41:06,454 INFO 2024-06-12 01:39:06 3630s elapsed 2024-06-12 00:41:06,454 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 00:41:06,454 INFO > Global (OK=1168014 KO=1 ) 2024-06-12 00:41:06,454 INFO > restLoginInitiate (OK=542278 KO=0 ) 2024-06-12 00:41:06,454 INFO > restLoginUsernamePasswordCallback (OK=542277 KO=1 ) 2024-06-12 00:41:06,454 INFO > restloginProgressiveProfileCallback (OK=83459 KO=0 ) 2024-06-12 00:41:06,454 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 00:41:06,454 INFO > status.find.is(200), but actually found 502 1 (100.0%) 2024-06-12 00:41:06,454 INFO 2024-06-12 00:41:06,454 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 00:41:06,454 INFO [##########################################################################]100% 2024-06-12 00:41:06,454 INFO waiting: 0 / active: 0 / done: 542278 2024-06-12 00:41:06,454 INFO ================================================================================ 2024-06-12 00:41:06,454 INFO 2024-06-12 00:41:06,454 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-12 00:41:06,454 INFO 2024-06-12 00:41:06,454 INFO ---------------------- 2024-06-12 00:41:06,454 INFO [12/Jun/2024 01:39:06] Create tar file login-tree-150.tar.gz 2024-06-12 00:41:06,454 INFO ---------------------- 2024-06-12 00:41:06,454 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-12 00:41:06,454 INFO RESULT_FOLDER=login-tree-150 2024-06-12 00:41:06,455 INFO SIMULATION_PATH=login-tree-150/loadtest-20240611233835880 2024-06-12 00:41:06,455 INFO PWD=/results/gatling/login-tree-150/loadtest-20240611233835880 2024-06-12 00:41:06,455 INFO [cmd] tar -czvf /results/gatling//login-tree-150.tar.gz --exclude=simulation.log * 2024-06-12 00:41:06,455 INFO /results/gatling 2024-06-12 00:41:06,455 INFO 2024-06-12 00:41:06,455 INFO ---------------------- 2024-06-12 00:41:06,455 INFO [12/Jun/2024 01:39:06] Update /results/gatling//latest 2024-06-12 00:41:06,455 INFO ---------------------- 2024-06-12 00:41:06,455 INFO 2024-06-12 00:41:06,455 INFO ---------------------- 2024-06-12 00:41:06,455 INFO [12/Jun/2024 01:39:06] Update /results/gatling//latest.tar.gz 2024-06-12 00:41:06,455 INFO ---------------------- 2024-06-12 00:41:06,455 INFO ________________________________________________________________________________ [2024-06-12 00:41:06] login-tree-150 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-12 00:41:06,455 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/04_login-tree-150/stderr.txt is small (0 lines), printing all lines: 2024-06-12 00:41:06,455 DEBUG ----- output ----- 2024-06-12 00:41:06,455 DEBUG 2024-06-12 00:41:06,455 INFO ________________________________________________________________________________ [2024-06-12 00:41:06] login-tree-150 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-12 00:41:06] login-tree-150 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-12 00:41:06] login-tree-150 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped