--Task-- name: login-tree-180 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-150'] preceding_task: login-tree-150 options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 180, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login_Protect'} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-06-13 04:00:31] login-tree-180 pre : N/A ________________________________________________________________________________ 2024-06-13 04:00:31 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-13 04:00:31 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-180/stdout.txt Pod log : /results/orders/login-tree-180 2024-06-13 04:00:31,940 INFO 2024-06-13 04:00:31,940 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-13 04:00:31,941 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:32,241 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:32,242 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:32,242 DEBUG --- stdout --- 2024-06-13 04:00:32,242 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:32,242 DEBUG --- stderr --- 2024-06-13 04:00:32,242 DEBUG ________________________________________________________________________________ [2024-06-13 04:00:32] login-tree-180 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-13 04:00:32,242 INFO 2024-06-13 04:00:32,242 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-13 04:00:32,243 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:32,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:32,544 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:32,544 DEBUG --- stdout --- 2024-06-13 04:00:32,544 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:32,544 DEBUG --- stderr --- 2024-06-13 04:00:32,544 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.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-180 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-13 04:00:32] login-tree-180 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-13 04:00:32,545 INFO 2024-06-13 04:00:32,545 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-13 04:00:32,545 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:32,845 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:32,846 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:32,846 DEBUG --- stdout --- 2024-06-13 04:00:32,846 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:32,846 DEBUG --- stderr --- 2024-06-13 04:00:32,846 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/order.json { "name": "login-tree-180", "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.loginProtect@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-180 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-180", "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_Protect -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.loginProtect -DtestMode=open -Dthroughput=180 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-13 04:00:32] login-tree-180 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-13 04:00:32,848 INFO 2024-06-13 04:00:32,848 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-13 04:00:32,848 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 04:00:33,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 04:00:33,080 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:33,080 DEBUG --- stdout --- 2024-06-13 04:00:33,080 DEBUG WAITING 2024-06-13 04:00:33,080 DEBUG --- stderr --- 2024-06-13 04:00:33,081 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 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-13 04:00:33] login-tree-180 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-13 04:00:33,081 INFO 2024-06-13 04:00:33,081 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-13 04:00:33,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:33,377 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:33,378 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:33,378 DEBUG --- stdout --- 2024-06-13 04:00:33,378 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:33,378 DEBUG --- stderr --- 2024-06-13 04:00:33,378 DEBUG 2024-06-13 04:00:33,378 INFO 2024-06-13 04:00:33,378 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/05_login-tree-180/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-13 04:00:33,378 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 04:00:34,727 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:34,727 DEBUG --- stdout --- 2024-06-13 04:00:34,727 DEBUG 2024-06-13 04:00:34,727 DEBUG --- stderr --- 2024-06-13 04:00:34,727 DEBUG Order has been successfully forwarded to overseer 2024-06-13 04:00:34 - INFO: Check if Gatling simulation started successfully 2024-06-13 04:00:34,728 INFO 2024-06-13 04:00:34,728 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 04:00:34,728 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 04:00:35,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 04:00:35,030 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:35,031 DEBUG --- stdout --- 2024-06-13 04:00:35,031 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 04:00:35,031 DEBUG --- stderr --- 2024-06-13 04:00:35,031 DEBUG 2024-06-13 04:00:35,032 INFO 2024-06-13 04:00:35,032 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-180/stdout.txt | grep "started..." 2024-06-13 04:00:35,032 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-13 04:00:35,763 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-13 04:00:41,515 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-13 04:00:41,515 INFO [loop_until]: OK (rc = 0) 2024-06-13 04:00:41,515 DEBUG --- stdout --- 2024-06-13 04:00:41,515 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-13 04:00:41,515 DEBUG --- stderr --- 2024-06-13 04:00:41,515 DEBUG ________________________________________________________________________________ [2024-06-13 04:00:41] login-tree-180 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 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 07s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 08s, retry Order is running since 52m 08s, retry Order is running since 54m 08s, retry Order is running since 56m 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-06-13 05:02:51] login-tree-180 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-13 05:02:51,501 INFO 2024-06-13 05:02:51,501 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-13 05:02:51,501 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:51,831 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:51,831 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:51,831 DEBUG --- stdout --- 2024-06-13 05:02:51,831 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:51,831 DEBUG --- stderr --- 2024-06-13 05:02:51,831 DEBUG 2024-06-13 05:02:51,832 INFO 2024-06-13 05:02:51,832 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-13 05:02:51,832 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 05:02:52,925 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:52,925 DEBUG --- stdout --- 2024-06-13 05:02:52,925 DEBUG 2024-06-13 05:02:52,925 DEBUG --- stderr --- 2024-06-13 05:02:52,925 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-13 05:02:52] login-tree-180 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-13 05:02:52 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/order.json 2024-06-13 05:02:52,926 INFO 2024-06-13 05:02:52,926 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-13 05:02:52,926 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:53,226 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:53,226 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:53,226 DEBUG --- stdout --- 2024-06-13 05:02:53,227 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:53,227 DEBUG --- stderr --- 2024-06-13 05:02:53,227 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 05:02:53,227 INFO 2024-06-13 05:02:53,227 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-13 05:02:53,227 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:53,520 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:53,520 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:53,520 DEBUG --- stdout --- 2024-06-13 05:02:53,520 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:53,520 DEBUG --- stderr --- 2024-06-13 05:02:53,520 DEBUG 2024-06-13 05:02:53,521 INFO 2024-06-13 05:02:53,521 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-180/rc.txt" ]; then echo "/results/orders/login-tree-180/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-180/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/rc.txt file found" 2024-06-13 05:02:53,521 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 05:02:54,322 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 05:02:54,323 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:54,323 DEBUG --- stdout --- 2024-06-13 05:02:54,323 DEBUG /results/orders/login-tree-180/rc.txt file found 2024-06-13 05:02:54,323 DEBUG --- stderr --- 2024-06-13 05:02:54,323 DEBUG 2024-06-13 05:02:54,323 INFO 2024-06-13 05:02:54,323 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-13 05:02:54,323 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:54,636 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:54,636 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:54,636 DEBUG --- stdout --- 2024-06-13 05:02:54,636 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:54,636 DEBUG --- stderr --- 2024-06-13 05:02:54,636 DEBUG 2024-06-13 05:02:54,636 INFO 2024-06-13 05:02:54,637 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-180/rc.txt 2024-06-13 05:02:55,447 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 05:02:55,447 DEBUG --- stdout --- 2024-06-13 05:02:55,448 DEBUG 4 /results/orders/login-tree-180/rc.txt 2024-06-13 05:02:55,448 DEBUG --- stderr --- 2024-06-13 05:02:55,448 DEBUG 2024-06-13 05:02:55,448 INFO - Download file (size 4k) 2024-06-13 05:02:55,448 INFO 2024-06-13 05:02:55,448 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-13 05:02:55,448 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:55,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:55,757 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:55,757 DEBUG --- stdout --- 2024-06-13 05:02:55,757 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:55,757 DEBUG --- stderr --- 2024-06-13 05:02:55,757 DEBUG source : /results/orders/login-tree-180/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/rc.txt 2024-06-13 05:02:55,758 INFO 2024-06-13 05:02:55,758 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-13 05:02:55,758 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:56,068 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:56,068 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:56,069 DEBUG --- stdout --- 2024-06-13 05:02:56,069 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:56,069 DEBUG --- stderr --- 2024-06-13 05:02:56,069 DEBUG 2024-06-13 05:02:56,070 INFO 2024-06-13 05:02:56,070 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-180/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/rc.txt 2024-06-13 05:02:56,070 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 05:02:56,809 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:56,809 DEBUG --- stdout --- 2024-06-13 05:02:56,809 DEBUG tar: Removing leading `/' from member names 2024-06-13 05:02:56,810 DEBUG --- stderr --- 2024-06-13 05:02:56,810 DEBUG 2024-06-13 05:02:56,810 INFO 2024-06-13 05:02:56,810 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-13 05:02:56,810 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:57,119 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:57,119 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:57,119 DEBUG --- stdout --- 2024-06-13 05:02:57,119 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:57,119 DEBUG --- stderr --- 2024-06-13 05:02:57,119 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 05:02:57,120 INFO 2024-06-13 05:02:57,120 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-13 05:02:57,120 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:57,443 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:57,443 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:57,443 DEBUG --- stdout --- 2024-06-13 05:02:57,443 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:57,443 DEBUG --- stderr --- 2024-06-13 05:02:57,443 DEBUG 2024-06-13 05:02:57,444 INFO 2024-06-13 05:02:57,444 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-180/stderr.txt" ]; then echo "/results/orders/login-tree-180/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-180/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/stderr.txt file found" 2024-06-13 05:02:57,444 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 05:02:58,244 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 05:02:58,244 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:58,244 DEBUG --- stdout --- 2024-06-13 05:02:58,244 DEBUG /results/orders/login-tree-180/stderr.txt file found 2024-06-13 05:02:58,244 DEBUG --- stderr --- 2024-06-13 05:02:58,244 DEBUG 2024-06-13 05:02:58,245 INFO 2024-06-13 05:02:58,245 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-13 05:02:58,245 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:58,542 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:58,542 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:58,542 DEBUG --- stdout --- 2024-06-13 05:02:58,543 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:58,543 DEBUG --- stderr --- 2024-06-13 05:02:58,543 DEBUG 2024-06-13 05:02:58,543 INFO 2024-06-13 05:02:58,543 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-180/stderr.txt 2024-06-13 05:02:59,337 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 05:02:59,337 DEBUG --- stdout --- 2024-06-13 05:02:59,337 DEBUG 0 /results/orders/login-tree-180/stderr.txt 2024-06-13 05:02:59,337 DEBUG --- stderr --- 2024-06-13 05:02:59,337 DEBUG 2024-06-13 05:02:59,337 INFO - Download file (size 0k) 2024-06-13 05:02:59,338 INFO 2024-06-13 05:02:59,338 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-13 05:02:59,338 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:59,635 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:59,635 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:59,635 DEBUG --- stdout --- 2024-06-13 05:02:59,635 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:59,635 DEBUG --- stderr --- 2024-06-13 05:02:59,635 DEBUG source : /results/orders/login-tree-180/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stderr.txt 2024-06-13 05:02:59,636 INFO 2024-06-13 05:02:59,636 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-13 05:02:59,636 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:02:59,951 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:02:59,951 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:02:59,951 DEBUG --- stdout --- 2024-06-13 05:02:59,951 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:02:59,951 DEBUG --- stderr --- 2024-06-13 05:02:59,951 DEBUG 2024-06-13 05:02:59,952 INFO 2024-06-13 05:02:59,952 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-180/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stderr.txt 2024-06-13 05:02:59,952 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 05:03:00,688 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:00,688 DEBUG --- stdout --- 2024-06-13 05:03:00,688 DEBUG tar: Removing leading `/' from member names 2024-06-13 05:03:00,688 DEBUG --- stderr --- 2024-06-13 05:03:00,688 DEBUG 2024-06-13 05:03:00,689 INFO 2024-06-13 05:03:00,689 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-13 05:03:00,689 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:01,005 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:01,005 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:01,005 DEBUG --- stdout --- 2024-06-13 05:03:01,005 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:01,005 DEBUG --- stderr --- 2024-06-13 05:03:01,005 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 05:03:01,005 INFO 2024-06-13 05:03:01,005 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-13 05:03:01,005 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:01,314 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:01,314 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:01,316 DEBUG --- stdout --- 2024-06-13 05:03:01,316 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:01,316 DEBUG --- stderr --- 2024-06-13 05:03:01,316 DEBUG 2024-06-13 05:03:01,317 INFO 2024-06-13 05:03:01,317 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-180/stdout.txt" ]; then echo "/results/orders/login-tree-180/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-180/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/stdout.txt file found" 2024-06-13 05:03:01,317 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 05:03:02,133 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 05:03:02,133 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:02,133 DEBUG --- stdout --- 2024-06-13 05:03:02,133 DEBUG /results/orders/login-tree-180/stdout.txt file found 2024-06-13 05:03:02,133 DEBUG --- stderr --- 2024-06-13 05:03:02,133 DEBUG 2024-06-13 05:03:02,134 INFO 2024-06-13 05:03:02,134 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-13 05:03:02,134 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:02,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:02,433 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:02,433 DEBUG --- stdout --- 2024-06-13 05:03:02,433 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:02,433 DEBUG --- stderr --- 2024-06-13 05:03:02,433 DEBUG 2024-06-13 05:03:02,434 INFO 2024-06-13 05:03:02,434 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-180/stdout.txt 2024-06-13 05:03:03,229 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 05:03:03,229 DEBUG --- stdout --- 2024-06-13 05:03:03,229 DEBUG 1715496 /results/orders/login-tree-180/stdout.txt 2024-06-13 05:03:03,229 DEBUG --- stderr --- 2024-06-13 05:03:03,229 DEBUG 2024-06-13 05:03:03,229 INFO - Download file (size 1715496k) 2024-06-13 05:03:03,230 INFO 2024-06-13 05:03:03,230 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-13 05:03:03,230 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:03,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:03,538 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:03,538 DEBUG --- stdout --- 2024-06-13 05:03:03,538 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:03,538 DEBUG --- stderr --- 2024-06-13 05:03:03,538 DEBUG source : /results/orders/login-tree-180/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stdout.txt 2024-06-13 05:03:03,538 INFO 2024-06-13 05:03:03,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-06-13 05:03:03,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:03,837 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:03,837 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:03,837 DEBUG --- stdout --- 2024-06-13 05:03:03,838 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:03,838 DEBUG --- stderr --- 2024-06-13 05:03:03,838 DEBUG 2024-06-13 05:03:03,838 INFO 2024-06-13 05:03:03,838 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-180/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stdout.txt 2024-06-13 05:03:03,838 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 05:03:46,578 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:46,578 DEBUG --- stdout --- 2024-06-13 05:03:46,578 DEBUG tar: Removing leading `/' from member names 2024-06-13 05:03:46,578 DEBUG --- stderr --- 2024-06-13 05:03:46,578 DEBUG ________________________________________________________________________________ [2024-06-13 05:03:46] login-tree-180 show_cmd : Display order command ________________________________________________________________________________ 2024-06-13 05:03:46,579 INFO 2024-06-13 05:03:46,579 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-13 05:03:46,579 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:46,894 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:46,895 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:46,895 DEBUG --- stdout --- 2024-06-13 05:03:46,895 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:46,895 DEBUG --- stderr --- 2024-06-13 05:03:46,895 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-13 05:03:46,895 INFO 2024-06-13 05:03:46,895 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-13 05:03:46,895 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:47,210 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:47,210 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:47,210 DEBUG --- stdout --- 2024-06-13 05:03:47,210 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:47,210 DEBUG --- stderr --- 2024-06-13 05:03:47,210 DEBUG 2024-06-13 05:03:47,211 INFO 2024-06-13 05:03:47,211 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-180/cmd.txt" ]; then echo "/results/orders/login-tree-180/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-180/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/cmd.txt file found" 2024-06-13 05:03:47,211 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-13 05:03:48,022 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-13 05:03:48,022 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:48,023 DEBUG --- stdout --- 2024-06-13 05:03:48,023 DEBUG /results/orders/login-tree-180/cmd.txt file found 2024-06-13 05:03:48,023 DEBUG --- stderr --- 2024-06-13 05:03:48,023 DEBUG 2024-06-13 05:03:48,023 INFO 2024-06-13 05:03:48,023 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-13 05:03:48,023 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:48,338 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:48,338 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:48,338 DEBUG --- stdout --- 2024-06-13 05:03:48,338 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:48,338 DEBUG --- stderr --- 2024-06-13 05:03:48,338 DEBUG 2024-06-13 05:03:48,338 INFO 2024-06-13 05:03:48,339 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-180/cmd.txt 2024-06-13 05:03:49,146 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-13 05:03:49,146 DEBUG --- stdout --- 2024-06-13 05:03:49,146 DEBUG 4 /results/orders/login-tree-180/cmd.txt 2024-06-13 05:03:49,147 DEBUG --- stderr --- 2024-06-13 05:03:49,147 DEBUG 2024-06-13 05:03:49,147 INFO - Download file (size 4k) 2024-06-13 05:03:49,147 INFO 2024-06-13 05:03:49,147 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-13 05:03:49,147 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:49,445 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:49,445 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:49,445 DEBUG --- stdout --- 2024-06-13 05:03:49,445 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:49,445 DEBUG --- stderr --- 2024-06-13 05:03:49,445 DEBUG source : /results/orders/login-tree-180/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/cmd.txt 2024-06-13 05:03:49,446 INFO 2024-06-13 05:03:49,446 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-13 05:03:49,446 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-13 05:03:49,751 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-13 05:03:49,751 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:49,751 DEBUG --- stdout --- 2024-06-13 05:03:49,751 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-13 05:03:49,751 DEBUG --- stderr --- 2024-06-13 05:03:49,751 DEBUG 2024-06-13 05:03:49,751 INFO 2024-06-13 05:03:49,752 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-180/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/cmd.txt 2024-06-13 05:03:49,752 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-13 05:03:50,490 INFO [loop_until]: OK (rc = 0) 2024-06-13 05:03:50,491 DEBUG --- stdout --- 2024-06-13 05:03:50,491 DEBUG tar: Removing leading `/' from member names 2024-06-13 05:03:50,491 DEBUG --- stderr --- 2024-06-13 05:03:50,491 DEBUG 2024-06-13 05:03:50,491 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/cmd.txt is small (1 lines), printing all lines: 2024-06-13 05:03:50,491 DEBUG ----- output ----- 2024-06-13 05:03:50,491 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.loginProtect@ema-perf-p1protect" --results-folder /results/gatling/login-tree-180 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-13 05:03:50,491 INFO ________________________________________________________________________________ [2024-06-13 05:03:50] login-tree-180 show_rc : Display rc order ________________________________________________________________________________ 2024-06-13 05:03:50,492 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/rc.txt is small (1 lines), printing all lines: 2024-06-13 05:03:50,492 DEBUG ----- output ----- 2024-06-13 05:03:50,492 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-13 05:03:50,492 INFO ________________________________________________________________________________ [2024-06-13 05:03:50] login-tree-180 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-13 05:03:52,872 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/05_login-tree-180/stdout.txt is 10153685 lines): 2024-06-13 05:03:52,872 DEBUG ----- output ----- 2024-06-13 05:03:52,872 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-13 05:03:52,872 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-13 05:03:52,872 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-13 05:03:52,872 INFO 2024-06-13 05:03:52,872 INFO ================================================================================ 2024-06-13 05:03:52,872 INFO 2024-06-13 05:00:43 5s elapsed 2024-06-13 05:03:52,872 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:52,872 INFO > Global (OK=270 KO=0 ) 2024-06-13 05:03:52,872 INFO > protectLoginInitiate (OK=72 KO=0 ) 2024-06-13 05:03:52,872 INFO > PingOneProtectInitializeCallback (OK=70 KO=0 ) 2024-06-13 05:03:52,872 INFO > restLoginUsernamePasswordCallback (OK=67 KO=0 ) 2024-06-13 05:03:52,872 INFO > PingOneProtectEvaluationCallback (OK=51 KO=0 ) 2024-06-13 05:03:52,872 INFO > restloginProgressiveProfileCallback (OK=10 KO=0 ) 2024-06-13 05:03:52,872 INFO 2024-06-13 05:03:52,872 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:52,872 INFO [- ] 0% 2024-06-13 05:03:52,872 INFO waiting: 649938 / active: 25 / done: 51 2024-06-13 05:03:52,872 INFO ================================================================================ 2024-06-13 05:03:52,872 INFO 2024-06-13 05:03:52,872 INFO 2024-06-13 05:03:52,872 INFO ================================================================================ 2024-06-13 05:03:52,873 INFO 2024-06-13 05:00:48 10s elapsed 2024-06-13 05:03:52,873 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:52,873 INFO > Global (OK=1161 KO=0 ) 2024-06-13 05:03:52,873 INFO > protectLoginInitiate (OK=295 KO=0 ) 2024-06-13 05:03:52,873 INFO > PingOneProtectInitializeCallback (OK=289 KO=0 ) 2024-06-13 05:03:52,873 INFO > restLoginUsernamePasswordCallback (OK=282 KO=0 ) 2024-06-13 05:03:52,873 INFO > PingOneProtectEvaluationCallback (OK=252 KO=0 ) 2024-06-13 05:03:52,873 INFO > restloginProgressiveProfileCallback (OK=43 KO=0 ) 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:52,873 INFO [- ] 0% 2024-06-13 05:03:52,873 INFO waiting: 649712 / active: 52 / done: 250 2024-06-13 05:03:52,873 INFO ================================================================================ 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO ================================================================================ 2024-06-13 05:03:52,873 INFO 2024-06-13 05:00:53 15s elapsed 2024-06-13 05:03:52,873 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:52,873 INFO > Global (OK=2686 KO=0 ) 2024-06-13 05:03:52,873 INFO > protectLoginInitiate (OK=667 KO=0 ) 2024-06-13 05:03:52,873 INFO > PingOneProtectInitializeCallback (OK=658 KO=0 ) 2024-06-13 05:03:52,873 INFO > restLoginUsernamePasswordCallback (OK=648 KO=0 ) 2024-06-13 05:03:52,873 INFO > PingOneProtectEvaluationCallback (OK=606 KO=0 ) 2024-06-13 05:03:52,873 INFO > restloginProgressiveProfileCallback (OK=107 KO=0 ) 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:52,873 INFO [- ] 0% 2024-06-13 05:03:52,873 INFO waiting: 649337 / active: 72 / done: 605 2024-06-13 05:03:52,873 INFO ================================================================================ 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO ================================================================================ 2024-06-13 05:03:52,873 INFO 2024-06-13 05:00:58 20s elapsed 2024-06-13 05:03:52,873 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:52,873 INFO > Global (OK=4848 KO=0 ) 2024-06-13 05:03:52,873 INFO > protectLoginInitiate (OK=1190 KO=0 ) 2024-06-13 05:03:52,873 INFO > PingOneProtectInitializeCallback (OK=1177 KO=0 ) 2024-06-13 05:03:52,873 INFO > restLoginUsernamePasswordCallback (OK=1163 KO=0 ) 2024-06-13 05:03:52,873 INFO > PingOneProtectEvaluationCallback (OK=1103 KO=0 ) 2024-06-13 05:03:52,873 INFO > restloginProgressiveProfileCallback (OK=215 KO=0 ) 2024-06-13 05:03:52,873 INFO 2024-06-13 05:03:52,873 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:52,874 INFO [- ] 0% 2024-06-13 05:03:52,874 INFO waiting: 648812 / active: 103 / done: 1099 2024-06-13 05:03:52,874 INFO ================================================================================ 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO ================================================================================ 2024-06-13 05:03:52,874 INFO 2024-06-13 05:01:03 25s elapsed 2024-06-13 05:03:52,874 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:52,874 INFO > Global (OK=7623 KO=0 ) 2024-06-13 05:03:52,874 INFO > protectLoginInitiate (OK=1862 KO=0 ) 2024-06-13 05:03:52,874 INFO > PingOneProtectInitializeCallback (OK=1845 KO=0 ) 2024-06-13 05:03:52,874 INFO > restLoginUsernamePasswordCallback (OK=1821 KO=0 ) 2024-06-13 05:03:52,874 INFO > PingOneProtectEvaluationCallback (OK=1746 KO=0 ) 2024-06-13 05:03:52,874 INFO > restloginProgressiveProfileCallback (OK=349 KO=0 ) 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:52,874 INFO [- ] 0% 2024-06-13 05:03:52,874 INFO waiting: 648136 / active: 142 / done: 1736 2024-06-13 05:03:52,874 INFO ================================================================================ 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO ================================================================================ 2024-06-13 05:03:52,874 INFO 2024-06-13 05:01:08 30s elapsed 2024-06-13 05:03:52,874 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:52,874 INFO > Global (OK=11014 KO=0 ) 2024-06-13 05:03:52,874 INFO > protectLoginInitiate (OK=2682 KO=0 ) 2024-06-13 05:03:52,874 INFO > PingOneProtectInitializeCallback (OK=2660 KO=0 ) 2024-06-13 05:03:52,874 INFO > restLoginUsernamePasswordCallback (OK=2631 KO=0 ) 2024-06-13 05:03:52,874 INFO > PingOneProtectEvaluationCallback (OK=2540 KO=0 ) 2024-06-13 05:03:52,874 INFO > restloginProgressiveProfileCallback (OK=501 KO=0 ) 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:52,874 INFO [- ] 0% 2024-06-13 05:03:52,874 INFO waiting: 647311 / active: 177 / done: 2526 2024-06-13 05:03:52,874 INFO ================================================================================ 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO 2024-06-13 05:03:52,874 INFO [...] 2024-06-13 05:03:56,441 INFO > restloginProgressiveProfileCallback: Failed to build request: 40242 (10.00%) 2024-06-13 05:03:56,442 INFO No attribute named 'callbacks' is defined 2024-06-13 05:03:56,442 INFO > status.find.is(200), but actually found 401 8149 ( 2.03%) 2024-06-13 05:03:56,442 INFO > status.find.is(200), but actually found 502 7761 ( 1.93%) 2024-06-13 05:03:56,442 INFO > jsonPath($.tokenId).find.exists, found nothing 4001 ( 0.99%) 2024-06-13 05:03:56,442 INFO > status.find.is(200), but actually found 400 587 ( 0.15%) 2024-06-13 05:03:56,442 INFO > Request timeout to openam-ema-perf-p1protect.forgeblocks.com/3 2 ( 0.00%) 2024-06-13 05:03:56,442 INFO 4.49.48.52:443 after 60000 ms 2024-06-13 05:03:56,442 INFO > j.i.IOException: Premature close 1 ( 0.00%) 2024-06-13 05:03:56,442 INFO 2024-06-13 05:03:56,442 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:56,442 INFO [#########################################################################-] 99% 2024-06-13 05:03:56,442 INFO waiting: 922 / active: 274 / done: 648818 2024-06-13 05:03:56,442 INFO ================================================================================ 2024-06-13 05:03:56,442 INFO 2024-06-13 05:03:56,442 INFO 2024-06-13 05:03:56,442 INFO ================================================================================ 2024-06-13 05:03:56,442 INFO 2024-06-13 06:01:08 3630s elapsed 2024-06-13 05:03:56,442 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:56,442 INFO > Global (OK=2433223 KO=241422) 2024-06-13 05:03:56,442 INFO > protectLoginInitiate (OK=609721 KO=40242 ) 2024-06-13 05:03:56,442 INFO > PingOneProtectInitializeCallback (OK=586703 KO=22961 ) 2024-06-13 05:03:56,442 INFO > restLoginUsernamePasswordCallback (OK=568371 KO=41224 ) 2024-06-13 05:03:56,442 INFO > PingOneProtectEvaluationCallback (OK=543398 KO=66035 ) 2024-06-13 05:03:56,442 INFO > restloginProgressiveProfileCallback (OK=125030 KO=70960 ) 2024-06-13 05:03:56,442 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 05:03:56,442 INFO > status.find.is(200), but actually found 503 220921 (54.90%) 2024-06-13 05:03:56,442 INFO > PingOneProtectInitializeCallback: Failed to build request: No 40242 (10.00%) 2024-06-13 05:03:56,442 INFO attribute named 'callbacks' is defined 2024-06-13 05:03:56,442 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 40242 (10.00%) 2024-06-13 05:03:56,443 INFO attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 40242 (10.00%) 2024-06-13 05:03:56,443 INFO attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > restloginProgressiveProfileCallback: Failed to build request: 40242 (10.00%) 2024-06-13 05:03:56,443 INFO No attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > status.find.is(200), but actually found 401 8149 ( 2.03%) 2024-06-13 05:03:56,443 INFO > status.find.is(200), but actually found 502 7761 ( 1.93%) 2024-06-13 05:03:56,443 INFO > jsonPath($.tokenId).find.exists, found nothing 4001 ( 0.99%) 2024-06-13 05:03:56,443 INFO > status.find.is(200), but actually found 400 587 ( 0.15%) 2024-06-13 05:03:56,443 INFO > Request timeout to openam-ema-perf-p1protect.forgeblocks.com/3 2 ( 0.00%) 2024-06-13 05:03:56,443 INFO 4.49.48.52:443 after 60000 ms 2024-06-13 05:03:56,443 INFO > j.i.IOException: Premature close 1 ( 0.00%) 2024-06-13 05:03:56,443 INFO 2024-06-13 05:03:56,443 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:56,443 INFO [#########################################################################-] 99% 2024-06-13 05:03:56,443 INFO waiting: 0 / active: 358 / done: 649656 2024-06-13 05:03:56,443 INFO ================================================================================ 2024-06-13 05:03:56,443 INFO 2024-06-13 05:03:56,443 INFO 2024-06-13 05:03:56,443 INFO ================================================================================ 2024-06-13 05:03:56,443 INFO 2024-06-13 06:01:10 3631s elapsed 2024-06-13 05:03:56,443 INFO ---- Requests ------------------------------------------------------------------ 2024-06-13 05:03:56,443 INFO > Global (OK=2434023 KO=241422) 2024-06-13 05:03:56,443 INFO > protectLoginInitiate (OK=609772 KO=40242 ) 2024-06-13 05:03:56,443 INFO > PingOneProtectInitializeCallback (OK=586811 KO=22961 ) 2024-06-13 05:03:56,443 INFO > restLoginUsernamePasswordCallback (OK=568548 KO=41224 ) 2024-06-13 05:03:56,443 INFO > PingOneProtectEvaluationCallback (OK=543737 KO=66035 ) 2024-06-13 05:03:56,443 INFO > restloginProgressiveProfileCallback (OK=125155 KO=70960 ) 2024-06-13 05:03:56,443 INFO ---- Errors -------------------------------------------------------------------- 2024-06-13 05:03:56,443 INFO > status.find.is(200), but actually found 503 220921 (54.90%) 2024-06-13 05:03:56,443 INFO > PingOneProtectInitializeCallback: Failed to build request: No 40242 (10.00%) 2024-06-13 05:03:56,443 INFO attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 40242 (10.00%) 2024-06-13 05:03:56,443 INFO attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 40242 (10.00%) 2024-06-13 05:03:56,443 INFO attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > restloginProgressiveProfileCallback: Failed to build request: 40242 (10.00%) 2024-06-13 05:03:56,443 INFO No attribute named 'callbacks' is defined 2024-06-13 05:03:56,443 INFO > status.find.is(200), but actually found 401 8149 ( 2.03%) 2024-06-13 05:03:56,444 INFO > status.find.is(200), but actually found 502 7761 ( 1.93%) 2024-06-13 05:03:56,444 INFO > jsonPath($.tokenId).find.exists, found nothing 4001 ( 0.99%) 2024-06-13 05:03:56,444 INFO > status.find.is(200), but actually found 400 587 ( 0.15%) 2024-06-13 05:03:56,444 INFO > Request timeout to openam-ema-perf-p1protect.forgeblocks.com/3 2 ( 0.00%) 2024-06-13 05:03:56,444 INFO 4.49.48.52:443 after 60000 ms 2024-06-13 05:03:56,444 INFO > j.i.IOException: Premature close 1 ( 0.00%) 2024-06-13 05:03:56,444 INFO 2024-06-13 05:03:56,444 INFO ---- idc.loginProtect ---------------------------------------------------------- 2024-06-13 05:03:56,444 INFO [##########################################################################]100% 2024-06-13 05:03:56,444 INFO waiting: 0 / active: 0 / done: 650014 2024-06-13 05:03:56,444 INFO ================================================================================ 2024-06-13 05:03:56,444 INFO 2024-06-13 05:03:56,444 INFO Simulation com.forgerock.gatling.Loadtest completed in 3631 seconds 2024-06-13 05:03:56,444 INFO 2024-06-13 05:03:56,444 INFO ---------------------- 2024-06-13 05:03:56,444 INFO [13/Jun/2024 06:01:10] Create tar file login-tree-180.tar.gz 2024-06-13 05:03:56,444 INFO ---------------------- 2024-06-13 05:03:56,444 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-13 05:03:56,444 INFO RESULT_FOLDER=login-tree-180 2024-06-13 05:03:56,444 INFO SIMULATION_PATH=login-tree-180/loadtest-20240613040038343 2024-06-13 05:03:56,444 INFO PWD=/results/gatling/login-tree-180/loadtest-20240613040038343 2024-06-13 05:03:56,444 INFO [cmd] tar -czvf /results/gatling//login-tree-180.tar.gz --exclude=simulation.log * 2024-06-13 05:03:56,444 INFO /results/gatling 2024-06-13 05:03:56,444 INFO 2024-06-13 05:03:56,444 INFO ---------------------- 2024-06-13 05:03:56,444 INFO [13/Jun/2024 06:01:10] Update /results/gatling//latest 2024-06-13 05:03:56,444 INFO ---------------------- 2024-06-13 05:03:56,444 INFO 2024-06-13 05:03:56,444 INFO ---------------------- 2024-06-13 05:03:56,444 INFO [13/Jun/2024 06:01:10] Update /results/gatling//latest.tar.gz 2024-06-13 05:03:56,444 INFO ---------------------- 2024-06-13 05:03:56,444 INFO ________________________________________________________________________________ [2024-06-13 05:03:56] login-tree-180 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-13 05:03:56,445 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stderr.txt is small (0 lines), printing all lines: 2024-06-13 05:03:56,445 DEBUG ----- output ----- 2024-06-13 05:03:56,445 DEBUG 2024-06-13 05:03:56,445 INFO ________________________________________________________________________________ [2024-06-13 05:03:56] login-tree-180 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-13 05:04:31] login-tree-180 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-13 05:04:31] login-tree-180 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped