--Task--
name: login-tree-250
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-200']
preceding_task: login-tree-200
options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 250, 'rampup-duration': 30, 'duration': 3600, 'java-opts': '-Drealm=alpha -Duser_password=Pa_ssw0rd -Dlogin_tree=Login'}
group_name: None
Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock
________________________________________________________________________________
[2024-06-22 22:22:00] login-tree-250 pre : N/A
________________________________________________________________________________
2024-06-22 22:22:00 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds)
2024-06-22 22:22:00 - INFO: timeout was calculated based on simulation duration parameter
Pod log file : /results/orders/login-tree-250/stdout.txt
Pod log : /results/orders/login-tree-250
2024-06-22 22:22:00,975 INFO
2024-06-22 22:22:00,975 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 22:22:00,975 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 22:22:01,290 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 22:22:01,290 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:01,290 DEBUG --- stdout ---
2024-06-22 22:22:01,290 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 22:22:01,290 DEBUG --- stderr ---
2024-06-22 22:22:01,290 DEBUG
________________________________________________________________________________
[2024-06-22 22:22:01] login-tree-250 print_command : Display the command to be forwarded to overseer as an order
________________________________________________________________________________
2024-06-22 22:22:01,291 INFO
2024-06-22 22:22:01,291 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1
2024-06-22 22:22:01,291 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 22:22:01,595 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 22:22:01,596 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:01,596 DEBUG --- stdout ---
2024-06-22 22:22:01,596 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 22:22:01,596 DEBUG --- stderr ---
2024-06-22 22:22:01,596 DEBUG
/lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options "-Xmx4G" --no-reports
________________________________________________________________________________
[2024-06-22 22:22:01] login-tree-250 create_order : Create order for overseer with the command to run
________________________________________________________________________________
2024-06-22 22:22:01,597 INFO
2024-06-22 22:22:01,597 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 22:22:01,597 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 22:22:01,900 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 22:22:01,901 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:01,901 DEBUG --- stdout ---
2024-06-22 22:22:01,901 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 22:22:01,901 DEBUG --- stderr ---
2024-06-22 22:22:01,901 DEBUG
Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json
{
"name": "login-tree-250",
"command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-p1\" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options \"-Xmx4G\" --no-reports",
"out-dir": "/results/orders/login-tree-250",
"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 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=250 -Duser_password=Pa_ssw0rd"
}
}
________________________________________________________________________________
[2024-06-22 22:22:01] login-tree-250 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 22:22:01,903 INFO
2024-06-22 22:22:01,903 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING"
2024-06-22 22:22:01,903 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2024-06-22 22:22:02,152 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 22:22:02,152 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:02,152 DEBUG --- stdout ---
2024-06-22 22:22:02,152 DEBUG WAITING
2024-06-22 22:22:02,152 DEBUG --- stderr ---
2024-06-22 22:22:02,152 DEBUG % Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33
________________________________________________________________________________
[2024-06-22 22:22:02] login-tree-250 start_order : Send the order to the overseer pod
________________________________________________________________________________
2024-06-22 22:22:02,153 INFO
2024-06-22 22:22:02,153 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 22:22:02,153 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 22:22:02,461 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 22:22:02,461 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:02,461 DEBUG --- stdout ---
2024-06-22 22:22:02,461 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 22:22:02,461 DEBUG --- stderr ---
2024-06-22 22:22:02,461 DEBUG
2024-06-22 22:22:02,462 INFO
2024-06-22 22:22:02,462 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json overseer-0-5b6f9464fd-slbm8:/results/orders/order.json
2024-06-22 22:22:02,462 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 22:22:03,846 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:03,847 DEBUG --- stdout ---
2024-06-22 22:22:03,847 DEBUG
2024-06-22 22:22:03,847 DEBUG --- stderr ---
2024-06-22 22:22:03,847 DEBUG
Order has been successfully forwarded to overseer
2024-06-22 22:22:03 - INFO: Check if Gatling simulation started successfully
2024-06-22 22:22:03,847 INFO
2024-06-22 22:22:03,847 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 22:22:03,847 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 22:22:04,163 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 22:22:04,164 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:04,164 DEBUG --- stdout ---
2024-06-22 22:22:04,164 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 22:22:04,164 DEBUG --- stderr ---
2024-06-22 22:22:04,164 DEBUG
2024-06-22 22:22:04,164 INFO
2024-06-22 22:22:04,164 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-250/stdout.txt | grep "started..."
2024-06-22 22:22:04,164 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0]
2024-06-22 22:22:04,916 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry
2024-06-22 22:22:10,669 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found
2024-06-22 22:22:10,669 INFO [loop_until]: OK (rc = 0)
2024-06-22 22:22:10,669 DEBUG --- stdout ---
2024-06-22 22:22:10,669 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 22:22:10,669 DEBUG --- stderr ---
2024-06-22 22:22:10,669 DEBUG
________________________________________________________________________________
[2024-06-22 22:22:10] login-tree-250 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-22 23:24:20] login-tree-250 stop_order : Stop the order on overseer pod
________________________________________________________________________________
2024-06-22 23:24:20,659 INFO
2024-06-22 23:24:20,659 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 23:24:20,659 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:21,007 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:21,008 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:21,008 DEBUG --- stdout ---
2024-06-22 23:24:21,008 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:21,008 DEBUG --- stderr ---
2024-06-22 23:24:21,008 DEBUG
2024-06-22 23:24:21,008 INFO
2024-06-22 23:24:21,008 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 23:24:21,008 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 23:24:21,816 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:21,816 DEBUG --- stdout ---
2024-06-22 23:24:21,816 DEBUG
2024-06-22 23:24:21,816 DEBUG --- stderr ---
2024-06-22 23:24:21,816 DEBUG
Order ran during 1h 02m
________________________________________________________________________________
[2024-06-22 23:24:21] login-tree-250 download_logs : Download individual order files
________________________________________________________________________________
2024-06-22 23:24:21 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/order.json
2024-06-22 23:24:21,817 INFO
2024-06-22 23:24:21,817 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 23:24:21,817 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:22,134 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:22,134 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:22,134 DEBUG --- stdout ---
2024-06-22 23:24:22,134 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:22,134 DEBUG --- stderr ---
2024-06-22 23:24:22,134 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 23:24:22,135 INFO
2024-06-22 23:24:22,135 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 23:24:22,135 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:22,466 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:22,466 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:22,466 DEBUG --- stdout ---
2024-06-22 23:24:22,467 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:22,467 DEBUG --- stderr ---
2024-06-22 23:24:22,467 DEBUG
2024-06-22 23:24:22,467 INFO
2024-06-22 23:24:22,467 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-250/rc.txt" ]; then echo "/results/orders/login-tree-250/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-250/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/rc.txt file found"
2024-06-22 23:24:22,467 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 23:24:23,307 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 23:24:23,307 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:23,309 DEBUG --- stdout ---
2024-06-22 23:24:23,309 DEBUG /results/orders/login-tree-250/rc.txt file found
2024-06-22 23:24:23,309 DEBUG --- stderr ---
2024-06-22 23:24:23,309 DEBUG
2024-06-22 23:24:23,310 INFO
2024-06-22 23:24:23,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 23:24:23,310 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:23,626 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:23,626 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:23,626 DEBUG --- stdout ---
2024-06-22 23:24:23,626 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:23,626 DEBUG --- stderr ---
2024-06-22 23:24:23,626 DEBUG
2024-06-22 23:24:23,626 INFO
2024-06-22 23:24:23,626 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-250/rc.txt
2024-06-22 23:24:24,429 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 23:24:24,429 DEBUG --- stdout ---
2024-06-22 23:24:24,429 DEBUG 4 /results/orders/login-tree-250/rc.txt
2024-06-22 23:24:24,429 DEBUG --- stderr ---
2024-06-22 23:24:24,429 DEBUG
2024-06-22 23:24:24,429 INFO
- Download file (size 4k)
2024-06-22 23:24:24,430 INFO
2024-06-22 23:24:24,430 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 23:24:24,430 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:24,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:24,752 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:24,752 DEBUG --- stdout ---
2024-06-22 23:24:24,752 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:24,753 DEBUG --- stderr ---
2024-06-22 23:24:24,753 DEBUG
source : /results/orders/login-tree-250/rc.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt
2024-06-22 23:24:24,753 INFO
2024-06-22 23:24:24,753 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 23:24:24,753 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:25,073 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:25,073 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:25,073 DEBUG --- stdout ---
2024-06-22 23:24:25,073 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:25,073 DEBUG --- stderr ---
2024-06-22 23:24:25,073 DEBUG
2024-06-22 23:24:25,074 INFO
2024-06-22 23:24:25,074 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-250/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt
2024-06-22 23:24:25,074 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 23:24:25,820 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:25,820 DEBUG --- stdout ---
2024-06-22 23:24:25,820 DEBUG tar: Removing leading `/' from member names
2024-06-22 23:24:25,820 DEBUG --- stderr ---
2024-06-22 23:24:25,820 DEBUG
2024-06-22 23:24:25,821 INFO
2024-06-22 23:24:25,821 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 23:24:25,821 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:26,144 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:26,144 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:26,144 DEBUG --- stdout ---
2024-06-22 23:24:26,144 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:26,144 DEBUG --- stderr ---
2024-06-22 23:24:26,144 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 23:24:26,145 INFO
2024-06-22 23:24:26,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-22 23:24:26,145 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:26,450 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:26,450 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:26,450 DEBUG --- stdout ---
2024-06-22 23:24:26,450 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:26,450 DEBUG --- stderr ---
2024-06-22 23:24:26,451 DEBUG
2024-06-22 23:24:26,451 INFO
2024-06-22 23:24:26,451 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-250/stderr.txt" ]; then echo "/results/orders/login-tree-250/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stderr.txt file found"
2024-06-22 23:24:26,451 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 23:24:27,259 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 23:24:27,260 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:27,260 DEBUG --- stdout ---
2024-06-22 23:24:27,260 DEBUG /results/orders/login-tree-250/stderr.txt file found
2024-06-22 23:24:27,260 DEBUG --- stderr ---
2024-06-22 23:24:27,260 DEBUG
2024-06-22 23:24:27,260 INFO
2024-06-22 23:24:27,261 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 23:24:27,261 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:27,579 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:27,579 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:27,579 DEBUG --- stdout ---
2024-06-22 23:24:27,579 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:27,579 DEBUG --- stderr ---
2024-06-22 23:24:27,579 DEBUG
2024-06-22 23:24:27,579 INFO
2024-06-22 23:24:27,579 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-250/stderr.txt
2024-06-22 23:24:28,385 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 23:24:28,385 DEBUG --- stdout ---
2024-06-22 23:24:28,386 DEBUG 0 /results/orders/login-tree-250/stderr.txt
2024-06-22 23:24:28,387 DEBUG --- stderr ---
2024-06-22 23:24:28,387 DEBUG
2024-06-22 23:24:28,387 INFO
- Download file (size 0k)
2024-06-22 23:24:28,387 INFO
2024-06-22 23:24:28,387 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 23:24:28,387 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:28,713 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:28,713 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:28,713 DEBUG --- stdout ---
2024-06-22 23:24:28,713 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:28,713 DEBUG --- stderr ---
2024-06-22 23:24:28,713 DEBUG
source : /results/orders/login-tree-250/stderr.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt
2024-06-22 23:24:28,713 INFO
2024-06-22 23:24:28,713 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 23:24:28,714 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:29,029 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:29,030 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:29,030 DEBUG --- stdout ---
2024-06-22 23:24:29,030 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:29,030 DEBUG --- stderr ---
2024-06-22 23:24:29,030 DEBUG
2024-06-22 23:24:29,030 INFO
2024-06-22 23:24:29,031 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-250/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt
2024-06-22 23:24:29,031 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 23:24:29,774 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:29,774 DEBUG --- stdout ---
2024-06-22 23:24:29,774 DEBUG tar: Removing leading `/' from member names
2024-06-22 23:24:29,774 DEBUG --- stderr ---
2024-06-22 23:24:29,775 DEBUG
2024-06-22 23:24:29,775 INFO
2024-06-22 23:24:29,775 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 23:24:29,775 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:30,094 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:30,094 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:30,095 DEBUG --- stdout ---
2024-06-22 23:24:30,095 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:30,095 DEBUG --- stderr ---
2024-06-22 23:24:30,095 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 23:24:30,095 INFO
2024-06-22 23:24:30,095 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 23:24:30,095 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:30,410 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:30,410 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:30,410 DEBUG --- stdout ---
2024-06-22 23:24:30,411 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:30,411 DEBUG --- stderr ---
2024-06-22 23:24:30,411 DEBUG
2024-06-22 23:24:30,411 INFO
2024-06-22 23:24:30,412 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-250/stdout.txt" ]; then echo "/results/orders/login-tree-250/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-250/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/stdout.txt file found"
2024-06-22 23:24:30,412 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 23:24:31,232 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 23:24:31,232 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:31,232 DEBUG --- stdout ---
2024-06-22 23:24:31,232 DEBUG /results/orders/login-tree-250/stdout.txt file found
2024-06-22 23:24:31,232 DEBUG --- stderr ---
2024-06-22 23:24:31,232 DEBUG
2024-06-22 23:24:31,232 INFO
2024-06-22 23:24:31,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 23:24:31,232 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:31,536 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:31,537 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:31,537 DEBUG --- stdout ---
2024-06-22 23:24:31,537 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:31,537 DEBUG --- stderr ---
2024-06-22 23:24:31,537 DEBUG
2024-06-22 23:24:31,537 INFO
2024-06-22 23:24:31,537 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-250/stdout.txt
2024-06-22 23:24:32,339 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 23:24:32,340 DEBUG --- stdout ---
2024-06-22 23:24:32,340 DEBUG 836 /results/orders/login-tree-250/stdout.txt
2024-06-22 23:24:32,340 DEBUG --- stderr ---
2024-06-22 23:24:32,340 DEBUG
2024-06-22 23:24:32,340 INFO
- Download file (size 836k)
2024-06-22 23:24:32,341 INFO
2024-06-22 23:24:32,341 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 23:24:32,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:32,643 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:32,643 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:32,643 DEBUG --- stdout ---
2024-06-22 23:24:32,644 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:32,644 DEBUG --- stderr ---
2024-06-22 23:24:32,644 DEBUG
source : /results/orders/login-tree-250/stdout.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt
2024-06-22 23:24:32,644 INFO
2024-06-22 23:24:32,644 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 23:24:32,644 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:32,952 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:32,952 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:32,952 DEBUG --- stdout ---
2024-06-22 23:24:32,952 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:32,952 DEBUG --- stderr ---
2024-06-22 23:24:32,952 DEBUG
2024-06-22 23:24:32,953 INFO
2024-06-22 23:24:32,953 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-250/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt
2024-06-22 23:24:32,953 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 23:24:34,011 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:34,011 DEBUG --- stdout ---
2024-06-22 23:24:34,011 DEBUG tar: Removing leading `/' from member names
2024-06-22 23:24:34,011 DEBUG --- stderr ---
2024-06-22 23:24:34,011 DEBUG
________________________________________________________________________________
[2024-06-22 23:24:34] login-tree-250 show_cmd : Display order command
________________________________________________________________________________
2024-06-22 23:24:34,012 INFO
2024-06-22 23:24:34,012 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 23:24:34,012 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:34,324 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:34,324 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:34,324 DEBUG --- stdout ---
2024-06-22 23:24:34,324 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:34,324 DEBUG --- stderr ---
2024-06-22 23:24:34,324 DEBUG
Checking pod: overseer-0-5b6f9464fd-slbm8 for file exists, if not wait.
2024-06-22 23:24:34,324 INFO
2024-06-22 23:24:34,324 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 23:24:34,324 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:34,628 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:34,629 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:34,629 DEBUG --- stdout ---
2024-06-22 23:24:34,629 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:34,629 DEBUG --- stderr ---
2024-06-22 23:24:34,629 DEBUG
2024-06-22 23:24:34,629 INFO
2024-06-22 23:24:34,630 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-250/cmd.txt" ]; then echo "/results/orders/login-tree-250/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-250/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-250/cmd.txt file found"
2024-06-22 23:24:34,630 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0]
2024-06-22 23:24:35,436 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found
2024-06-22 23:24:35,436 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:35,436 DEBUG --- stdout ---
2024-06-22 23:24:35,436 DEBUG /results/orders/login-tree-250/cmd.txt file found
2024-06-22 23:24:35,436 DEBUG --- stderr ---
2024-06-22 23:24:35,436 DEBUG
2024-06-22 23:24:35,436 INFO
2024-06-22 23:24:35,436 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 23:24:35,436 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:35,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:35,758 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:35,758 DEBUG --- stdout ---
2024-06-22 23:24:35,758 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:35,758 DEBUG --- stderr ---
2024-06-22 23:24:35,758 DEBUG
2024-06-22 23:24:35,758 INFO
2024-06-22 23:24:35,758 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-250/cmd.txt
2024-06-22 23:24:36,590 INFO [run_command]: OK (rc = 0 - expected to be in [0])
2024-06-22 23:24:36,590 DEBUG --- stdout ---
2024-06-22 23:24:36,590 DEBUG 4 /results/orders/login-tree-250/cmd.txt
2024-06-22 23:24:36,590 DEBUG --- stderr ---
2024-06-22 23:24:36,590 DEBUG
2024-06-22 23:24:36,590 INFO
- Download file (size 4k)
2024-06-22 23:24:36,591 INFO
2024-06-22 23:24:36,591 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 23:24:36,591 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:36,912 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:36,912 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:36,912 DEBUG --- stdout ---
2024-06-22 23:24:36,913 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:36,913 DEBUG --- stderr ---
2024-06-22 23:24:36,913 DEBUG
source : /results/orders/login-tree-250/cmd.txt @ overseer-0-5b6f9464fd-slbm8
destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt
2024-06-22 23:24:36,913 INFO
2024-06-22 23:24:36,913 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 23:24:36,913 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0]
2024-06-22 23:24:37,229 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found
2024-06-22 23:24:37,230 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:37,230 DEBUG --- stdout ---
2024-06-22 23:24:37,230 DEBUG overseer-0-5b6f9464fd-slbm8
2024-06-22 23:24:37,230 DEBUG --- stderr ---
2024-06-22 23:24:37,230 DEBUG
2024-06-22 23:24:37,231 INFO
2024-06-22 23:24:37,231 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-250/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt
2024-06-22 23:24:37,231 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0]
2024-06-22 23:24:38,035 INFO [loop_until]: OK (rc = 0)
2024-06-22 23:24:38,035 DEBUG --- stdout ---
2024-06-22 23:24:38,035 DEBUG tar: Removing leading `/' from member names
2024-06-22 23:24:38,035 DEBUG --- stderr ---
2024-06-22 23:24:38,035 DEBUG
2024-06-22 23:24:38,036 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/cmd.txt is small (1 lines), printing all lines:
2024-06-22 23:24:38,036 DEBUG ----- output -----
2024-06-22 23:24:38,036 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1" --results-folder /results/gatling/login-tree-250 --extra-run-jvm-options "-Xmx4G" --no-reports
2024-06-22 23:24:38,036 INFO
________________________________________________________________________________
[2024-06-22 23:24:38] login-tree-250 show_rc : Display rc order
________________________________________________________________________________
2024-06-22 23:24:38,036 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/rc.txt is small (1 lines), printing all lines:
2024-06-22 23:24:38,036 DEBUG ----- output -----
2024-06-22 23:24:38,036 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15])
2024-06-22 23:24:38,036 INFO
________________________________________________________________________________
[2024-06-22 23:24:38] login-tree-250 show_stdout : Display stdout order
________________________________________________________________________________
2024-06-22 23:24:38,038 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stdout.txt is 12398 lines):
2024-06-22 23:24:38,039 DEBUG ----- output -----
2024-06-22 23:24:38,039 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling
2024-06-22 23:24:38,039 INFO Gatling 3.11.4 is available! (you're using 3.9.5)
2024-06-22 23:24:38,039 INFO Simulation com.forgerock.gatling.Loadtest started...
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO ================================================================================
2024-06-22 23:24:38,039 INFO 2024-06-22 23:22:13 5s elapsed
2024-06-22 23:24:38,039 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,039 INFO > Global (OK=226 KO=0 )
2024-06-22 23:24:38,039 INFO > restLoginInitiate (OK=103 KO=0 )
2024-06-22 23:24:38,039 INFO > restLoginUsernamePasswordCallback (OK=98 KO=0 )
2024-06-22 23:24:38,039 INFO > restloginProgressiveProfileCallback (OK=25 KO=0 )
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,039 INFO [- ] 0%
2024-06-22 23:24:38,039 INFO waiting: 903673 / active: 9 / done: 96
2024-06-22 23:24:38,039 INFO ================================================================================
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO ================================================================================
2024-06-22 23:24:38,039 INFO 2024-06-22 23:22:18 10s elapsed
2024-06-22 23:24:38,039 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,039 INFO > Global (OK=908 KO=0 )
2024-06-22 23:24:38,039 INFO > restLoginInitiate (OK=413 KO=0 )
2024-06-22 23:24:38,039 INFO > restLoginUsernamePasswordCallback (OK=404 KO=0 )
2024-06-22 23:24:38,039 INFO > restloginProgressiveProfileCallback (OK=91 KO=0 )
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,039 INFO [- ] 0%
2024-06-22 23:24:38,039 INFO waiting: 903361 / active: 16 / done: 401
2024-06-22 23:24:38,039 INFO ================================================================================
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO
2024-06-22 23:24:38,039 INFO ================================================================================
2024-06-22 23:24:38,040 INFO 2024-06-22 23:22:23 15s elapsed
2024-06-22 23:24:38,040 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,040 INFO > Global (OK=2080 KO=0 )
2024-06-22 23:24:38,040 INFO > restLoginInitiate (OK=932 KO=0 )
2024-06-22 23:24:38,040 INFO > restLoginUsernamePasswordCallback (OK=918 KO=0 )
2024-06-22 23:24:38,040 INFO > restloginProgressiveProfileCallback (OK=230 KO=0 )
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,040 INFO [- ] 0%
2024-06-22 23:24:38,040 INFO waiting: 902839 / active: 27 / done: 912
2024-06-22 23:24:38,040 INFO ================================================================================
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO ================================================================================
2024-06-22 23:24:38,040 INFO 2024-06-22 23:22:28 20s elapsed
2024-06-22 23:24:38,040 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,040 INFO > Global (OK=3738 KO=0 )
2024-06-22 23:24:38,040 INFO > restLoginInitiate (OK=1660 KO=0 )
2024-06-22 23:24:38,040 INFO > restLoginUsernamePasswordCallback (OK=1642 KO=0 )
2024-06-22 23:24:38,040 INFO > restloginProgressiveProfileCallback (OK=436 KO=0 )
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,040 INFO [- ] 0%
2024-06-22 23:24:38,040 INFO waiting: 902110 / active: 32 / done: 1636
2024-06-22 23:24:38,040 INFO ================================================================================
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO ================================================================================
2024-06-22 23:24:38,040 INFO 2024-06-22 23:22:33 25s elapsed
2024-06-22 23:24:38,040 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,040 INFO > Global (OK=5835 KO=0 )
2024-06-22 23:24:38,040 INFO > restLoginInitiate (OK=2597 KO=0 )
2024-06-22 23:24:38,040 INFO > restLoginUsernamePasswordCallback (OK=2573 KO=0 )
2024-06-22 23:24:38,040 INFO > restloginProgressiveProfileCallback (OK=665 KO=0 )
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,040 INFO [- ] 0%
2024-06-22 23:24:38,040 INFO waiting: 901171 / active: 40 / done: 2567
2024-06-22 23:24:38,040 INFO ================================================================================
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO
2024-06-22 23:24:38,040 INFO ================================================================================
2024-06-22 23:24:38,041 INFO 2024-06-22 23:22:38 30s elapsed
2024-06-22 23:24:38,041 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,041 INFO > Global (OK=8401 KO=0 )
2024-06-22 23:24:38,041 INFO > restLoginInitiate (OK=3739 KO=0 )
2024-06-22 23:24:38,041 INFO > restLoginUsernamePasswordCallback (OK=3704 KO=0 )
2024-06-22 23:24:38,041 INFO > restloginProgressiveProfileCallback (OK=958 KO=0 )
2024-06-22 23:24:38,041 INFO
2024-06-22 23:24:38,041 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,041 INFO [- ] 0%
2024-06-22 23:24:38,041 INFO waiting: 900022 / active: 64 / done: 3692
2024-06-22 23:24:38,041 INFO ================================================================================
2024-06-22 23:24:38,041 INFO
2024-06-22 23:24:38,041 INFO
2024-06-22 23:24:38,041 INFO ================================================================================
2024-06-22 23:24:38,041 INFO 2024-06-22 23:22:43 35s elapsed
2024-06-22 23:24:38,041 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,041 INFO > Global (OK=11192 KO=0 )
2024-06-22 23:24:38,041 INFO > restLoginInitiate (OK=4968 KO=0 )
2024-06-22 23:24:38,041 INFO > restLoginUsernamePasswordCallback (OK=4935 KO=0 )
2024-06-22 23:24:38,041 INFO > restloginProgressiveProfileCallback (OK=1289 KO=0 )
2024-06-22 23:24:38,041 INFO
2024-06-22 23:24:38,041 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,041 INFO [- ] 0%
2024-06-22 23:24:38,041 INFO waiting: 898796 / active: 55 / done: 4927
2024-06-22 23:24:38,041 INFO ================================================================================
2024-06-22 23:24:38,041 INFO [...]
2024-06-22 23:24:38,045 INFO Content-Length: 332
2024-06-22 23:24:38,045 INFO Date: Sat, 22 Jun 2024 23:22:26 GMT
2024-06-22 23:24:38,045 INFO Alt-Svc: h3=":443"; ma=2592000,h3-29=":443"; ma=2592000
2024-06-22 23:24:38,045 INFO
2024-06-22 23:24:38,045 INFO body:
2024-06-22 23:24:38,045 INFO
2024-06-22 23:24:38,045 INFO
2024-06-22 23:24:38,045 INFO
2024-06-22 23:24:38,046 INFO 502 Server Error
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO Error: Server Error
2024-06-22 23:24:38,046 INFO The server encountered a temporary error and could not complete your request.
Please try again in 30 seconds.
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO <<<<<<<<<<<<<<<<<<<<<<<<<
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO ================================================================================
2024-06-22 23:24:38,046 INFO 2024-06-23 00:22:28 3620s elapsed
2024-06-22 23:24:38,046 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,046 INFO > Global (OK=2042612 KO=17 )
2024-06-22 23:24:38,046 INFO > restLoginInitiate (OK=901272 KO=8 )
2024-06-22 23:24:38,046 INFO > restLoginUsernamePasswordCallback (OK=901231 KO=6 )
2024-06-22 23:24:38,046 INFO > restloginProgressiveProfileCallback (OK=240109 KO=3 )
2024-06-22 23:24:38,046 INFO ---- Errors --------------------------------------------------------------------
2024-06-22 23:24:38,046 INFO > status.find.is(200), but actually found 502 17 (100.0%)
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,046 INFO [#########################################################################-] 99%
2024-06-22 23:24:38,046 INFO waiting: 2487 / active: 64 / done: 901227
2024-06-22 23:24:38,046 INFO ================================================================================
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO
2024-06-22 23:24:38,046 INFO ================================================================================
2024-06-22 23:24:38,046 INFO 2024-06-23 00:22:33 3625s elapsed
2024-06-22 23:24:38,046 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,046 INFO > Global (OK=2045509 KO=17 )
2024-06-22 23:24:38,046 INFO > restLoginInitiate (OK=902552 KO=8 )
2024-06-22 23:24:38,046 INFO > restLoginUsernamePasswordCallback (OK=902510 KO=6 )
2024-06-22 23:24:38,046 INFO > restloginProgressiveProfileCallback (OK=240447 KO=3 )
2024-06-22 23:24:38,047 INFO ---- Errors --------------------------------------------------------------------
2024-06-22 23:24:38,047 INFO > status.find.is(200), but actually found 502 17 (100.0%)
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,047 INFO [#########################################################################-] 99%
2024-06-22 23:24:38,047 INFO waiting: 1202 / active: 66 / done: 902510
2024-06-22 23:24:38,047 INFO ================================================================================
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO ================================================================================
2024-06-22 23:24:38,047 INFO 2024-06-23 00:22:38 3630s elapsed
2024-06-22 23:24:38,047 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,047 INFO > Global (OK=2048226 KO=17 )
2024-06-22 23:24:38,047 INFO > restLoginInitiate (OK=903757 KO=8 )
2024-06-22 23:24:38,047 INFO > restLoginUsernamePasswordCallback (OK=903708 KO=6 )
2024-06-22 23:24:38,047 INFO > restloginProgressiveProfileCallback (OK=240761 KO=3 )
2024-06-22 23:24:38,047 INFO ---- Errors --------------------------------------------------------------------
2024-06-22 23:24:38,047 INFO > status.find.is(200), but actually found 502 17 (100.0%)
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,047 INFO [#########################################################################-] 99%
2024-06-22 23:24:38,047 INFO waiting: 0 / active: 71 / done: 903707
2024-06-22 23:24:38,047 INFO ================================================================================
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO ================================================================================
2024-06-22 23:24:38,047 INFO 2024-06-23 00:22:38 3630s elapsed
2024-06-22 23:24:38,047 INFO ---- Requests ------------------------------------------------------------------
2024-06-22 23:24:38,047 INFO > Global (OK=2048321 KO=17 )
2024-06-22 23:24:38,047 INFO > restLoginInitiate (OK=903770 KO=8 )
2024-06-22 23:24:38,047 INFO > restLoginUsernamePasswordCallback (OK=903764 KO=6 )
2024-06-22 23:24:38,047 INFO > restloginProgressiveProfileCallback (OK=240787 KO=3 )
2024-06-22 23:24:38,047 INFO ---- Errors --------------------------------------------------------------------
2024-06-22 23:24:38,047 INFO > status.find.is(200), but actually found 502 17 (100.0%)
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,047 INFO ---- idc.login -----------------------------------------------------------------
2024-06-22 23:24:38,047 INFO [##########################################################################]100%
2024-06-22 23:24:38,047 INFO waiting: 0 / active: 0 / done: 903778
2024-06-22 23:24:38,047 INFO ================================================================================
2024-06-22 23:24:38,047 INFO
2024-06-22 23:24:38,048 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds
2024-06-22 23:24:38,048 INFO
2024-06-22 23:24:38,048 INFO ----------------------
2024-06-22 23:24:38,048 INFO [23/Jun/2024 00:22:38] Create tar file login-tree-250.tar.gz
2024-06-22 23:24:38,048 INFO ----------------------
2024-06-22 23:24:38,048 INFO RESULTS_FOLDER=/results/gatling/
2024-06-22 23:24:38,048 INFO RESULT_FOLDER=login-tree-250
2024-06-22 23:24:38,048 INFO SIMULATION_PATH=login-tree-250/loadtest-20240622222207511
2024-06-22 23:24:38,048 INFO PWD=/results/gatling/login-tree-250/loadtest-20240622222207511
2024-06-22 23:24:38,048 INFO [cmd] tar -czvf /results/gatling//login-tree-250.tar.gz --exclude=simulation.log *
2024-06-22 23:24:38,048 INFO /results/gatling
2024-06-22 23:24:38,048 INFO
2024-06-22 23:24:38,048 INFO ----------------------
2024-06-22 23:24:38,048 INFO [23/Jun/2024 00:22:38] Update /results/gatling//latest
2024-06-22 23:24:38,048 INFO ----------------------
2024-06-22 23:24:38,048 INFO
2024-06-22 23:24:38,048 INFO ----------------------
2024-06-22 23:24:38,048 INFO [23/Jun/2024 00:22:38] Update /results/gatling//latest.tar.gz
2024-06-22 23:24:38,048 INFO ----------------------
2024-06-22 23:24:38,048 INFO
________________________________________________________________________________
[2024-06-22 23:24:38] login-tree-250 show_stderr : Display stderr order
________________________________________________________________________________
2024-06-22 23:24:38,048 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-250/stderr.txt is small (0 lines), printing all lines:
2024-06-22 23:24:38,049 DEBUG ----- output -----
2024-06-22 23:24:38,049 DEBUG
2024-06-22 23:24:38,049 INFO
________________________________________________________________________________
[2024-06-22 23:24:38] login-tree-250 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 23:24:38] login-tree-250 print_overseer_logs : Print overseer logs from the K8s pod
________________________________________________________________________________
Task result is INIT no need to display logs.
________________________________________________________________________________
[2024-06-22 23:24:38] login-tree-250 post : Post
________________________________________________________________________________
Setting result to PASS
Task has been successfully stopped