--Task--
name: login-tree-330
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-300']
preceding_task: login-tree-300
options: {'testCase': 'idc.loginProtect', 'testMode': 'open', 'throughput': 330, '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-23 09:59:19] login-tree-330 pre : N/A
________________________________________________________________________________
2024-06-23 09:59:19 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds)
2024-06-23 09:59:19 - INFO: timeout was calculated based on simulation duration parameter
Pod log file : /results/orders/login-tree-330/stdout.txt
Pod log : /results/orders/login-tree-330
2024-06-23 09:59:19,053 INFO
2024-06-23 09:59:19,053 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-23 09:59:19,053 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 09:59:19,356 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 09:59:19,356 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:19,356 DEBUG --- stdout ---
2024-06-23 09:59:19,356 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 09:59:19,356 DEBUG --- stderr ---
2024-06-23 09:59:19,356 DEBUG
________________________________________________________________________________
[2024-06-23 09:59:19] login-tree-330 print_command : Display the command to be forwarded to overseer as an order
________________________________________________________________________________
2024-06-23 09:59:19,357 INFO
2024-06-23 09:59:19,357 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-23 09:59:19,357 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 09:59:19,703 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 09:59:19,704 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:19,704 DEBUG --- stdout ---
2024-06-23 09:59:19,704 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 09:59:19,704 DEBUG --- stderr ---
2024-06-23 09:59:19,704 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-330 --extra-run-jvm-options "-Xmx4G" --no-reports
________________________________________________________________________________
[2024-06-23 09:59:19] login-tree-330 create_order : Create order for overseer with the command to run
________________________________________________________________________________
2024-06-23 09:59:19,704 INFO
2024-06-23 09:59:19,705 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-23 09:59:19,705 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 09:59:20,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 09:59:20,030 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:20,030 DEBUG --- stdout ---
2024-06-23 09:59:20,030 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 09:59:20,030 DEBUG --- stderr ---
2024-06-23 09:59:20,030 DEBUG
Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/order.json
{
"name": "login-tree-330",
"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-330 --extra-run-jvm-options \"-Xmx4G\" --no-reports",
"out-dir": "/results/orders/login-tree-330",
"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=330 -Duser_password=Pa_ssw0rd"
}
}
________________________________________________________________________________
[2024-06-23 09:59:20] login-tree-330 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-23 09:59:20,033 INFO
2024-06-23 09:59:20,033 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-06-23 09:59:20,033 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2024-06-23 09:59:20,278 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 09:59:20,279 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:20,279 DEBUG --- stdout ---
2024-06-23 09:59:20,279 DEBUG WAITING
2024-06-23 09:59:20,279 DEBUG --- stderr ---
2024-06-23 09:59:20,279 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-23 09:59:20] login-tree-330 start_order : Send the order to the overseer pod
________________________________________________________________________________
2024-06-23 09:59:20,280 INFO
2024-06-23 09:59:20,280 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-23 09:59:20,280 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 09:59:20,591 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 09:59:20,591 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:20,591 DEBUG --- stdout ---
2024-06-23 09:59:20,592 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 09:59:20,592 DEBUG --- stderr ---
2024-06-23 09:59:20,592 DEBUG
2024-06-23 09:59:20,592 INFO
2024-06-23 09:59:20,592 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/07_login-tree-330/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json
2024-06-23 09:59:20,592 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 09:59:21,959 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:21,959 DEBUG --- stdout ---
2024-06-23 09:59:21,959 DEBUG
2024-06-23 09:59:21,959 DEBUG --- stderr ---
2024-06-23 09:59:21,959 DEBUG
Order has been successfully forwarded to overseer
2024-06-23 09:59:21 - INFO: Check if Gatling simulation started successfully
2024-06-23 09:59:21,960 INFO
2024-06-23 09:59:21,960 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-23 09:59:21,960 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 09:59:22,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 09:59:22,259 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:22,259 DEBUG --- stdout ---
2024-06-23 09:59:22,259 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 09:59:22,259 DEBUG --- stderr ---
2024-06-23 09:59:22,259 DEBUG
2024-06-23 09:59:22,259 INFO
2024-06-23 09:59:22,259 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-330/stdout.txt | grep "started..."
2024-06-23 09:59:22,260 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2024-06-23 09:59:23,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry
2024-06-23 09:59:28,761 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found
2024-06-23 09:59:28,761 INFO [loop_until]: OK (rc = 0)
2024-06-23 09:59:28,761 DEBUG --- stdout ---
2024-06-23 09:59:28,762 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-23 09:59:28,762 DEBUG --- stderr ---
2024-06-23 09:59:28,762 DEBUG
________________________________________________________________________________
[2024-06-23 09:59:28] login-tree-330 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, retry
Order is running since 8m 01s, retry
Order is running since 10m 01s, retry
Order is running since 12m 01s, retry
Order is running since 14m 02s, retry
Order is running since 16m 02s, retry
Order is running since 18m 02s, retry
Order is running since 20m 03s, retry
Order is running since 22m 03s, retry
Order is running since 24m 03s, retry
Order is running since 26m 04s, retry
Order is running since 28m 04s, retry
Order is running since 30m 04s, retry
Order is running since 32m 05s, retry
Order is running since 34m 05s, retry
Order is running since 36m 05s, retry
Order is running since 38m 06s, retry
Order is running since 40m 06s, retry
Order is running since 42m 06s, retry
Order is running since 44m 07s, retry
Order is running since 46m 07s, retry
Order is running since 48m 07s, retry
Order is running since 50m 08s, retry
Order is running since 52m 08s, retry
Order is running since 54m 08s, retry
Order is running since 56m 09s, retry
Order is running since 58m 09s, retry
Order is running since 1h, retry
PASS : Order stopped by itself after 1h 02m and before timeout (1h 12m)
________________________________________________________________________________
[2024-06-23 11:01:38] login-tree-330 stop_order : Stop the order on overseer pod
________________________________________________________________________________
2024-06-23 11:01:38,932 INFO
2024-06-23 11:01:38,932 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-23 11:01:38,933 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:39,311 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:39,311 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:39,311 DEBUG --- stdout ---
2024-06-23 11:01:39,311 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:39,311 DEBUG --- stderr ---
2024-06-23 11:01:39,311 DEBUG
2024-06-23 11:01:39,312 INFO
2024-06-23 11:01:39,312 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-23 11:01:39,312 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:01:40,127 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:40,127 DEBUG --- stdout ---
2024-06-23 11:01:40,127 DEBUG
2024-06-23 11:01:40,127 DEBUG --- stderr ---
2024-06-23 11:01:40,127 DEBUG
Order ran during 1h 02m
________________________________________________________________________________
[2024-06-23 11:01:40] login-tree-330 download_logs : Download individual order files
________________________________________________________________________________
2024-06-23 11:01:40 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/order.json
2024-06-23 11:01:40,129 INFO
2024-06-23 11:01:40,129 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-23 11:01:40,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:40,444 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:40,444 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:40,444 DEBUG --- stdout ---
2024-06-23 11:01:40,445 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:40,445 DEBUG --- stderr ---
2024-06-23 11:01:40,445 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:01:40,445 INFO
2024-06-23 11:01:40,445 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-23 11:01:40,445 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:40,770 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:40,770 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:40,770 DEBUG --- stdout ---
2024-06-23 11:01:40,770 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:40,770 DEBUG --- stderr ---
2024-06-23 11:01:40,770 DEBUG
2024-06-23 11:01:40,771 INFO
2024-06-23 11:01:40,771 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-330/rc.txt" ]; then echo "/results/orders/login-tree-330/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-330/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/rc.txt file found"
2024-06-23 11:01:40,771 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:01:41,587 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:01:41,588 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:41,588 DEBUG --- stdout ---
2024-06-23 11:01:41,588 DEBUG /results/orders/login-tree-330/rc.txt file found
2024-06-23 11:01:41,588 DEBUG --- stderr ---
2024-06-23 11:01:41,588 DEBUG
2024-06-23 11:01:41,588 INFO
2024-06-23 11:01:41,588 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-23 11:01:41,588 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:41,890 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:41,891 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:41,891 DEBUG --- stdout ---
2024-06-23 11:01:41,891 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:41,891 DEBUG --- stderr ---
2024-06-23 11:01:41,891 DEBUG
2024-06-23 11:01:41,891 INFO
2024-06-23 11:01:41,891 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-330/rc.txt
2024-06-23 11:01:42,700 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:01:42,700 DEBUG --- stdout ---
2024-06-23 11:01:42,701 DEBUG 4 /results/orders/login-tree-330/rc.txt
2024-06-23 11:01:42,701 DEBUG --- stderr ---
2024-06-23 11:01:42,701 DEBUG
2024-06-23 11:01:42,701 INFO
- Download file (size 4k)
2024-06-23 11:01:42,701 INFO
2024-06-23 11:01:42,701 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-23 11:01:42,701 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:43,021 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:43,022 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:43,022 DEBUG --- stdout ---
2024-06-23 11:01:43,022 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:43,022 DEBUG --- stderr ---
2024-06-23 11:01:43,022 DEBUG
source : /results/orders/login-tree-330/rc.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/rc.txt
2024-06-23 11:01:43,022 INFO
2024-06-23 11:01:43,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-23 11:01:43,023 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:43,338 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:43,338 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:43,338 DEBUG --- stdout ---
2024-06-23 11:01:43,338 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:43,338 DEBUG --- stderr ---
2024-06-23 11:01:43,338 DEBUG
2024-06-23 11:01:43,339 INFO
2024-06-23 11:01:43,339 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-330/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/rc.txt
2024-06-23 11:01:43,339 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:01:44,144 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:44,145 DEBUG --- stdout ---
2024-06-23 11:01:44,145 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:01:44,145 DEBUG --- stderr ---
2024-06-23 11:01:44,145 DEBUG
2024-06-23 11:01:44,145 INFO
2024-06-23 11:01:44,145 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-23 11:01:44,145 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:44,465 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:44,465 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:44,466 DEBUG --- stdout ---
2024-06-23 11:01:44,466 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:44,466 DEBUG --- stderr ---
2024-06-23 11:01:44,466 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:01:44,466 INFO
2024-06-23 11:01:44,466 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-23 11:01:44,466 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:44,789 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:44,789 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:44,789 DEBUG --- stdout ---
2024-06-23 11:01:44,789 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:44,789 DEBUG --- stderr ---
2024-06-23 11:01:44,789 DEBUG
2024-06-23 11:01:44,790 INFO
2024-06-23 11:01:44,790 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-330/stderr.txt" ]; then echo "/results/orders/login-tree-330/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-330/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/stderr.txt file found"
2024-06-23 11:01:44,790 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:01:45,610 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:01:45,611 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:45,611 DEBUG --- stdout ---
2024-06-23 11:01:45,611 DEBUG /results/orders/login-tree-330/stderr.txt file found
2024-06-23 11:01:45,611 DEBUG --- stderr ---
2024-06-23 11:01:45,611 DEBUG
2024-06-23 11:01:45,611 INFO
2024-06-23 11:01:45,612 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-23 11:01:45,612 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:46,004 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:46,004 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:46,004 DEBUG --- stdout ---
2024-06-23 11:01:46,005 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:46,005 DEBUG --- stderr ---
2024-06-23 11:01:46,005 DEBUG
2024-06-23 11:01:46,005 INFO
2024-06-23 11:01:46,005 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-330/stderr.txt
2024-06-23 11:01:46,832 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:01:46,832 DEBUG --- stdout ---
2024-06-23 11:01:46,832 DEBUG 0 /results/orders/login-tree-330/stderr.txt
2024-06-23 11:01:46,832 DEBUG --- stderr ---
2024-06-23 11:01:46,832 DEBUG
2024-06-23 11:01:46,832 INFO
- Download file (size 0k)
2024-06-23 11:01:46,833 INFO
2024-06-23 11:01:46,833 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-23 11:01:46,833 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:47,140 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:47,140 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:47,140 DEBUG --- stdout ---
2024-06-23 11:01:47,141 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:47,141 DEBUG --- stderr ---
2024-06-23 11:01:47,141 DEBUG
source : /results/orders/login-tree-330/stderr.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stderr.txt
2024-06-23 11:01:47,141 INFO
2024-06-23 11:01:47,141 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-23 11:01:47,141 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:47,450 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:47,451 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:47,451 DEBUG --- stdout ---
2024-06-23 11:01:47,451 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:47,451 DEBUG --- stderr ---
2024-06-23 11:01:47,451 DEBUG
2024-06-23 11:01:47,451 INFO
2024-06-23 11:01:47,451 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-330/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stderr.txt
2024-06-23 11:01:47,451 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:01:48,238 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:48,239 DEBUG --- stdout ---
2024-06-23 11:01:48,239 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:01:48,239 DEBUG --- stderr ---
2024-06-23 11:01:48,239 DEBUG
2024-06-23 11:01:48,240 INFO
2024-06-23 11:01:48,240 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-23 11:01:48,240 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:48,561 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:48,561 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:48,561 DEBUG --- stdout ---
2024-06-23 11:01:48,561 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:48,561 DEBUG --- stderr ---
2024-06-23 11:01:48,561 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:01:48,562 INFO
2024-06-23 11:01:48,562 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-23 11:01:48,562 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:48,882 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:48,882 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:48,882 DEBUG --- stdout ---
2024-06-23 11:01:48,882 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:48,882 DEBUG --- stderr ---
2024-06-23 11:01:48,882 DEBUG
2024-06-23 11:01:48,883 INFO
2024-06-23 11:01:48,883 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-330/stdout.txt" ]; then echo "/results/orders/login-tree-330/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-330/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/stdout.txt file found"
2024-06-23 11:01:48,883 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:01:49,684 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:01:49,684 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:49,684 DEBUG --- stdout ---
2024-06-23 11:01:49,684 DEBUG /results/orders/login-tree-330/stdout.txt file found
2024-06-23 11:01:49,685 DEBUG --- stderr ---
2024-06-23 11:01:49,685 DEBUG
2024-06-23 11:01:49,685 INFO
2024-06-23 11:01:49,685 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-23 11:01:49,685 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:50,005 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:50,005 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:50,005 DEBUG --- stdout ---
2024-06-23 11:01:50,005 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:50,005 DEBUG --- stderr ---
2024-06-23 11:01:50,005 DEBUG
2024-06-23 11:01:50,005 INFO
2024-06-23 11:01:50,006 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-330/stdout.txt
2024-06-23 11:01:50,826 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:01:50,826 DEBUG --- stdout ---
2024-06-23 11:01:50,826 DEBUG 1475800 /results/orders/login-tree-330/stdout.txt
2024-06-23 11:01:50,827 DEBUG --- stderr ---
2024-06-23 11:01:50,827 DEBUG
2024-06-23 11:01:50,827 INFO
- Download file (size 1475800k)
2024-06-23 11:01:50,827 INFO
2024-06-23 11:01:50,827 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-23 11:01:50,827 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:51,129 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:51,129 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:51,129 DEBUG --- stdout ---
2024-06-23 11:01:51,129 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:51,129 DEBUG --- stderr ---
2024-06-23 11:01:51,129 DEBUG
source : /results/orders/login-tree-330/stdout.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stdout.txt
2024-06-23 11:01:51,130 INFO
2024-06-23 11:01:51,130 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-23 11:01:51,130 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:01:51,433 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:01:51,433 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:01:51,433 DEBUG --- stdout ---
2024-06-23 11:01:51,433 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:01:51,433 DEBUG --- stderr ---
2024-06-23 11:01:51,433 DEBUG
2024-06-23 11:01:51,434 INFO
2024-06-23 11:01:51,434 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-330/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stdout.txt
2024-06-23 11:01:51,434 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:02:26,748 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:26,748 DEBUG --- stdout ---
2024-06-23 11:02:26,748 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:02:26,748 DEBUG --- stderr ---
2024-06-23 11:02:26,748 DEBUG
________________________________________________________________________________
[2024-06-23 11:02:26] login-tree-330 show_cmd : Display order command
________________________________________________________________________________
2024-06-23 11:02:26,749 INFO
2024-06-23 11:02:26,749 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-23 11:02:26,749 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:02:27,080 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:02:27,081 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:27,081 DEBUG --- stdout ---
2024-06-23 11:02:27,081 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:02:27,081 DEBUG --- stderr ---
2024-06-23 11:02:27,081 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-23 11:02:27,081 INFO
2024-06-23 11:02:27,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-23 11:02:27,081 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:02:27,398 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:02:27,398 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:27,398 DEBUG --- stdout ---
2024-06-23 11:02:27,398 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:02:27,398 DEBUG --- stderr ---
2024-06-23 11:02:27,398 DEBUG
2024-06-23 11:02:27,399 INFO
2024-06-23 11:02:27,399 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-330/cmd.txt" ]; then echo "/results/orders/login-tree-330/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-330/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-330/cmd.txt file found"
2024-06-23 11:02:27,399 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-23 11:02:28,222 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-23 11:02:28,222 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:28,222 DEBUG --- stdout ---
2024-06-23 11:02:28,222 DEBUG /results/orders/login-tree-330/cmd.txt file found
2024-06-23 11:02:28,222 DEBUG --- stderr ---
2024-06-23 11:02:28,222 DEBUG
2024-06-23 11:02:28,222 INFO
2024-06-23 11:02:28,222 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-23 11:02:28,223 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:02:28,535 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:02:28,535 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:28,535 DEBUG --- stdout ---
2024-06-23 11:02:28,535 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:02:28,535 DEBUG --- stderr ---
2024-06-23 11:02:28,535 DEBUG
2024-06-23 11:02:28,535 INFO
2024-06-23 11:02:28,536 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-330/cmd.txt
2024-06-23 11:02:29,396 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-23 11:02:29,397 DEBUG --- stdout ---
2024-06-23 11:02:29,398 DEBUG 4 /results/orders/login-tree-330/cmd.txt
2024-06-23 11:02:29,398 DEBUG --- stderr ---
2024-06-23 11:02:29,398 DEBUG
2024-06-23 11:02:29,398 INFO
- Download file (size 4k)
2024-06-23 11:02:29,399 INFO
2024-06-23 11:02:29,399 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-06-23 11:02:29,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:02:29,719 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:02:29,719 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:29,719 DEBUG --- stdout ---
2024-06-23 11:02:29,720 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:02:29,720 DEBUG --- stderr ---
2024-06-23 11:02:29,720 DEBUG
source : /results/orders/login-tree-330/cmd.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/cmd.txt
2024-06-23 11:02:29,720 INFO
2024-06-23 11:02:29,720 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-23 11:02:29,720 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-23 11:02:30,047 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-23 11:02:30,047 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:30,047 DEBUG --- stdout ---
2024-06-23 11:02:30,047 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-23 11:02:30,047 DEBUG --- stderr ---
2024-06-23 11:02:30,047 DEBUG
2024-06-23 11:02:30,048 INFO
2024-06-23 11:02:30,048 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-330/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/cmd.txt
2024-06-23 11:02:30,048 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-23 11:02:30,791 INFO [loop_until]: OK (rc = 0)
2024-06-23 11:02:30,791 DEBUG --- stdout ---
2024-06-23 11:02:30,791 DEBUG tar: Removing leading `/' from member names
2024-06-23 11:02:30,791 DEBUG --- stderr ---
2024-06-23 11:02:30,791 DEBUG
2024-06-23 11:02:30,791 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/cmd.txt is small (1 lines), printing all lines:
2024-06-23 11:02:30,792 DEBUG ----- output -----
2024-06-23 11:02:30,792 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-330 --extra-run-jvm-options "-Xmx4G" --no-reports
2024-06-23 11:02:30,792 INFO
________________________________________________________________________________
[2024-06-23 11:02:30] login-tree-330 show_rc : Display rc order
________________________________________________________________________________
2024-06-23 11:02:30,792 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/rc.txt is small (1 lines), printing all lines:
2024-06-23 11:02:30,792 DEBUG ----- output -----
2024-06-23 11:02:30,792 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
2024-06-23 11:02:30,792 INFO
________________________________________________________________________________
[2024-06-23 11:02:30] login-tree-330 show_stdout : Display stdout order
________________________________________________________________________________
2024-06-23 11:02:32,882 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/07_login-tree-330/stdout.txt is 8580905 lines):
2024-06-23 11:02:32,882 DEBUG ----- output -----
2024-06-23 11:02:32,883 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling
2024-06-23 11:02:32,883 INFO Gatling 3.11.4 is available! (you're using 3.9.5)
2024-06-23 11:02:32,883 INFO Simulation com.forgerock.gatling.Loadtest started...
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,883 INFO ================================================================================
2024-06-23 11:02:32,883 INFO 2024-06-23 10:59:31 5s elapsed
2024-06-23 11:02:32,883 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:32,883 INFO > Global (OK=536 KO=0 )
2024-06-23 11:02:32,883 INFO > protectLoginInitiate (OK=136 KO=0 )
2024-06-23 11:02:32,883 INFO > PingOneProtectInitializeCallback (OK=133 KO=0 )
2024-06-23 11:02:32,883 INFO > restLoginUsernamePasswordCallback (OK=130 KO=0 )
2024-06-23 11:02:32,883 INFO > PingOneProtectEvaluationCallback (OK=117 KO=0 )
2024-06-23 11:02:32,883 INFO > restloginProgressiveProfileCallback (OK=20 KO=0 )
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,883 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:32,883 INFO [- ] 0%
2024-06-23 11:02:32,883 INFO waiting: 1192689 / active: 24 / done: 114
2024-06-23 11:02:32,883 INFO ================================================================================
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,883 INFO ================================================================================
2024-06-23 11:02:32,883 INFO 2024-06-23 10:59:36 10s elapsed
2024-06-23 11:02:32,883 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:32,883 INFO > Global (OK=2224 KO=0 )
2024-06-23 11:02:32,883 INFO > protectLoginInitiate (OK=547 KO=0 )
2024-06-23 11:02:32,883 INFO > PingOneProtectInitializeCallback (OK=541 KO=0 )
2024-06-23 11:02:32,883 INFO > restLoginUsernamePasswordCallback (OK=535 KO=0 )
2024-06-23 11:02:32,883 INFO > PingOneProtectEvaluationCallback (OK=510 KO=0 )
2024-06-23 11:02:32,883 INFO > restloginProgressiveProfileCallback (OK=91 KO=0 )
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,883 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:32,883 INFO [- ] 0%
2024-06-23 11:02:32,883 INFO waiting: 1192275 / active: 42 / done: 510
2024-06-23 11:02:32,883 INFO ================================================================================
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,883 INFO
2024-06-23 11:02:32,884 INFO ================================================================================
2024-06-23 11:02:32,884 INFO 2024-06-23 10:59:41 15s elapsed
2024-06-23 11:02:32,884 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:32,884 INFO > Global (OK=5045 KO=0 )
2024-06-23 11:02:32,884 INFO > protectLoginInitiate (OK=1231 KO=0 )
2024-06-23 11:02:32,884 INFO > PingOneProtectInitializeCallback (OK=1223 KO=0 )
2024-06-23 11:02:32,884 INFO > restLoginUsernamePasswordCallback (OK=1212 KO=0 )
2024-06-23 11:02:32,884 INFO > PingOneProtectEvaluationCallback (OK=1169 KO=0 )
2024-06-23 11:02:32,884 INFO > restloginProgressiveProfileCallback (OK=210 KO=0 )
2024-06-23 11:02:32,884 INFO
2024-06-23 11:02:32,884 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:32,884 INFO [- ] 0%
2024-06-23 11:02:32,884 INFO waiting: 1191587 / active: 78 / done: 1162
2024-06-23 11:02:32,884 INFO ================================================================================
2024-06-23 11:02:32,884 INFO
2024-06-23 11:02:32,884 INFO
2024-06-23 11:02:32,884 INFO ================================================================================
2024-06-23 11:02:32,884 INFO 2024-06-23 10:59:46 20s elapsed
2024-06-23 11:02:32,884 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:32,884 INFO > Global (OK=9016 KO=0 )
2024-06-23 11:02:32,884 INFO > protectLoginInitiate (OK=2192 KO=0 )
2024-06-23 11:02:32,884 INFO > PingOneProtectInitializeCallback (OK=2181 KO=0 )
2024-06-23 11:02:32,884 INFO > restLoginUsernamePasswordCallback (OK=2166 KO=0 )
2024-06-23 11:02:32,884 INFO > PingOneProtectEvaluationCallback (OK=2100 KO=0 )
2024-06-23 11:02:32,884 INFO > restloginProgressiveProfileCallback (OK=377 KO=0 )
2024-06-23 11:02:32,884 INFO
2024-06-23 11:02:32,884 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:32,884 INFO [- ] 0%
2024-06-23 11:02:32,884 INFO waiting: 1190624 / active: 109 / done: 2094
2024-06-23 11:02:32,884 INFO ================================================================================
2024-06-23 11:02:32,884 INFO
2024-06-23 11:02:32,884 INFO
2024-06-23 11:02:32,884 INFO ================================================================================
2024-06-23 11:02:32,884 INFO 2024-06-23 10:59:51 25s elapsed
2024-06-23 11:02:32,884 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:32,884 INFO > Global (OK=14039 KO=0 )
2024-06-23 11:02:32,884 INFO > protectLoginInitiate (OK=3422 KO=0 )
2024-06-23 11:02:32,884 INFO > PingOneProtectInitializeCallback (OK=3407 KO=0 )
2024-06-23 11:02:32,884 INFO > restLoginUsernamePasswordCallback (OK=3383 KO=0 )
2024-06-23 11:02:32,884 INFO > PingOneProtectEvaluationCallback (OK=3279 KO=0 )
2024-06-23 11:02:32,884 INFO > restloginProgressiveProfileCallback (OK=548 KO=0 )
2024-06-23 11:02:32,885 INFO
2024-06-23 11:02:32,885 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:32,885 INFO [- ] 0%
2024-06-23 11:02:32,885 INFO waiting: 1189386 / active: 170 / done: 3271
2024-06-23 11:02:32,885 INFO ================================================================================
2024-06-23 11:02:32,885 INFO
2024-06-23 11:02:32,885 INFO
2024-06-23 11:02:32,885 INFO ================================================================================
2024-06-23 11:02:32,885 INFO 2024-06-23 10:59:56 30s elapsed
2024-06-23 11:02:32,885 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:32,885 INFO > Global (OK=20150 KO=0 )
2024-06-23 11:02:32,885 INFO > protectLoginInitiate (OK=4929 KO=0 )
2024-06-23 11:02:32,885 INFO > PingOneProtectInitializeCallback (OK=4908 KO=0 )
2024-06-23 11:02:32,885 INFO > restLoginUsernamePasswordCallback (OK=4855 KO=0 )
2024-06-23 11:02:32,885 INFO > PingOneProtectEvaluationCallback (OK=4685 KO=0 )
2024-06-23 11:02:32,885 INFO > restloginProgressiveProfileCallback (OK=773 KO=0 )
2024-06-23 11:02:32,885 INFO
2024-06-23 11:02:32,885 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:32,885 INFO [- ] 0%
2024-06-23 11:02:32,885 INFO waiting: 1187870 / active: 298 / done: 4659
2024-06-23 11:02:32,885 INFO ================================================================================
2024-06-23 11:02:32,885 INFO
2024-06-23 11:02:32,885 INFO
2024-06-23 11:02:32,885 INFO [...]
2024-06-23 11:02:36,061 INFO restLoginUsernamePasswordCallback: KO Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.59.5:443 after 60000 ms
2024-06-23 11:02:36,062 INFO =========================
2024-06-23 11:02:36,062 INFO Session:
2024-06-23 11:02:36,062 INFO Session(idc.loginProtect,1191386,HashMap(gatling.http.cache.baseUrl -> https://openam-ema-perf-p1.forgeblocks.com:443, username -> user.309873, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@36eb191b, fake-user-agent -> Mozilla/5.0 (Linux; Android 6.0; Nexus 5 Build/MRA58N) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.85 Mobile Safari/537.36, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiI1ZmZiYTNhMy1mNmZhLTQ1NTctYmY4Ni1kMjU1MDI3ZjhlOGEiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJ0dDlvcDM5dHAxaGoxcDJpaGNqc2MyYmVkaiIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTVVZEY5MWFIcHhhVGxaTUY5dVZraHpSbEJKV1RsQkxsRnNaMDV1VkRkbVZEWnJXVmgzV201bGJXSnBhM0JsZERKUGRrbE1iemR3YjBWRlkydE9jMUV5U1hsWE5GWTVPR0psWkZWUVdVZHRjVlo0YW5ZM1RuWnNkVFpmUkUxbE5EVk9PR1Y0TkVOQ2NGUjRiVEoxYkhwTGJXSnBUMG8zYVhKME5sUm5NR1JyUW5kME1rWmZRVzFOUjNjMlJGSTBZME5JZW1ZeE5FaGZWMmhRVVhsSFlYaDZkR2sxY3pSUWNYaHFTM1IzV21Ob1N6ZHJNbFZXTW1GSGVVVndiRjlsV0daaVoyOUZTMkpOYmkxbGNGbFNaVTU2WVZVdGJYSlpZbWQyVlRkM1pqWmlXWE5tZEc5RGFsVjJabWxaZGw5amVsUlVWSE5ITVdOelZFOXVSbXRZVkRkYVdIVTRNVzFLUW1zeWRGSlNUalYxY0dGUWN6UTNOM1EwYlRsQmMxQklaMnhSVEc1T1l6Wm9aa00xUTJodVJreGtlVTFEUVcxeVRtVkJPV05CWHpNeFV6RjZMVmhEUldGSkxYbGxOMFZKWjFKa01WZG9UVFI1U1Zwa1lsVkdMWEJDVVZkVmQweG9kVTVpY0V0UldGQlFaM0Z6WlVsRlZ6Z3dZVWgwWm1ZeU5VTmxOWEpzYVRRM2EzRkZabmszYzBWVFpYVmllVEJmZGs5NGJqSkVVM1ZNWmxNd2RGZ3hSVlZJWTBGblJtSXpORWxIZFZOck5UWndVSHBCZEhSMk5XUXlXWFIyZUVjME9GOUpaWEJLWW1sVFZVSldkREYzZEVKb1NtTnJaMUpMVW5sVE0wZFVWelV6V0ZSUGFFMUVRekZpY2xneFltVkllbkZ6UmpoMVJITldOVm8yTTNodlpVMUNORmhRVWpONldsOUlWbVZ4YzFCTFYwdEVNVGxDVFhwaVIyb3hMV3RzWjJSbloxbFBORWxqYVhOR1pVZHNZMHQzYnpkdFpHZDFTVUptUkd4NWFVUklRbmRCY2tFMVVXOXZNMnhzZFVSYVRtSkdkamRxZG10a1RVNXRSa3RTWldJdFVGUXRUVk5EWjBobk5VUjFVMHRwUTBSSE1rNVlZV1F6TUhjMGNsWmtWa2xFVmxKWmNYaENaMlJMZVVkcmEwNXhUMGt5TFRSWk5GTm5WbFEyYXpSdlRWTjJTMHR3ZDBwQ1FqUnJZMUZwWDBSU1ZGZElSQzFOYlRnNFZVWndSeTFaYlc1emFFcHpWMGxpTjI5TFZDMXpkbTlEZHpOUFJHVkZlWEJMTVVkMVRHWnNTR1ZQVGs1elZGWklkbmwwYlVGQmVXUmlWRXBtYW5adWMxZFBjMlIxWkdJeGVGaHBRMEpSYVhWcVRGaDZUeTVOVFVWaVpuSkRUVWQwTVRsQ1RqQXRabXcyYm1SMy4yc3IxM0VyczRzeWdMUk5UWHF4a1ltQ20xWHJzaDNIWlpvNTlGSnVsQllVIiwiZXhwIjoxNzE5MTQwNzAxLCJpYXQiOjE3MTkxNDA0MDF9.obWXT85kl20rFiG9X8cjoqT7PnyunnXzRFXWsYBOz68","callbacks":[{"type":"NameCallback","output":[{"name":"prompt","value":"User Name"}],"input":[{"name":"IDToken1","value":"user.309873"}],"_id":0},{"type":"PasswordCallback","output":[{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken2","value":"Pa_ssw0rd"}],"_id":1}],"header":"Sign In","description":"New here? Create an account
Forgot username? Forgot password?"}, 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,1719140411438))), password -> Pa_ssw0rd, fake-ip -> 218.39.221.16, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@40e0c104),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$824/0x00007a88006e8000@183541a9,io.netty.channel.epoll.EpollEventLoop@2e27d72f)
2024-06-23 11:02:36,062 INFO =========================
2024-06-23 11:02:36,062 INFO HTTP request:
2024-06-23 11:02:36,062 INFO POST https://openam-ema-perf-p1.forgeblocks.com/am/json/realms/alpha/authenticate?authIndexType=service&authIndexValue=Login_Protect
2024-06-23 11:02:36,062 INFO headers:
2024-06-23 11:02:36,062 INFO Accept-API-Version: resource=2.0, protocol=1.0
2024-06-23 11:02:36,062 INFO accept: application/json
2024-06-23 11:02:36,062 INFO content-type: application/json
2024-06-23 11:02:36,062 INFO cookie: amlbcookie=01
2024-06-23 11:02:36,062 INFO host: openam-ema-perf-p1.forgeblocks.com
2024-06-23 11:02:36,062 INFO content-length: 2642
2024-06-23 11:02:36,062 INFO cookies:
2024-06-23 11:02:36,062 INFO amlbcookie=01, domain=openam-ema-perf-p1.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None
2024-06-23 11:02:36,062 INFO body:StringChunksRequestBody{charset=UTF-8, content={"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiI1ZmZiYTNhMy1mNmZhLTQ1NTctYmY4Ni1kMjU1MDI3ZjhlOGEiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJ0dDlvcDM5dHAxaGoxcDJpaGNqc2MyYmVkaiIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTVVZEY5MWFIcHhhVGxaTUY5dVZraHpSbEJKV1RsQkxsRnNaMDV1VkRkbVZEWnJXVmgzV201bGJXSnBhM0JsZERKUGRrbE1iemR3YjBWRlkydE9jMUV5U1hsWE5GWTVPR0psWkZWUVdVZHRjVlo0YW5ZM1RuWnNkVFpmUkUxbE5EVk9PR1Y0TkVOQ2NGUjRiVEoxYkhwTGJXSnBUMG8zYVhKME5sUm5NR1JyUW5kME1rWmZRVzFOUjNjMlJGSTBZME5JZW1ZeE5FaGZWMmhRVVhsSFlYaDZkR2sxY3pSUWNYaHFTM1IzV21Ob1N6ZHJNbFZXTW1GSGVVVndiRjlsV0daaVoyOUZTMkpOYmkxbGNGbFNaVTU2WVZVdGJYSlpZbWQyVlRkM1pqWmlXWE5tZEc5RGFsVjJabWxaZGw5amVsUlVWSE5ITVdOelZFOXVSbXRZVkRkYVdIVTRNVzFLUW1zeWRGSlNUalYxY0dGUWN6UTNOM1EwYlRsQmMxQklaMnhSVEc1T1l6Wm9aa00xUTJodVJreGtlVTFEUVcxeVRtVkJPV05CWHpNeFV6RjZMVmhEUldGSkxYbGxOMFZKWjFKa01WZG9UVFI1U1Zwa1lsVkdMWEJDVVZkVmQweG9kVTVpY0V0UldGQlFaM0Z6WlVsRlZ6Z3dZVWgwWm1ZeU5VTmxOWEpzYVRRM2EzRkZabmszYzBWVFpYVmllVEJmZGs5NGJqSkVVM1ZNWmxNd2RGZ3hSVlZJWTBGblJtSXpORWxIZFZOck5UWndVSHBCZEhSMk5XUXlXWFIyZUVjME9GOUpaWEJLWW1sVFZVSldkREYzZEVKb1NtTnJaMUpMVW5sVE0wZFVWelV6V0ZSUGFFMUVRekZpY2xneFltVkllbkZ6UmpoMVJITldOVm8yTTNodlpVMUNORmhRVWpONldsOUlWbVZ4YzFCTFYwdEVNVGxDVFhwaVIyb3hMV3RzWjJSbloxbFBORWxqYVhOR1pVZHNZMHQzYnpkdFpHZDFTVUptUkd4NWFVUklRbmRCY2tFMVVXOXZNMnhzZFVSYVRtSkdkamRxZG10a1RVNXRSa3RTWldJdFVGUXRUVk5EWjBobk5VUjFVMHRwUTBSSE1rNVlZV1F6TUhjMGNsWmtWa2xFVmxKWmNYaENaMlJMZVVkcmEwNXhUMGt5TFRSWk5GTm5WbFEyYXpSdlRWTjJTMHR3ZDBwQ1FqUnJZMUZwWDBSU1ZGZElSQzFOYlRnNFZVWndSeTFaYlc1emFFcHpWMGxpTjI5TFZDMXpkbTlEZHpOUFJHVkZlWEJMTVVkMVRHWnNTR1ZQVGs1elZGWklkbmwwYlVGQmVXUmlWRXBtYW5adWMxZFBjMlIxWkdJeGVGaHBRMEpSYVhWcVRGaDZUeTVOVFVWaVpuSkRUVWQwTVRsQ1RqQXRabXcyYm1SMy4yc3IxM0VyczRzeWdMUk5UWHF4a1ltQ20xWHJzaDNIWlpvNTlGSnVsQllVIiwiZXhwIjoxNzE5MTQwNzAxLCJpYXQiOjE3MTkxNDA0MDF9.obWXT85kl20rFiG9X8cjoqT7PnyunnXzRFXWsYBOz68","callbacks":[{"type":"NameCallback","output":[{"name":"prompt","value":"User Name"}],"input":[{"name":"IDToken1","value":"user.309873"}],"_id":0},{"type":"PasswordCallback","output":[{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken2","value":"Pa_ssw0rd"}],"_id":1}],"header":"Sign In","description":"New here? Create an account
Forgot username? Forgot password?"}}
2024-06-23 11:02:36,062 INFO =========================
2024-06-23 11:02:36,062 INFO HTTP response:
2024-06-23 11:02:36,062 INFO <<<<<<<<<<<<<<<<<<<<<<<<<
2024-06-23 11:02:36,062 INFO 12:01:11.599 [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-23 11:02:36,062 INFO 12:01:12.163 [DEBUG] i.g.h.e.r.DefaultStatsProcessor - Request 'restLoginUsernamePasswordCallback' failed for user 1192225: Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.59.5:443 after 60000 ms
2024-06-23 11:02:36,062 INFO 12:01:12.164 [DEBUG] i.g.h.e.r.DefaultStatsProcessor -
2024-06-23 11:02:36,062 INFO >>>>>>>>>>>>>>>>>>>>>>>>>>
2024-06-23 11:02:36,062 INFO Request:
2024-06-23 11:02:36,062 INFO restLoginUsernamePasswordCallback: KO Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.59.5:443 after 60000 ms
2024-06-23 11:02:36,062 INFO =========================
2024-06-23 11:02:36,062 INFO Session:
2024-06-23 11:02:36,062 INFO Session(idc.loginProtect,1192225,HashMap(gatling.http.cache.baseUrl -> https://openam-ema-perf-p1.forgeblocks.com:443, username -> user.357365, gatling.http.ssl.sslContexts -> io.gatling.http.util.SslContexts@410d8311, fake-user-agent -> Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:60.0) Gecko/20100101 Firefox/60.0, callbacks -> {"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiI3ODg4YWY0Yy1jODNmLTRjNWEtODFhNy0zMTQxZDMzYzQ5NGQiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJjbGJmZTdhOTFnNGk4czlyN2g0aHZqNHM1MiIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTVRWjJwc2VFSlZibHBEVGs1SlUyMVRlRlJCVmtwbkxsVnRSME5aVm1wWVNubzFaRFJLWDFKdWFtWk9WazFxVDFFMWJXZFRWamt4Y25KS1YwbG1kekV0VTE5dlZXMVZTSFZTUlZkUlkybzRTMDltVVdWM05uSkVVa3RTZGtONVpIVkRUVWR2Y0hSTVNUZERSVE5IVHpkblpVOXdiV0l3U0dwcGVGWlJYM1l4YmtaQ1VFSTJWRXc0ZVU5VVFXUTRhbEJzUWtSa1owOW9XRXB6UWxWTGEyMURWR2RaVG1WblpYRnpWVmd6WHpCSE1EaHBWaTFtVmt4bWNGUk1jV05rYzBNeGNsQmhaSHB3YmxWV1VXcFhhSGROYURaNVRFUnRVRzk0VVhORmVtWnhSSG95UjJ4S1VXOTJTSEJ2YW5VdFR6aFBiVXd3VTAweVVuRm9UMGxhYmtkcVJEaERXSEZ6YnpsMlZIVlRlVTFXYlhvM1luQkNRVUZuUjNsek1GZ3RkamxvT0U0d1pGVjBURGN6WlhrNGJqTjViMWg2TVRkeVVtSjJUWEJLTTFkdmJEbEhTWFZOYlVZMGMxQkZXbEJCVnpCT1RtMUhNbXBvWWpsWk5rNUpOM1ozYzNKeWRIRTBlRlJ0WHpkNlNWb3hTWEV6VVRKS1dEWTNWMk5rYXpFNWNYSkNTbFZTTXpVd2QyaDNTRVJxY2psM05XVlBTVVJXWVRkd1VrSkpaMGg2YTFCTFdrMUtWWE5OV1RaWk5ETk1Za1JCYWxNM2MzbE1NRU5oUVhkVU9YZDBWR05vYTNWd1VuZHJUbXBpWDJGWmRsTm5abmhpUjB4eGJqWlNhV1YzZG5OVlNWTXlYMlZHU2pFNVNGTnFOVFI1TlhGVVFrOUtTR2d6VTJKNVpIb3hjMTlNU0hkV2RtcGFXVkUzV1VsVU5GbHRXbVY1V0V0dlV6Uk9NMUpOUVcxTVJTMTJRVmx1YVd3d1lrSk9RWEZ4U2toblJWcFZiRWwyZDNoTmQyVmhkV2xpZUdabmNUUnVlVEExZEdoUVJETnJRMXAxWjFaUFFrWnFYMEZEZWxWNVJsZFdXVGN5UjBOUWVteDFZMkpSZERSUk1tMVdPRGxIZUVGUk1tcENZV2xZTTBWVlpWQnZOelkwY0RSeFZuazNNVzV2Y0hwVlEweFZVMGw2V0ZkMFgzbE9NR3hqWlVNMmVGRnNka1o2U21wS1gyOXhjWEV6UnpSR1lpMTVNRTlTZFRabU5FbHFla0UyVkZKbmRIUm9WblZQTTNCTlVtOURURXRmVVdoUk9EQkZVVlJ3VkhveVRtSnpTVFpPUWpadVJYaFRObDlSYVMxRWF6SjJOa1phVmtKalYyMXFhV2hLVkdNd1lXbEJPVFJYYkhJd2JqUkRVeTVpYnpaWmFXcFBXblIwY2w5dlJtWnlkVGhQTWtsQi5pWTJfXzlTNWlPdXpfMDEyS05obGtsVm1jVGhNc0RCUXBlaGFlamd1NERnIiwiZXhwIjoxNzE5MTQwNzA0LCJpYXQiOjE3MTkxNDA0MDR9.b0Y3vWIkL9lj9tpn921qd9hewwaYCioaOVhyoxmTfUE","callbacks":[{"type":"NameCallback","output":[{"name":"prompt","value":"User Name"}],"input":[{"name":"IDToken1","value":"user.357365"}],"_id":0},{"type":"PasswordCallback","output":[{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken2","value":"Pa_ssw0rd"}],"_id":1}],"header":"Sign In","description":"New here? Create an account
Forgot username? Forgot password?"}, 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,1719140412163))), password -> Pa_ssw0rd, fake-ip -> 188.243.137.23, gatling.http.cache.dns -> io.gatling.http.resolver.ShufflingNameResolver@1fe3fe7f),KO,List(),io.gatling.core.protocol.ProtocolComponentsRegistry$$Lambda$824/0x00007a88006e8000@183541a9,io.netty.channel.epoll.EpollEventLoop@17ae98d7)
2024-06-23 11:02:36,063 INFO =========================
2024-06-23 11:02:36,063 INFO HTTP request:
2024-06-23 11:02:36,063 INFO POST https://openam-ema-perf-p1.forgeblocks.com/am/json/realms/alpha/authenticate?authIndexType=service&authIndexValue=Login_Protect
2024-06-23 11:02:36,063 INFO headers:
2024-06-23 11:02:36,063 INFO Accept-API-Version: resource=2.0, protocol=1.0
2024-06-23 11:02:36,063 INFO accept: application/json
2024-06-23 11:02:36,063 INFO content-type: application/json
2024-06-23 11:02:36,063 INFO cookie: amlbcookie=01
2024-06-23 11:02:36,063 INFO host: openam-ema-perf-p1.forgeblocks.com
2024-06-23 11:02:36,063 INFO content-length: 2642
2024-06-23 11:02:36,063 INFO cookies:
2024-06-23 11:02:36,063 INFO amlbcookie=01, domain=openam-ema-perf-p1.forgeblocks.com, path=/, secure, HTTPOnly, SameSite=None
2024-06-23 11:02:36,063 INFO body:StringChunksRequestBody{charset=UTF-8, content={"authId":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ3aGl0ZWxpc3Qtc3RhdGUiOiI3ODg4YWY0Yy1jODNmLTRjNWEtODFhNy0zMTQxZDMzYzQ5NGQiLCJhdXRoSW5kZXhWYWx1ZSI6IkxvZ2luX1Byb3RlY3QiLCJvdGsiOiJjbGJmZTdhOTFnNGk4czlyN2g0aHZqNHM1MiIsImF1dGhJbmRleFR5cGUiOiJzZXJ2aWNlIiwicmVhbG0iOiIvYWxwaGEiLCJzZXNzaW9uSWQiOiIqQUFKVFNRQUNNRElBQkhSNWNHVUFDRXBYVkY5QlZWUklBQUpUTVFBQ01ERS4qZXlKMGVYQWlPaUpLVjFRaUxDSmpkSGtpT2lKS1YxUWlMQ0poYkdjaU9pSklVekkxTmlKOS5aWGxLTUdWWVFXbFBhVXBMVmpGUmFVeERTbXhpYlUxcFQybEtRazFVU1RSUk1FcEVURlZvVkUxcVZUSkphWGRwV1ZkNGJrbHFiMmxhUjJ4NVNXNHdMaTVRWjJwc2VFSlZibHBEVGs1SlUyMVRlRlJCVmtwbkxsVnRSME5aVm1wWVNubzFaRFJLWDFKdWFtWk9WazFxVDFFMWJXZFRWamt4Y25KS1YwbG1kekV0VTE5dlZXMVZTSFZTUlZkUlkybzRTMDltVVdWM05uSkVVa3RTZGtONVpIVkRUVWR2Y0hSTVNUZERSVE5IVHpkblpVOXdiV0l3U0dwcGVGWlJYM1l4YmtaQ1VFSTJWRXc0ZVU5VVFXUTRhbEJzUWtSa1owOW9XRXB6UWxWTGEyMURWR2RaVG1WblpYRnpWVmd6WHpCSE1EaHBWaTFtVmt4bWNGUk1jV05rYzBNeGNsQmhaSHB3YmxWV1VXcFhhSGROYURaNVRFUnRVRzk0VVhORmVtWnhSSG95UjJ4S1VXOTJTSEJ2YW5VdFR6aFBiVXd3VTAweVVuRm9UMGxhYmtkcVJEaERXSEZ6YnpsMlZIVlRlVTFXYlhvM1luQkNRVUZuUjNsek1GZ3RkamxvT0U0d1pGVjBURGN6WlhrNGJqTjViMWg2TVRkeVVtSjJUWEJLTTFkdmJEbEhTWFZOYlVZMGMxQkZXbEJCVnpCT1RtMUhNbXBvWWpsWk5rNUpOM1ozYzNKeWRIRTBlRlJ0WHpkNlNWb3hTWEV6VVRKS1dEWTNWMk5rYXpFNWNYSkNTbFZTTXpVd2QyaDNTRVJxY2psM05XVlBTVVJXWVRkd1VrSkpaMGg2YTFCTFdrMUtWWE5OV1RaWk5ETk1Za1JCYWxNM2MzbE1NRU5oUVhkVU9YZDBWR05vYTNWd1VuZHJUbXBpWDJGWmRsTm5abmhpUjB4eGJqWlNhV1YzZG5OVlNWTXlYMlZHU2pFNVNGTnFOVFI1TlhGVVFrOUtTR2d6VTJKNVpIb3hjMTlNU0hkV2RtcGFXVkUzV1VsVU5GbHRXbVY1V0V0dlV6Uk9NMUpOUVcxTVJTMTJRVmx1YVd3d1lrSk9RWEZ4U2toblJWcFZiRWwyZDNoTmQyVmhkV2xpZUdabmNUUnVlVEExZEdoUVJETnJRMXAxWjFaUFFrWnFYMEZEZWxWNVJsZFdXVGN5UjBOUWVteDFZMkpSZERSUk1tMVdPRGxIZUVGUk1tcENZV2xZTTBWVlpWQnZOelkwY0RSeFZuazNNVzV2Y0hwVlEweFZVMGw2V0ZkMFgzbE9NR3hqWlVNMmVGRnNka1o2U21wS1gyOXhjWEV6UnpSR1lpMTVNRTlTZFRabU5FbHFla0UyVkZKbmRIUm9WblZQTTNCTlVtOURURXRmVVdoUk9EQkZVVlJ3VkhveVRtSnpTVFpPUWpadVJYaFRObDlSYVMxRWF6SjJOa1phVmtKalYyMXFhV2hLVkdNd1lXbEJPVFJYYkhJd2JqUkRVeTVpYnpaWmFXcFBXblIwY2w5dlJtWnlkVGhQTWtsQi5pWTJfXzlTNWlPdXpfMDEyS05obGtsVm1jVGhNc0RCUXBlaGFlamd1NERnIiwiZXhwIjoxNzE5MTQwNzA0LCJpYXQiOjE3MTkxNDA0MDR9.b0Y3vWIkL9lj9tpn921qd9hewwaYCioaOVhyoxmTfUE","callbacks":[{"type":"NameCallback","output":[{"name":"prompt","value":"User Name"}],"input":[{"name":"IDToken1","value":"user.357365"}],"_id":0},{"type":"PasswordCallback","output":[{"name":"prompt","value":"Password"}],"input":[{"name":"IDToken2","value":"Pa_ssw0rd"}],"_id":1}],"header":"Sign In","description":"New here? Create an account
Forgot username? Forgot password?"}}
2024-06-23 11:02:36,063 INFO =========================
2024-06-23 11:02:36,063 INFO HTTP response:
2024-06-23 11:02:36,063 INFO <<<<<<<<<<<<<<<<<<<<<<<<<
2024-06-23 11:02:36,063 INFO 12:01:12.235 [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-23 11:02:36,063 INFO
2024-06-23 11:02:36,063 INFO ================================================================================
2024-06-23 11:02:36,063 INFO 2024-06-23 12:01:12 3706s elapsed
2024-06-23 11:02:36,063 INFO ---- Requests ------------------------------------------------------------------
2024-06-23 11:02:36,063 INFO > Global (OK=4682546 KO=199150)
2024-06-23 11:02:36,063 INFO > protectLoginInitiate (OK=1160528 KO=32299 )
2024-06-23 11:02:36,063 INFO > PingOneProtectInitializeCallback (OK=1137485 KO=23043 )
2024-06-23 11:02:36,063 INFO > restLoginUsernamePasswordCallback (OK=1123927 KO=36601 )
2024-06-23 11:02:36,063 INFO > PingOneProtectEvaluationCallback (OK=1110029 KO=50499 )
2024-06-23 11:02:36,063 INFO > restloginProgressiveProfileCallback (OK=150577 KO=56708 )
2024-06-23 11:02:36,063 INFO ---- Errors --------------------------------------------------------------------
2024-06-23 11:02:36,063 INFO > status.find.is(200), but actually found 503 152276 (46.38%)
2024-06-23 11:02:36,063 INFO > status.find.is(200), but actually found 502 35696 (10.87%)
2024-06-23 11:02:36,063 INFO > PingOneProtectInitializeCallback: Failed to build request: No 32299 ( 9.84%)
2024-06-23 11:02:36,063 INFO attribute named 'callbacks' is defined
2024-06-23 11:02:36,063 INFO > restLoginUsernamePasswordCallback: Failed to build request: No 32299 ( 9.84%)
2024-06-23 11:02:36,063 INFO attribute named 'callbacks' is defined
2024-06-23 11:02:36,063 INFO > PingOneProtectEvaluationCallback: Failed to build request: No 32299 ( 9.84%)
2024-06-23 11:02:36,063 INFO attribute named 'callbacks' is defined
2024-06-23 11:02:36,063 INFO > restloginProgressiveProfileCallback: Failed to build request: 32299 ( 9.84%)
2024-06-23 11:02:36,063 INFO No attribute named 'callbacks' is defined
2024-06-23 11:02:36,064 INFO > jsonPath($.tokenId).find.exists, found nothing 5824 ( 1.77%)
2024-06-23 11:02:36,064 INFO > status.find.is(200), but actually found 400 3357 ( 1.02%)
2024-06-23 11:02:36,064 INFO > status.find.is(200), but actually found 401 1829 ( 0.56%)
2024-06-23 11:02:36,064 INFO > Request timeout to openam-ema-perf-p1.forgeblocks.com/35.241.5 141 ( 0.04%)
2024-06-23 11:02:36,064 INFO 9.5:443 after 60000 ms
2024-06-23 11:02:36,064 INFO > status.find.is(200), but actually found 500 19 ( 0.01%)
2024-06-23 11:02:36,064 INFO > j.i.IOException: Premature close 7 ( 0.00%)
2024-06-23 11:02:36,064 INFO > j.n.c.ClosedChannelException 1 ( 0.00%)
2024-06-23 11:02:36,064 INFO
2024-06-23 11:02:36,064 INFO ---- idc.loginProtect ----------------------------------------------------------
2024-06-23 11:02:36,064 INFO [##########################################################################]100%
2024-06-23 11:02:36,064 INFO waiting: 0 / active: 0 / done: 1192827
2024-06-23 11:02:36,064 INFO ================================================================================
2024-06-23 11:02:36,064 INFO
2024-06-23 11:02:36,064 INFO Simulation com.forgerock.gatling.Loadtest completed in 3706 seconds
2024-06-23 11:02:36,064 INFO
2024-06-23 11:02:36,064 INFO ----------------------
2024-06-23 11:02:36,064 INFO [23/Jun/2024 12:01:12] Create tar file login-tree-330.tar.gz
2024-06-23 11:02:36,064 INFO ----------------------
2024-06-23 11:02:36,064 INFO RESULTS_FOLDER=/results/gatling/
2024-06-23 11:02:36,064 INFO RESULT_FOLDER=login-tree-330
2024-06-23 11:02:36,064 INFO SIMULATION_PATH=login-tree-330/loadtest-20240623095925474
2024-06-23 11:02:36,064 INFO PWD=/results/gatling/login-tree-330/loadtest-20240623095925474
2024-06-23 11:02:36,064 INFO [cmd] tar -czvf /results/gatling//login-tree-330.tar.gz --exclude=simulation.log *
2024-06-23 11:02:36,064 INFO /results/gatling
2024-06-23 11:02:36,064 INFO
2024-06-23 11:02:36,064 INFO ----------------------
2024-06-23 11:02:36,064 INFO [23/Jun/2024 12:01:12] Update /results/gatling//latest
2024-06-23 11:02:36,064 INFO ----------------------
2024-06-23 11:02:36,064 INFO
2024-06-23 11:02:36,064 INFO ----------------------
2024-06-23 11:02:36,064 INFO [23/Jun/2024 12:01:12] Update /results/gatling//latest.tar.gz
2024-06-23 11:02:36,064 INFO ----------------------
2024-06-23 11:02:36,064 INFO
________________________________________________________________________________
[2024-06-23 11:02:36] login-tree-330 show_stderr : Display stderr order
________________________________________________________________________________
2024-06-23 11:02:36,065 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/07_login-tree-330/stderr.txt is small (0 lines), printing all lines:
2024-06-23 11:02:36,065 DEBUG ----- output -----
2024-06-23 11:02:36,065 DEBUG
2024-06-23 11:02:36,065 INFO
________________________________________________________________________________
[2024-06-23 11:02:36] login-tree-330 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-23 11:03:38] login-tree-330 print_overseer_logs : Print overseer logs from the K8s pod
________________________________________________________________________________
Task result is INIT no need to display logs.
________________________________________________________________________________
[2024-06-23 11:03:38] login-tree-330 post : Post
________________________________________________________________________________
Setting result to PASS
Task has been successfully stopped