--Task--
name: login-tree-275
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-250']
preceding_task: login-tree-250
options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 275, '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-22 08:33:28] login-tree-275 pre : N/A
________________________________________________________________________________
2024-06-22 08:33:28 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds)
2024-06-22 08:33:28 - INFO: timeout was calculated based on simulation duration parameter
Pod log file : /results/orders/login-tree-275/stdout.txt
Pod log : /results/orders/login-tree-275
2024-06-22 08:33:28,954 INFO
2024-06-22 08:33:28,954 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-22 08:33:28,954 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 08:33:29,264 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 08:33:29,265 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:29,265 DEBUG --- stdout ---
2024-06-22 08:33:29,265 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 08:33:29,265 DEBUG --- stderr ---
2024-06-22 08:33:29,265 DEBUG
________________________________________________________________________________
[2024-06-22 08:33:29] login-tree-275 print_command : Display the command to be forwarded to overseer as an order
________________________________________________________________________________
2024-06-22 08:33:29,266 INFO
2024-06-22 08:33:29,266 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-22 08:33:29,266 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 08:33:29,584 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 08:33:29,584 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:29,584 DEBUG --- stdout ---
2024-06-22 08:33:29,584 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 08:33:29,584 DEBUG --- stderr ---
2024-06-22 08:33:29,584 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-p1" --results-folder /results/gatling/login-tree-275 --extra-run-jvm-options "-Xmx4G" --no-reports
________________________________________________________________________________
[2024-06-22 08:33:29] login-tree-275 create_order : Create order for overseer with the command to run
________________________________________________________________________________
2024-06-22 08:33:29,585 INFO
2024-06-22 08:33:29,585 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-22 08:33:29,585 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 08:33:29,906 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 08:33:29,906 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:29,906 DEBUG --- stdout ---
2024-06-22 08:33:29,906 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 08:33:29,906 DEBUG --- stderr ---
2024-06-22 08:33:29,906 DEBUG
Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/order.json
{
"name": "login-tree-275",
"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-p1\" --results-folder /results/gatling/login-tree-275 --extra-run-jvm-options \"-Xmx4G\" --no-reports",
"out-dir": "/results/orders/login-tree-275",
"environment": {
"JAVA_OPTS": "-Dam_host=openam-ema-perf-p1.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=69cee86761d9c02 -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=275 -Duser_password=Pa_ssw0rd"
}
}
________________________________________________________________________________
[2024-06-22 08:33:29] login-tree-275 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-22 08:33:29,909 INFO
2024-06-22 08:33:29,909 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-06-22 08:33:29,909 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2024-06-22 08:33:30,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 08:33:30,161 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:30,161 DEBUG --- stdout ---
2024-06-22 08:33:30,162 DEBUG WAITING
2024-06-22 08:33:30,162 DEBUG --- stderr ---
2024-06-22 08:33:30,162 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-22 08:33:30] login-tree-275 start_order : Send the order to the overseer pod
________________________________________________________________________________
2024-06-22 08:33:30,162 INFO
2024-06-22 08:33:30,163 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-22 08:33:30,163 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 08:33:30,479 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 08:33:30,480 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:30,480 DEBUG --- stdout ---
2024-06-22 08:33:30,480 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 08:33:30,480 DEBUG --- stderr ---
2024-06-22 08:33:30,480 DEBUG
2024-06-22 08:33:30,480 INFO
2024-06-22 08:33:30,480 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/06_login-tree-275/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json
2024-06-22 08:33:30,480 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 08:33:31,854 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:31,854 DEBUG --- stdout ---
2024-06-22 08:33:31,854 DEBUG
2024-06-22 08:33:31,854 DEBUG --- stderr ---
2024-06-22 08:33:31,854 DEBUG
Order has been successfully forwarded to overseer
2024-06-22 08:33:31 - INFO: Check if Gatling simulation started successfully
2024-06-22 08:33:31,854 INFO
2024-06-22 08:33:31,854 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-22 08:33:31,855 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 08:33:32,178 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 08:33:32,178 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:32,178 DEBUG --- stdout ---
2024-06-22 08:33:32,178 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 08:33:32,178 DEBUG --- stderr ---
2024-06-22 08:33:32,178 DEBUG
2024-06-22 08:33:32,179 INFO
2024-06-22 08:33:32,179 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- head -n 25 /results/orders/login-tree-275/stdout.txt | grep "started..."
2024-06-22 08:33:32,179 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2024-06-22 08:33:32,925 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry
2024-06-22 08:33:38,679 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found
2024-06-22 08:33:38,679 INFO [loop_until]: OK (rc = 0)
2024-06-22 08:33:38,679 DEBUG --- stdout ---
2024-06-22 08:33:38,680 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling
Gatling 3.11.4 is available! (you're using 3.9.5)
Simulation com.forgerock.gatling.Loadtest started...
2024-06-22 08:33:38,680 DEBUG --- stderr ---
2024-06-22 08:33:38,680 DEBUG
________________________________________________________________________________
[2024-06-22 08:33:38] login-tree-275 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-22 09:35:49] login-tree-275 stop_order : Stop the order on overseer pod
________________________________________________________________________________
2024-06-22 09:35:49,107 INFO
2024-06-22 09:35:49,108 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-22 09:35:49,108 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:49,450 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:49,450 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:49,451 DEBUG --- stdout ---
2024-06-22 09:35:49,452 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:49,452 DEBUG --- stderr ---
2024-06-22 09:35:49,452 DEBUG
2024-06-22 09:35:49,452 INFO
2024-06-22 09:35:49,452 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 -- rm -f /results/orders/order.json
2024-06-22 09:35:49,452 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 09:35:50,260 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:50,260 DEBUG --- stdout ---
2024-06-22 09:35:50,260 DEBUG
2024-06-22 09:35:50,260 DEBUG --- stderr ---
2024-06-22 09:35:50,260 DEBUG
Order ran during 1h 02m
________________________________________________________________________________
[2024-06-22 09:35:50] login-tree-275 download_logs : Download individual order files
________________________________________________________________________________
2024-06-22 09:35:50 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/order.json
2024-06-22 09:35:50,262 INFO
2024-06-22 09:35:50,262 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-22 09:35:50,262 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:50,569 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:50,570 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:50,570 DEBUG --- stdout ---
2024-06-22 09:35:50,570 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:50,570 DEBUG --- stderr ---
2024-06-22 09:35:50,570 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 09:35:50,570 INFO
2024-06-22 09:35:50,570 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-22 09:35:50,570 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:50,880 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:50,880 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:50,880 DEBUG --- stdout ---
2024-06-22 09:35:50,880 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:50,880 DEBUG --- stderr ---
2024-06-22 09:35:50,880 DEBUG
2024-06-22 09:35:50,881 INFO
2024-06-22 09:35:50,881 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/rc.txt" ]; then echo "/results/orders/login-tree-275/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-275/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/rc.txt file found"
2024-06-22 09:35:50,881 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 09:35:51,722 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 09:35:51,722 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:51,722 DEBUG --- stdout ---
2024-06-22 09:35:51,722 DEBUG /results/orders/login-tree-275/rc.txt file found
2024-06-22 09:35:51,722 DEBUG --- stderr ---
2024-06-22 09:35:51,722 DEBUG
2024-06-22 09:35:51,723 INFO
2024-06-22 09:35:51,723 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-22 09:35:51,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:52,042 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:52,042 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:52,042 DEBUG --- stdout ---
2024-06-22 09:35:52,042 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:52,042 DEBUG --- stderr ---
2024-06-22 09:35:52,042 DEBUG
2024-06-22 09:35:52,042 INFO
2024-06-22 09:35:52,043 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/rc.txt
2024-06-22 09:35:52,915 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 09:35:52,915 DEBUG --- stdout ---
2024-06-22 09:35:52,915 DEBUG 4 /results/orders/login-tree-275/rc.txt
2024-06-22 09:35:52,915 DEBUG --- stderr ---
2024-06-22 09:35:52,915 DEBUG
2024-06-22 09:35:52,915 INFO
- Download file (size 4k)
2024-06-22 09:35:52,916 INFO
2024-06-22 09:35:52,916 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-22 09:35:52,916 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:53,231 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:53,231 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:53,231 DEBUG --- stdout ---
2024-06-22 09:35:53,231 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:53,231 DEBUG --- stderr ---
2024-06-22 09:35:53,231 DEBUG
source : /results/orders/login-tree-275/rc.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/rc.txt
2024-06-22 09:35:53,232 INFO
2024-06-22 09:35:53,232 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-22 09:35:53,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:53,544 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:53,544 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:53,544 DEBUG --- stdout ---
2024-06-22 09:35:53,545 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:53,545 DEBUG --- stderr ---
2024-06-22 09:35:53,545 DEBUG
2024-06-22 09:35:53,545 INFO
2024-06-22 09:35:53,545 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/rc.txt
2024-06-22 09:35:53,545 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 09:35:54,344 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:54,344 DEBUG --- stdout ---
2024-06-22 09:35:54,344 DEBUG tar: Removing leading `/' from member names
2024-06-22 09:35:54,344 DEBUG --- stderr ---
2024-06-22 09:35:54,344 DEBUG
2024-06-22 09:35:54,345 INFO
2024-06-22 09:35:54,345 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-22 09:35:54,345 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:54,665 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:54,665 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:54,667 DEBUG --- stdout ---
2024-06-22 09:35:54,667 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:54,667 DEBUG --- stderr ---
2024-06-22 09:35:54,667 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 09:35:54,667 INFO
2024-06-22 09:35:54,668 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-22 09:35:54,668 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:54,984 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:54,984 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:54,984 DEBUG --- stdout ---
2024-06-22 09:35:54,984 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:54,984 DEBUG --- stderr ---
2024-06-22 09:35:54,984 DEBUG
2024-06-22 09:35:54,985 INFO
2024-06-22 09:35:54,985 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/stderr.txt" ]; then echo "/results/orders/login-tree-275/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-275/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/stderr.txt file found"
2024-06-22 09:35:54,985 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 09:35:55,792 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 09:35:55,792 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:55,792 DEBUG --- stdout ---
2024-06-22 09:35:55,792 DEBUG /results/orders/login-tree-275/stderr.txt file found
2024-06-22 09:35:55,792 DEBUG --- stderr ---
2024-06-22 09:35:55,792 DEBUG
2024-06-22 09:35:55,793 INFO
2024-06-22 09:35:55,793 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-22 09:35:55,793 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:56,109 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:56,109 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:56,109 DEBUG --- stdout ---
2024-06-22 09:35:56,109 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:56,109 DEBUG --- stderr ---
2024-06-22 09:35:56,109 DEBUG
2024-06-22 09:35:56,110 INFO
2024-06-22 09:35:56,110 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/stderr.txt
2024-06-22 09:35:56,909 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 09:35:56,910 DEBUG --- stdout ---
2024-06-22 09:35:56,910 DEBUG 0 /results/orders/login-tree-275/stderr.txt
2024-06-22 09:35:56,910 DEBUG --- stderr ---
2024-06-22 09:35:56,910 DEBUG
2024-06-22 09:35:56,910 INFO
- Download file (size 0k)
2024-06-22 09:35:56,910 INFO
2024-06-22 09:35:56,910 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-22 09:35:56,910 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:57,215 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:57,216 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:57,216 DEBUG --- stdout ---
2024-06-22 09:35:57,216 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:57,216 DEBUG --- stderr ---
2024-06-22 09:35:57,216 DEBUG
source : /results/orders/login-tree-275/stderr.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stderr.txt
2024-06-22 09:35:57,216 INFO
2024-06-22 09:35:57,216 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-22 09:35:57,216 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:57,519 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:57,520 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:57,520 DEBUG --- stdout ---
2024-06-22 09:35:57,520 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:57,520 DEBUG --- stderr ---
2024-06-22 09:35:57,520 DEBUG
2024-06-22 09:35:57,520 INFO
2024-06-22 09:35:57,520 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stderr.txt
2024-06-22 09:35:57,520 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 09:35:58,310 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:58,310 DEBUG --- stdout ---
2024-06-22 09:35:58,310 DEBUG tar: Removing leading `/' from member names
2024-06-22 09:35:58,310 DEBUG --- stderr ---
2024-06-22 09:35:58,310 DEBUG
2024-06-22 09:35:58,310 INFO
2024-06-22 09:35:58,310 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-22 09:35:58,311 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:58,637 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:58,637 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:58,637 DEBUG --- stdout ---
2024-06-22 09:35:58,637 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:58,637 DEBUG --- stderr ---
2024-06-22 09:35:58,637 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 09:35:58,637 INFO
2024-06-22 09:35:58,637 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-22 09:35:58,637 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:35:58,942 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:35:58,942 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:58,942 DEBUG --- stdout ---
2024-06-22 09:35:58,942 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:35:58,943 DEBUG --- stderr ---
2024-06-22 09:35:58,943 DEBUG
2024-06-22 09:35:58,943 INFO
2024-06-22 09:35:58,943 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/stdout.txt" ]; then echo "/results/orders/login-tree-275/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-275/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/stdout.txt file found"
2024-06-22 09:35:58,943 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 09:35:59,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 09:35:59,757 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:35:59,758 DEBUG --- stdout ---
2024-06-22 09:35:59,758 DEBUG /results/orders/login-tree-275/stdout.txt file found
2024-06-22 09:35:59,758 DEBUG --- stderr ---
2024-06-22 09:35:59,759 DEBUG
2024-06-22 09:35:59,759 INFO
2024-06-22 09:35:59,759 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-22 09:35:59,759 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:00,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:00,080 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:00,080 DEBUG --- stdout ---
2024-06-22 09:36:00,080 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:00,080 DEBUG --- stderr ---
2024-06-22 09:36:00,080 DEBUG
2024-06-22 09:36:00,081 INFO
2024-06-22 09:36:00,081 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/stdout.txt
2024-06-22 09:36:00,901 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 09:36:00,901 DEBUG --- stdout ---
2024-06-22 09:36:00,901 DEBUG 1760 /results/orders/login-tree-275/stdout.txt
2024-06-22 09:36:00,901 DEBUG --- stderr ---
2024-06-22 09:36:00,901 DEBUG
2024-06-22 09:36:00,901 INFO
- Download file (size 1760k)
2024-06-22 09:36:00,902 INFO
2024-06-22 09:36:00,902 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-22 09:36:00,902 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:01,225 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:01,225 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:01,225 DEBUG --- stdout ---
2024-06-22 09:36:01,225 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:01,225 DEBUG --- stderr ---
2024-06-22 09:36:01,225 DEBUG
source : /results/orders/login-tree-275/stdout.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stdout.txt
2024-06-22 09:36:01,225 INFO
2024-06-22 09:36:01,225 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-22 09:36:01,225 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:01,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:01,541 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:01,541 DEBUG --- stdout ---
2024-06-22 09:36:01,541 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:01,542 DEBUG --- stderr ---
2024-06-22 09:36:01,542 DEBUG
2024-06-22 09:36:01,542 INFO
2024-06-22 09:36:01,542 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stdout.txt
2024-06-22 09:36:01,542 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 09:36:02,722 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:02,722 DEBUG --- stdout ---
2024-06-22 09:36:02,722 DEBUG tar: Removing leading `/' from member names
2024-06-22 09:36:02,722 DEBUG --- stderr ---
2024-06-22 09:36:02,722 DEBUG
________________________________________________________________________________
[2024-06-22 09:36:02] login-tree-275 show_cmd : Display order command
________________________________________________________________________________
2024-06-22 09:36:02,723 INFO
2024-06-22 09:36:02,723 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-22 09:36:02,723 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:03,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:03,042 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:03,042 DEBUG --- stdout ---
2024-06-22 09:36:03,042 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:03,042 DEBUG --- stderr ---
2024-06-22 09:36:03,042 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 09:36:03,042 INFO
2024-06-22 09:36:03,042 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-22 09:36:03,042 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:03,364 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:03,365 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:03,365 DEBUG --- stdout ---
2024-06-22 09:36:03,365 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:03,365 DEBUG --- stderr ---
2024-06-22 09:36:03,365 DEBUG
2024-06-22 09:36:03,366 INFO
2024-06-22 09:36:03,366 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- bash -c 'if [ -f "/results/orders/login-tree-275/cmd.txt" ]; then echo "/results/orders/login-tree-275/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-275/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-275/cmd.txt file found"
2024-06-22 09:36:03,366 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 09:36:04,177 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 09:36:04,177 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:04,177 DEBUG --- stdout ---
2024-06-22 09:36:04,178 DEBUG /results/orders/login-tree-275/cmd.txt file found
2024-06-22 09:36:04,178 DEBUG --- stderr ---
2024-06-22 09:36:04,178 DEBUG
2024-06-22 09:36:04,178 INFO
2024-06-22 09:36:04,178 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-06-22 09:36:04,178 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:04,503 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:04,504 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:04,504 DEBUG --- stdout ---
2024-06-22 09:36:04,504 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:04,504 DEBUG --- stderr ---
2024-06-22 09:36:04,504 DEBUG
2024-06-22 09:36:04,504 INFO
2024-06-22 09:36:04,504 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5b6f9464fd-slbm8 --stdin -- du -k /results/orders/login-tree-275/cmd.txt
2024-06-22 09:36:05,332 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 09:36:05,333 DEBUG --- stdout ---
2024-06-22 09:36:05,334 DEBUG 4 /results/orders/login-tree-275/cmd.txt
2024-06-22 09:36:05,334 DEBUG --- stderr ---
2024-06-22 09:36:05,334 DEBUG
2024-06-22 09:36:05,334 INFO
- Download file (size 4k)
2024-06-22 09:36:05,335 INFO
2024-06-22 09:36:05,335 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-22 09:36:05,335 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:05,641 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:05,641 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:05,641 DEBUG --- stdout ---
2024-06-22 09:36:05,642 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:05,642 DEBUG --- stderr ---
2024-06-22 09:36:05,642 DEBUG
source : /results/orders/login-tree-275/cmd.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/cmd.txt
2024-06-22 09:36:05,642 INFO
2024-06-22 09:36:05,642 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-22 09:36:05,642 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 09:36:05,961 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 09:36:05,961 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:05,961 DEBUG --- stdout ---
2024-06-22 09:36:05,961 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 09:36:05,961 DEBUG --- stderr ---
2024-06-22 09:36:05,961 DEBUG
2024-06-22 09:36:05,961 INFO
2024-06-22 09:36:05,962 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5b6f9464fd-slbm8:/results/orders/login-tree-275/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/cmd.txt
2024-06-22 09:36:05,962 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 09:36:06,693 INFO [loop_until]: OK (rc = 0)
2024-06-22 09:36:06,693 DEBUG --- stdout ---
2024-06-22 09:36:06,693 DEBUG tar: Removing leading `/' from member names
2024-06-22 09:36:06,693 DEBUG --- stderr ---
2024-06-22 09:36:06,693 DEBUG
2024-06-22 09:36:06,694 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/cmd.txt is small (1 lines), printing all lines:
2024-06-22 09:36:06,694 DEBUG ----- output -----
2024-06-22 09:36:06,694 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-p1" --results-folder /results/gatling/login-tree-275 --extra-run-jvm-options "-Xmx4G" --no-reports
2024-06-22 09:36:06,694 INFO
________________________________________________________________________________
[2024-06-22 09:36:06] login-tree-275 show_rc : Display rc order
________________________________________________________________________________
2024-06-22 09:36:06,695 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/rc.txt is small (1 lines), printing all lines:
2024-06-22 09:36:06,695 DEBUG ----- output -----
2024-06-22 09:36:06,695 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
2024-06-22 09:36:06,695 INFO
________________________________________________________________________________
[2024-06-22 09:36:06] login-tree-275 show_stdout : Display stdout order
________________________________________________________________________________
2024-06-22 09:36:06,699 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/06_login-tree-275/stdout.txt is 22545 lines):
2024-06-22 09:36:06,699 DEBUG ----- output -----
2024-06-22 09:36:06,699 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling
2024-06-22 09:36:06,699 INFO Gatling 3.11.4 is available! (you're using 3.9.5)
2024-06-22 09:36:06,699 INFO Simulation com.forgerock.gatling.Loadtest started...
2024-06-22 09:36:06,699 INFO
2024-06-22 09:36:06,699 INFO ================================================================================
2024-06-22 09:36:06,699 INFO 2024-06-22 09:33:41 5s elapsed
2024-06-22 09:36:06,699 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,699 INFO > Global (OK=455 KO=0 )
2024-06-22 09:36:06,699 INFO > protectLoginInitiate (OK=113 KO=0 )
2024-06-22 09:36:06,699 INFO > PingOneProtectInitializeCallback (OK=111 KO=0 )
2024-06-22 09:36:06,699 INFO > restLoginUsernamePasswordCallback (OK=108 KO=0 )
2024-06-22 09:36:06,699 INFO > PingOneProtectEvaluationCallback (OK=98 KO=0 )
2024-06-22 09:36:06,699 INFO > restloginProgressiveProfileCallback (OK=25 KO=0 )
2024-06-22 09:36:06,699 INFO
2024-06-22 09:36:06,699 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,699 INFO [- ] 0%
2024-06-22 09:36:06,699 INFO waiting: 994602 / active: 19 / done: 96
2024-06-22 09:36:06,699 INFO ================================================================================
2024-06-22 09:36:06,699 INFO
2024-06-22 09:36:06,699 INFO
2024-06-22 09:36:06,699 INFO ================================================================================
2024-06-22 09:36:06,699 INFO 2024-06-22 09:33:46 10s elapsed
2024-06-22 09:36:06,699 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,699 INFO > Global (OK=1879 KO=0 )
2024-06-22 09:36:06,699 INFO > protectLoginInitiate (OK=455 KO=0 )
2024-06-22 09:36:06,699 INFO > PingOneProtectInitializeCallback (OK=451 KO=0 )
2024-06-22 09:36:06,699 INFO > restLoginUsernamePasswordCallback (OK=445 KO=0 )
2024-06-22 09:36:06,699 INFO > PingOneProtectEvaluationCallback (OK=422 KO=0 )
2024-06-22 09:36:06,699 INFO > restloginProgressiveProfileCallback (OK=106 KO=0 )
2024-06-22 09:36:06,699 INFO
2024-06-22 09:36:06,699 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,699 INFO [- ] 0%
2024-06-22 09:36:06,699 INFO waiting: 994257 / active: 43 / done: 417
2024-06-22 09:36:06,700 INFO ================================================================================
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO ================================================================================
2024-06-22 09:36:06,700 INFO 2024-06-22 09:33:51 15s elapsed
2024-06-22 09:36:06,700 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,700 INFO > Global (OK=4281 KO=0 )
2024-06-22 09:36:06,700 INFO > protectLoginInitiate (OK=1025 KO=0 )
2024-06-22 09:36:06,700 INFO > PingOneProtectInitializeCallback (OK=1020 KO=0 )
2024-06-22 09:36:06,700 INFO > restLoginUsernamePasswordCallback (OK=1010 KO=0 )
2024-06-22 09:36:06,700 INFO > PingOneProtectEvaluationCallback (OK=976 KO=0 )
2024-06-22 09:36:06,700 INFO > restloginProgressiveProfileCallback (OK=250 KO=0 )
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,700 INFO [- ] 0%
2024-06-22 09:36:06,700 INFO waiting: 993684 / active: 61 / done: 972
2024-06-22 09:36:06,700 INFO ================================================================================
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO ================================================================================
2024-06-22 09:36:06,700 INFO 2024-06-22 09:33:56 20s elapsed
2024-06-22 09:36:06,700 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,700 INFO > Global (OK=7625 KO=0 )
2024-06-22 09:36:06,700 INFO > protectLoginInitiate (OK=1825 KO=0 )
2024-06-22 09:36:06,700 INFO > PingOneProtectInitializeCallback (OK=1815 KO=0 )
2024-06-22 09:36:06,700 INFO > restLoginUsernamePasswordCallback (OK=1801 KO=0 )
2024-06-22 09:36:06,700 INFO > PingOneProtectEvaluationCallback (OK=1747 KO=0 )
2024-06-22 09:36:06,700 INFO > restloginProgressiveProfileCallback (OK=437 KO=0 )
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,700 INFO [- ] 0%
2024-06-22 09:36:06,700 INFO waiting: 992882 / active: 97 / done: 1738
2024-06-22 09:36:06,700 INFO ================================================================================
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO
2024-06-22 09:36:06,700 INFO ================================================================================
2024-06-22 09:36:06,700 INFO 2024-06-22 09:34:01 25s elapsed
2024-06-22 09:36:06,700 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,700 INFO > Global (OK=11977 KO=0 )
2024-06-22 09:36:06,700 INFO > protectLoginInitiate (OK=2854 KO=0 )
2024-06-22 09:36:06,700 INFO > PingOneProtectInitializeCallback (OK=2841 KO=0 )
2024-06-22 09:36:06,700 INFO > restLoginUsernamePasswordCallback (OK=2821 KO=0 )
2024-06-22 09:36:06,701 INFO > PingOneProtectEvaluationCallback (OK=2751 KO=0 )
2024-06-22 09:36:06,701 INFO > restloginProgressiveProfileCallback (OK=710 KO=0 )
2024-06-22 09:36:06,701 INFO
2024-06-22 09:36:06,701 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,701 INFO [- ] 0%
2024-06-22 09:36:06,701 INFO waiting: 991851 / active: 125 / done: 2741
2024-06-22 09:36:06,701 INFO ================================================================================
2024-06-22 09:36:06,701 INFO
2024-06-22 09:36:06,701 INFO
2024-06-22 09:36:06,701 INFO ================================================================================
2024-06-22 09:36:06,701 INFO 2024-06-22 09:34:06 30s elapsed
2024-06-22 09:36:06,701 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,701 INFO > Global (OK=17194 KO=0 )
2024-06-22 09:36:06,701 INFO > protectLoginInitiate (OK=4107 KO=0 )
2024-06-22 09:36:06,701 INFO > PingOneProtectInitializeCallback (OK=4088 KO=0 )
2024-06-22 09:36:06,701 INFO > restLoginUsernamePasswordCallback (OK=4064 KO=0 )
2024-06-22 09:36:06,701 INFO > PingOneProtectEvaluationCallback (OK=3955 KO=0 )
2024-06-22 09:36:06,701 INFO > restloginProgressiveProfileCallback (OK=980 KO=0 )
2024-06-22 09:36:06,701 INFO
2024-06-22 09:36:06,701 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,701 INFO [- ] 0%
2024-06-22 09:36:06,701 INFO waiting: 990591 / active: 191 / done: 3935
2024-06-22 09:36:06,701 INFO ================================================================================
2024-06-22 09:36:06,701 INFO
2024-06-22 09:36:06,701 INFO
2024-06-22 09:36:06,701 INFO [...]
2024-06-22 09:36:06,707 INFO HTTP response:
2024-06-22 09:36:06,707 INFO <<<<<<<<<<<<<<<<<<<<<<<<<
2024-06-22 09:36:06,707 INFO 10:34:11.829 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-8' crashed with 'j.l.IndexOutOfBoundsException: 1 is out of bounds (min 0, max 0)', forwarding to the next one
2024-06-22 09:36:06,707 INFO 10:34:11.980 [ERROR] i.g.c.a.b.SessionHookBuilder$$anon$1 - 'hook-4' crashed with 'j.l.IndexOutOfBoundsException: 1 is out of bounds (min 0, max 0)', forwarding to the next one
2024-06-22 09:36:06,708 INFO 10:34:12.027 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restloginProgressiveProfileCallback' failed for user 979870: jsonPath($.tokenId).find.exists, found nothing
2024-06-22 09:36:06,708 INFO 10:34:12.027 [DEBUG] i.g.h.e.r.DefaultStatsProcessor -
2024-06-22 09:36:06,708 INFO >>>>>>>>>>>>>>>>>>>>>>>>>>
2024-06-22 09:36:06,708 INFO Request:
2024-06-22 09:36:06,708 INFO restloginProgressiveProfileCallback: KO jsonPath($.tokenId).find.exists, found nothing
2024-06-22 09:36:06,708 INFO =========================
2024-06-22 09:36:06,708 INFO Session:
2024-06-22 09:36:06,708 INFO Session(idc.loginProtect,979870,HashMap(gatling.http.cache.baseUrl -> https://openam-ema-perf-p1.forgeblocks.com:443, username -> user.767574, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@378f2026, fake-user-agent -> Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/44.0.2403.157 Safari/537.36, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiIyN2IxMzBmMi04Mjg2LTRlNGEtOGY5NC01MWI0ZDM4YjdiMmIiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJocmdxZjUxcHF2NnA0amNxNzlibDkzbnFpcyIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTUwYlU1SFEwY3pXRkpIVUhwQ2EzZDBkVzFOVHpkQkxrTXhNMkZDYW5GV1dXdHZjRmd6WjBwelRteDJXRXhqWDI5SWVIbFFjbVF5ZVVsMGJWWTVjMWR0ZDJ4alpUZEhYM05UTnpSNk9VOXVVbFpyYjBsMWN6UXplbUl5T0c1aGJVbGZTVTk0ZUhSbVUwRXpWbEZJZUZCSVpVbEpNakJXZEhOUU0xUTJUMFJhU2kxRWNHeG5MV1pmZVdGa05IaGtWWEJGU3pJdGRIUjVjWFUyYnpoc2JFdEJTVmx5VG5obVZrdEZWVEZxY0VsTFlYSTBWMGRHVkRWU2NteEpTRll6T1cxV1FUZFFTSHBNY1dkMGVIbHdSVjlRY0ZnMlpEbGpOMGhZVmxCbk1VeFpUbHBXZURnM2VYWjRRVGR1VjBjeVFVTkRTRW8zU0dObkxXNVJkbnBOWjJnNGJqaFNjMFoxYVVRdFh6aE9WRzVqTVRWNVh6RXhMVVl0YjJJNVMyWm1iR3RMVlZCaVJsaGZWVFJyVEVVeWFXeG1ieTFXYXpWUFdXZDVWUzFIYlZSbVRVSllZM2M0VEhsNGFXTnFkM0pWU1dZeFpXNDJXbE0zVUdSS1EwRXpiSGwxVDFOVGFVVkpPVm93UTJwR1RuVnBiMUZTUlhWWmRIcHNhek5pV0ZKalNFdzRZemRMV0Mxa05qTmhRVUpCZDFkQ1VtWkZNbGxDWWxkUlZHcFNVa1ZUV1ZWeldVSnBTR1pLVXpCR1kwTk1VbGhuZGtKamF6UkxkRVpwTmtnek1FZDVNRkJJYXpWSFMzbGljR0pmZW5kQlozZFRkSEJRTURGNVltRnNkRFp4VFZSMlJYaFRSSGRxVFZGcFgxUk5jbEU0ZVhVd05tTXlaRXh4T0dnMGFHdzJUR1ZQVTBwRlZXaEdVWFJzWmpaQ1EzQlhibXhQTm1acmJHbFdTVXhLWVROS1UwbHhUVkJzU2tJelZ6QnBWM04xUlVsVE5UVjFTWEZtYUMxb01VWnhkMFkyWjBKaWVIWmhkV3B2UTNSMmRWSTJOVzFzUVVWc2NHZFpaMUJ1Y2xSUFkwcFJaWE5xUVRaUFpURkdVbGQ1TmtWNlNDMXNiMmhSYWtvNVlVWTVXVEZZY0dKNlNrSkVRVXBFY0hKMFlXaG1hWEZMUkhodUxXcEVhR0ZMWDNWRVJsTTRNVUl4UVV4NGQyRlpVblZZZUZneGRrUmxkRlpqYnpNeWVuSm9NMnBuUVRaUFoyVkVObEpLWWpBNFZFRlpjM2RDZVZWZmRXVTNOV3BGYUdsWFJFeFJjWGxOVVZGdFVEUnBSMkUzYlVSMVVIRkVORTlMUVdoTGMxQjJXR1U0Vm1jNFFuZEJhRTV2YlVKRWNXeDNWbmxXVkhRd1JWUkVVak5MU1Zock5scGFjelJCYVVoU2FWZHFNelZYVlc5SWVsQjNaa2R5U1VGRWNtOXpValZ4TjNsaWExRm9WakEwUTJ4bVVHWnBaa1U1Y1U1eFdsTmlRazVPTFU0M1VHRjZXbmhaTVRKVVVGVlhUM3B5Y25oTFlsYzNPVVUwVDAxeE9VeDVaVlpHYkd0aVFXbEZXR3A2TmpSVU9UUmxibnA0WVVOTlNFODRiMGhxVTJReWJIUXphV3cwZFV0VE0xSktNVkZFVFZkdVVtdzVWbXBxTmtGelNIRkZOazlITTFJMGJ5MTRha1F0UVUxQ2NVOXVNSFJGTjFOQ2RXSlBWVlEyY1RBdGVHWmxWQzFyTjFGQmJITm1NelZxYTNWbFRuSTVOMGN3YTBkWlRXRjNaRk5PZUhKTllUbFVXV3BKZEhjMU5IaExjelZsTVZCS1lVaEhVR1I1ZW1GbGRucHpUelV0TUdGeU16Sm9WMjVsVWxGamRXczJWMEpZZFRCWllVSnVRWFpmZWxoRFEwbE1NRTExZFZOQmNUSlRVMGxyYlhGelRsRk1UbkJHYlZGdE1WQkRPSE0yWVcwMmJGUkZkakJoWDIxRVUwUklkM0JrWVhVNVFrdDBVVzFqYzE4MGREUXlNV3AzV25aS1VVMXVTM1Z0Vm01UmFrMVBRMjlEVVRSSlRsUk5TRlZHVG1sTllXbFRhVFI0VEV0SFJtZFJNV2N1UzJ3elRITTFXWGQ1ZUdoTk9HaFZRMHA1VlVkNmR3Lk83SjZvMXhpSmUwcF83bmFCNlB5Y3d1ZThlYVYwWDV3Y3hxX3lSeFVxQzQiLCJleHAiOjE3MTkwNDkwOTEsImlhdCI6MTcxOTA0ODc5MX0.OoJdNP24qKO9TrxjoLVW7GdZSVDjaFkQ2vE7pBGeF7Q","callbacks":[{"type":"PingOneProtectInitializeCallback","output":[{"name":"envId","value":"078561fb-3b4f-4506-a102-c99b78f94a48"},{"name":"consoleLogEnabled","value":false},{"name":"deviceAttributesToIgnore","value":[]},{"name":"customHost","value":""},{"name":"lazyMetadata","value":false},{"name":"behavioralDataCollection","value":true},{"name":"deviceKeyRsyncIntervals","value":14},{"name":"enableTrust","value":false},{"name":"disableTags","value":false},{"name":"disableHub","value":false}],"input":[{"name":"IDToken1clientError","value":""}]}]}, gatling.http.cookies -> CookieJar(Map(CookieKey(amlbcookie,openam-ema-perf-p1.forgeblocks.com,/) -> StoredCookie(amlbcookie=01, domain=openam-ema-perf-p1.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None,false,false,1719048852026))), password -> Pa_ssw0rd, fake-ip -> 19.114.115.47, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@233922a2),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$823/0x000079e0946e7b98@16407b90,io.netty.channel.epoll.EpollEventLoop@3b718392)
2024-06-22 09:36:06,708 INFO =========================
2024-06-22 09:36:06,708 INFO HTTP request:
2024-06-22 09:36:06,708 INFO POST https://openam-ema-perf-p1.forgeblocks.com/am/json/realms/alpha/authenticate?authIndexType=service&authIndexValue=Login_Protect
2024-06-22 09:36:06,708 INFO headers:
2024-06-22 09:36:06,708 INFO Accept-API-Version: protocol=2.1,resource=1.0
2024-06-22 09:36:06,708 INFO content-type: application/json
2024-06-22 09:36:06,708 INFO Accept-Language: en-US,en;q=0.5
2024-06-22 09:36:06,708 INFO accept: application/json
2024-06-22 09:36:06,708 INFO cookie: amlbcookie=01
2024-06-22 09:36:06,708 INFO host: openam-ema-perf-p1.forgeblocks.com
2024-06-22 09:36:06,708 INFO content-length: 3412
2024-06-22 09:36:06,708 INFO cookies:
2024-06-22 09:36:06,708 INFO amlbcookie=01, domain=openam-ema-perf-p1.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None
2024-06-22 09:36:06,708 INFO body:StringChunksRequestBody{charset=UTF-8, content={"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiIyN2IxMzBmMi04Mjg2LTRlNGEtOGY5NC01MWI0ZDM4YjdiMmIiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJocmdxZjUxcHF2NnA0amNxNzlibDkzbnFpcyIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTUwYlU1SFEwY3pXRkpIVUhwQ2EzZDBkVzFOVHpkQkxrTXhNMkZDYW5GV1dXdHZjRmd6WjBwelRteDJXRXhqWDI5SWVIbFFjbVF5ZVVsMGJWWTVjMWR0ZDJ4alpUZEhYM05UTnpSNk9VOXVVbFpyYjBsMWN6UXplbUl5T0c1aGJVbGZTVTk0ZUhSbVUwRXpWbEZJZUZCSVpVbEpNakJXZEhOUU0xUTJUMFJhU2kxRWNHeG5MV1pmZVdGa05IaGtWWEJGU3pJdGRIUjVjWFUyYnpoc2JFdEJTVmx5VG5obVZrdEZWVEZxY0VsTFlYSTBWMGRHVkRWU2NteEpTRll6T1cxV1FUZFFTSHBNY1dkMGVIbHdSVjlRY0ZnMlpEbGpOMGhZVmxCbk1VeFpUbHBXZURnM2VYWjRRVGR1VjBjeVFVTkRTRW8zU0dObkxXNVJkbnBOWjJnNGJqaFNjMFoxYVVRdFh6aE9WRzVqTVRWNVh6RXhMVVl0YjJJNVMyWm1iR3RMVlZCaVJsaGZWVFJyVEVVeWFXeG1ieTFXYXpWUFdXZDVWUzFIYlZSbVRVSllZM2M0VEhsNGFXTnFkM0pWU1dZeFpXNDJXbE0zVUdSS1EwRXpiSGwxVDFOVGFVVkpPVm93UTJwR1RuVnBiMUZTUlhWWmRIcHNhek5pV0ZKalNFdzRZemRMV0Mxa05qTmhRVUpCZDFkQ1VtWkZNbGxDWWxkUlZHcFNVa1ZUV1ZWeldVSnBTR1pLVXpCR1kwTk1VbGhuZGtKamF6UkxkRVpwTmtnek1FZDVNRkJJYXpWSFMzbGljR0pmZW5kQlozZFRkSEJRTURGNVltRnNkRFp4VFZSMlJYaFRSSGRxVFZGcFgxUk5jbEU0ZVhVd05tTXlaRXh4T0dnMGFHdzJUR1ZQVTBwRlZXaEdVWFJzWmpaQ1EzQlhibXhQTm1acmJHbFdTVXhLWVROS1UwbHhUVkJzU2tJelZ6QnBWM04xUlVsVE5UVjFTWEZtYUMxb01VWnhkMFkyWjBKaWVIWmhkV3B2UTNSMmRWSTJOVzFzUVVWc2NHZFpaMUJ1Y2xSUFkwcFJaWE5xUVRaUFpURkdVbGQ1TmtWNlNDMXNiMmhSYWtvNVlVWTVXVEZZY0dKNlNrSkVRVXBFY0hKMFlXaG1hWEZMUkhodUxXcEVhR0ZMWDNWRVJsTTRNVUl4UVV4NGQyRlpVblZZZUZneGRrUmxkRlpqYnpNeWVuSm9NMnBuUVRaUFoyVkVObEpLWWpBNFZFRlpjM2RDZVZWZmRXVTNOV3BGYUdsWFJFeFJjWGxOVVZGdFVEUnBSMkUzYlVSMVVIRkVORTlMUVdoTGMxQjJXR1U0Vm1jNFFuZEJhRTV2YlVKRWNXeDNWbmxXVkhRd1JWUkVVak5MU1Zock5scGFjelJCYVVoU2FWZHFNelZYVlc5SWVsQjNaa2R5U1VGRWNtOXpValZ4TjNsaWExRm9WakEwUTJ4bVVHWnBaa1U1Y1U1eFdsTmlRazVPTFU0M1VHRjZXbmhaTVRKVVVGVlhUM3B5Y25oTFlsYzNPVVUwVDAxeE9VeDVaVlpHYkd0aVFXbEZXR3A2TmpSVU9UUmxibnA0WVVOTlNFODRiMGhxVTJReWJIUXphV3cwZFV0VE0xSktNVkZFVFZkdVVtdzVWbXBxTmtGelNIRkZOazlITTFJMGJ5MTRha1F0UVUxQ2NVOXVNSFJGTjFOQ2RXSlBWVlEyY1RBdGVHWmxWQzFyTjFGQmJITm1NelZxYTNWbFRuSTVOMGN3YTBkWlRXRjNaRk5PZUhKTllUbFVXV3BKZEhjMU5IaExjelZsTVZCS1lVaEhVR1I1ZW1GbGRucHpUelV0TUdGeU16Sm9WMjVsVWxGamRXczJWMEpZZFRCWllVSnVRWFpmZWxoRFEwbE1NRTExZFZOQmNUSlRVMGxyYlhGelRsRk1UbkJHYlZGdE1WQkRPSE0yWVcwMmJGUkZkakJoWDIxRVUwUklkM0JrWVhVNVFrdDBVVzFqYzE4MGREUXlNV3AzV25aS1VVMXVTM1Z0Vm01UmFrMVBRMjlEVVRSSlRsUk5TRlZHVG1sTllXbFRhVFI0VEV0SFJtZFJNV2N1UzJ3elRITTFXWGQ1ZUdoTk9HaFZRMHA1VlVkNmR3Lk83SjZvMXhpSmUwcF83bmFCNlB5Y3d1ZThlYVYwWDV3Y3hxX3lSeFVxQzQiLCJleHAiOjE3MTkwNDkwOTEsImlhdCI6MTcxOTA0ODc5MX0.OoJdNP24qKO9TrxjoLVW7GdZSVDjaFkQ2vE7pBGeF7Q","callbacks":[{"type":"PingOneProtectInitializeCallback","output":[{"name":"envId","value":"078561fb-3b4f-4506-a102-c99b78f94a48"},{"name":"consoleLogEnabled","value":false},{"name":"deviceAttributesToIgnore","value":[]},{"name":"customHost","value":""},{"name":"lazyMetadata","value":false},{"name":"behavioralDataCollection","value":true},{"name":"deviceKeyRsyncIntervals","value":14},{"name":"enableTrust","value":false},{"name":"disableTags","value":false},{"name":"disableHub","value":false}],"input":[{"name":"IDToken1clientError","value":""}]}]}}
2024-06-22 09:36:06,708 INFO =========================
2024-06-22 09:36:06,708 INFO HTTP response:
2024-06-22 09:36:06,708 INFO status:
2024-06-22 09:36:06,708 INFO 200 OK
2024-06-22 09:36:06,709 INFO headers:
2024-06-22 09:36:06,709 INFO x-frame-options: SAMEORIGIN
2024-06-22 09:36:06,709 INFO content-security-policy-report-only: frame-ancestors 'self'; script-src 'self' 'unsafe-eval' 'unsafe-inline'
2024-06-22 09:36:06,709 INFO x-content-type-options: nosniff
2024-06-22 09:36:06,709 INFO cache-control: private
2024-06-22 09:36:06,709 INFO content-api-version: resource=1.2
2024-06-22 09:36:06,709 INFO expires: 0
2024-06-22 09:36:06,709 INFO pragma: no-cache
2024-06-22 09:36:06,709 INFO set-cookie: amlbcookie=01; Path=/; Domain=openam-ema-perf-p1.forgeblocks.com; Secure; HttpOnly; SameSite=none
2024-06-22 09:36:06,709 INFO content-type: application/json
2024-06-22 09:36:06,709 INFO Content-Length: 3382
2024-06-22 09:36:06,709 INFO date: Sat, 22 Jun 2024 09:34:11 GMT
2024-06-22 09:36:06,709 INFO x-forgerock-transactionid: 3117201e-e670-4449-a37f-11dfcf384773
2024-06-22 09:36:06,709 INFO strict-transport-security: max-age=31536000; includeSubDomains; preload;
2024-06-22 09:36:06,709 INFO x-robots-tag: none
2024-06-22 09:36:06,709 INFO Via: 1.1 google
2024-06-22 09:36:06,709 INFO Alt-Svc: h3=":443"; ma=2592000
2024-06-22 09:36:06,709 INFO
2024-06-22 09:36:06,709 INFO body:
2024-06-22 09:36:06,709 INFO {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiI1MjQ3MmMyMS00ZjdjLTQ0YTAtYmQ4Zi05NjFkNmQ1NWIzOGUiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJqc2VudGQxZ21sczViZTY0bmcyZm5vZzllYSIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTUyTlVrd1FVeGpVVFF5WWtVM1pqRmtWV1JpWjFSM0xqbENTMkp2ZDBOWk1tMVhlVlEzVVZCTWEzY3RVVmRyT0RGU1NIRnZVSEp2WjNrd1QwZDBiMk5aTTA0NFFUSk5UVU5oTW5oemFGaDZNVFl3VjFwV1p6WXhaV3BLWkVaS1dtSmhla0ZNU1VwaWFGRXhiV2N3UzJGR2JrTnlRWGxxZVROTlYxUlVha3hqZGxOV1NsRmZkSEJCWHpVeFNHWlFPVGxuYTNnd2NIcEZlWEpWY3pVNWRGTXlkek14TkdSc1NGcDFNbFIzVXpkQkxVcFZOVEpxV2tOb2VsWmtkbEk0V0VveldsZEdZWFZQTmtoUlIwNXZTMUp6UlZadGRsVjBXVE4yZWxkb1EzY3pRVkl4YWpGaVRUVjRaM0JvTFVreVMyZHJXbFZ1YTI1VWVsZHNhRWw0ZUMxVVlWRmxOMWM0WW1KWU5HcEJhVk00VWxwVlEwTTRaMWN6YVZWUWFITkZOVFJ0YVZCdlprTlZiV3RuWWpWRFduSjVRbXR4UTI5clJGWm1jblJIZDJOYU5YSTVVR2hMV1RsT1JYSjFWMGRZZW1adWQxbE9kVzVwUkRKVlJqaFhTRGRFUXpCeGQyZHVaa2xtVURjeVpVaEVXa3RNV1U5d1NWSXdlVGd5WjNSUGNXRkdhazlYWkdveFRURllVVE5JTTNKaFJYcFJhekpqTjBSblFUQm5aRlZRUkRCQ1pUbDJZVUV0YkVaWmMyUlpTMUpKT1dZemVuSTBja05MWnkxclRGbDRTblJwWW1wdVZtNTBPV05IY25sTFJEUmtSMFZmT1hCTGFGbDBXa2QwVDJVM1NrOWhVM3BKTlZCWlVXdG9MV3BKU25GVmNYbDBZM0Z3WW04MVNFcFNWRms0ZEc1ZlRHbFhRemxqVW5oWU9XbHhSME54V0ZOalIwSm1jRms1YkZCbWEyMXJXbHAwUmtGbVZqZHBPRWRRVDBRM09VcGtkbFp4YkRVNE9IaHdRelE1YkdsRlpubDJhV294VVhoUGQzazBZVE5rZFRRMk9FTjRWRXBwVmxFeE5GQlpVRWRhWkdFd04ycEJlRUphZVVaR1YxWkRXRkZ3WkVwVmJqZzBaMVZEUzJKMFYwdFBNRFF4UkdFeVJqRkhTSEY1VFhkVk1FdzRVbDloTmtOQlVFSnhTVzVLU1d0aE5FbDBXVXBKVEdkdFdrbGxlSE5xVTAxbWRXUnlkMGM1YkdKcVRGcFZTRkZPTjFoM1RHMWxWV0prTmpJMmNISnFNbHBuUVVaS1pqWkdabUZtVERCdlRFVkNPRkpJU1VSSU9DMXlha0pZWW1aU1VERTBYMmw1UVdZeVYzRjBRa1p4VUZSUVQwOTVSRnB6VjJWVFdGcGxWME14YlRsbFNURmhZekZMWWtwTWFHY3dNWE5IYXpZeVVHbGtRbU55Vms4MGQwSmFObVJGVkd4UWNYbEhRVVZGZUZGRWFtUnVjVlF5VkZSVGJEQjVjMnBmYUdwdFJGUXhZaTA1VkhKTGRHSjNkbkI0V0RSd1pUQlRVRzFzYVZwaVVFNDRWa0ZaWkZaeVVGZGpOV05QU1cxRldWUmZSWGM1V0hRd2QxZElVbFZzY0ZOTVJtMDROVXBaV0hJMmNVZE9RVGxZYm5SQlJHbHJOMGhZZVdob1VrNUtSRXBFUzB4Rk9IWmZXRkExWms5UVdGQnFSRll4YldVemVWcHRVRTFrZG1Vd05GVXdYMHhvV25oUFdUZzViVlF4UVRSUVducFZXV2cxYkZWTlpEVm9jMWR3Y0dsTGRHaDZiVmxwWWtSWWNESjJSMnN3V1ZKcFFtNDNRbWMwU1dSTVp6VXlZM2hZZUdjME1YTTJjMHRxUmpseGFtOXRlV3hVZDBoRGJtRnZPRlZMUzBweE4za3piVnBMTFRWbk9HUlhhVFo1TUVsV1lXbEtSakJyU2pnd2RrbEhUa0ZoUlRsblRFTk9jRnBTYkZReFRYUlhWM1pSZGtOYVpsaDFOVXRWZFU1aVRURkpTMVJMY1VjM2NWbFhiMnhwWldOVFUxQkliblpuVEVkWmJVeE1jMDR3WlZKWFVWZzBlREEwYlZSc01uTnpOazlFTmtSZlJXZDNOemRSUmpSblREVkhlWEY1TlRndUxYUnVaWEY0YUVwQ09FOTVOM2hLZGs5VGJYQjJkdy5zRXloUk9NU0I5YUs4TV9UanhkeGVmR0NYM2phZzVQLTZiMEtWNFBYN0VFIiwiZXhwIjoxNzE5MDQ5MDkxLCJpYXQiOjE3MTkwNDg3OTF9.Ti98211qJkIqSsZGsmYd4ekoOrrI_Aa0gXI6l7QB-IE","callbacks":[{"type":"NameCallback","output":[{"name":"prompt","value":"User Name"}],"input":[{"name":"IDToken1","value":""}],"_id":2},{"type":"PasswordCallback","output":[{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken2","value":""}],"_id":3}],"header":"Sign In","description":"New here? Create an account
Forgot username? Forgot password?"}
2024-06-22 09:36:06,709 INFO <<<<<<<<<<<<<<<<<<<<<<<<<
2024-06-22 09:36:06,709 INFO
2024-06-22 09:36:06,709 INFO ================================================================================
2024-06-22 09:36:06,709 INFO 2024-06-22 10:34:12 3635s elapsed
2024-06-22 09:36:06,709 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 09:36:06,709 INFO > Global (OK=4196452 KO=79 )
2024-06-22 09:36:06,710 INFO > protectLoginInitiate (OK=994712 KO=5 )
2024-06-22 09:36:06,710 INFO > PingOneProtectInitializeCallback (OK=994705 KO=7 )
2024-06-22 09:36:06,710 INFO > restLoginUsernamePasswordCallback (OK=994708 KO=4 )
2024-06-22 09:36:06,710 INFO > PingOneProtectEvaluationCallback (OK=994682 KO=30 )
2024-06-22 09:36:06,710 INFO > restloginProgressiveProfileCallback (OK=217645 KO=33 )
2024-06-22 09:36:06,710 INFO ---- Errors --------------------------------------------------------------------
2024-06-22 09:36:06,710 INFO > jsonPath($.tokenId).find.exists, found nothing 32 (32.32%)
2024-06-22 09:36:06,710 INFO > status.find.is(200), but actually found 401 29 (29.29%)
2024-06-22 09:36:06,710 INFO > status.find.is(200), but actually found 502 15 (15.15%)
2024-06-22 09:36:06,710 INFO > PingOneProtectInitializeCallback: Failed to build request: No 5 ( 5.05%)
2024-06-22 09:36:06,710 INFO attribute named 'callbacks' is defined
2024-06-22 09:36:06,710 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 5 ( 5.05%)
2024-06-22 09:36:06,710 INFO attribute named 'callbacks' is defined
2024-06-22 09:36:06,710 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 5 ( 5.05%)
2024-06-22 09:36:06,710 INFO attribute named 'callbacks' is defined
2024-06-22 09:36:06,710 INFO > restloginProgressiveProfileCallback: Failed to build request: 5 ( 5.05%)
2024-06-22 09:36:06,710 INFO No attribute named 'callbacks' is defined
2024-06-22 09:36:06,710 INFO > Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.5 3 ( 3.03%)
2024-06-22 09:36:06,710 INFO 9.5:443 after 60000 ms
2024-06-22 09:36:06,710 INFO
2024-06-22 09:36:06,710 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-22 09:36:06,710 INFO [##########################################################################]100%
2024-06-22 09:36:06,710 INFO waiting: 0 / active: 0 / done: 994717
2024-06-22 09:36:06,710 INFO ================================================================================
2024-06-22 09:36:06,710 INFO
2024-06-22 09:36:06,710 INFO Simulation com.forgerock.gatling.Loadtest completed in 3635 seconds
2024-06-22 09:36:06,710 INFO
2024-06-22 09:36:06,710 INFO ----------------------
2024-06-22 09:36:06,710 INFO [22/Jun/2024 10:34:12] Create tar file login-tree-275.tar.gz
2024-06-22 09:36:06,710 INFO ----------------------
2024-06-22 09:36:06,710 INFO RESULTS_FOLDER=/results/gatling/
2024-06-22 09:36:06,710 INFO RESULT_FOLDER=login-tree-275
2024-06-22 09:36:06,710 INFO SIMULATION_PATH=login-tree-275/loadtest-20240622083335584
2024-06-22 09:36:06,711 INFO PWD=/results/gatling/login-tree-275/loadtest-20240622083335584
2024-06-22 09:36:06,711 INFO [cmd] tar -czvf /results/gatling//login-tree-275.tar.gz --exclude=simulation.log *
2024-06-22 09:36:06,711 INFO /results/gatling
2024-06-22 09:36:06,711 INFO
2024-06-22 09:36:06,711 INFO ----------------------
2024-06-22 09:36:06,711 INFO [22/Jun/2024 10:34:12] Update /results/gatling//latest
2024-06-22 09:36:06,711 INFO ----------------------
2024-06-22 09:36:06,711 INFO
2024-06-22 09:36:06,711 INFO ----------------------
2024-06-22 09:36:06,711 INFO [22/Jun/2024 10:34:12] Update /results/gatling//latest.tar.gz
2024-06-22 09:36:06,711 INFO ----------------------
2024-06-22 09:36:06,711 INFO
________________________________________________________________________________
[2024-06-22 09:36:06] login-tree-275 show_stderr : Display stderr order
________________________________________________________________________________
2024-06-22 09:36:06,711 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/06_login-tree-275/stderr.txt is small (0 lines), printing all lines:
2024-06-22 09:36:06,712 DEBUG ----- output -----
2024-06-22 09:36:06,712 DEBUG
2024-06-22 09:36:06,712 INFO
________________________________________________________________________________
[2024-06-22 09:36:06] login-tree-275 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-22 09:36:06] login-tree-275 print_overseer_logs : Print overseer logs from the K8s pod
________________________________________________________________________________
Task result is INIT no need to display logs.
________________________________________________________________________________
[2024-06-22 09:36:06] login-tree-275 post : Post
________________________________________________________________________________
Setting result to PASS
Task has been successfully stopped