--Task-- name: login-tree-180 enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: am target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates bellow loop: False interval: None dependencies: [] wait_for: ['login-tree-150'] preceding_task: login-tree-150 options: {'testCase': 'idc.login', 'testMode': 'open', 'throughput': 180, '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-12 00:41:07] login-tree-180 pre : N/A ________________________________________________________________________________ 2024-06-12 00:41:07 - INFO: Timeout for this Task has changed to 1h 12m (4320 seconds) 2024-06-12 00:41:07 - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/login-tree-180/stdout.txt Pod log : /results/orders/login-tree-180 2024-06-12 00:41:07,246 INFO 2024-06-12 00:41:07,246 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:07,247 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:07,579 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:07,579 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:07,579 DEBUG --- stdout --- 2024-06-12 00:41:07,579 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:07,579 DEBUG --- stderr --- 2024-06-12 00:41:07,579 DEBUG ________________________________________________________________________________ [2024-06-12 00:41:07] login-tree-180 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-06-12 00:41:07,580 INFO 2024-06-12 00:41:07,580 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:07,580 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:07,889 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:07,890 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:07,890 DEBUG --- stdout --- 2024-06-12 00:41:07,890 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:07,890 DEBUG --- stderr --- 2024-06-12 00:41:07,890 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1protect" --results-folder /results/gatling/login-tree-180 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-06-12 00:41:07] login-tree-180 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-06-12 00:41:07,891 INFO 2024-06-12 00:41:07,891 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:07,891 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:08,256 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:08,257 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:08,257 DEBUG --- stdout --- 2024-06-12 00:41:08,257 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:08,257 DEBUG --- stderr --- 2024-06-12 00:41:08,257 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/order.json { "name": "login-tree-180", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description \"idc.login@ema-perf-p1protect\" --results-folder /results/gatling/login-tree-180 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/login-tree-180", "environment": { "JAVA_OPTS": "-Dam_host=openam-ema-perf-p1protect.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dduration=3600 -Didc=true -Dlogin_cookie=956c97cd5742324 -Dlogin_tree=Login -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Drampup_duration=30 -Drealm=alpha -DtestCase=idc.login -DtestMode=open -Dthroughput=180 -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-06-12 00:41:08] login-tree-180 loop_until_ready : Wait for preceding task to finish if it uses same Overseer as a source, then loop for max_time until Overseer pod status is READY to accept order :param max_time: periodically check overseer status for this many seconds at maximum :param interval: interval between two periodical checks :return: ________________________________________________________________________________ 2024-06-12 00:41:08,258 INFO 2024-06-12 00:41:08,258 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-06-12 00:41:08,258 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-12 00:41:08,490 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 00:41:08,490 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:08,490 DEBUG --- stdout --- 2024-06-12 00:41:08,490 DEBUG WAITING 2024-06-12 00:41:08,490 DEBUG --- stderr --- 2024-06-12 00:41:08,490 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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 7 100 7 0 0 33 0 --:--:-- --:--:-- --:--:-- 33 ________________________________________________________________________________ [2024-06-12 00:41:08] login-tree-180 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-06-12 00:41:08,491 INFO 2024-06-12 00:41:08,491 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:08,491 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:08,793 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:08,794 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:08,794 DEBUG --- stdout --- 2024-06-12 00:41:08,794 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:08,794 DEBUG --- stderr --- 2024-06-12 00:41:08,794 DEBUG 2024-06-12 00:41:08,794 INFO 2024-06-12 00:41:08,794 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/order.json overseer-0-6c7d65494f-sln6z:/results/orders/order.json 2024-06-12 00:41:08,794 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 00:41:10,154 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:10,154 DEBUG --- stdout --- 2024-06-12 00:41:10,154 DEBUG 2024-06-12 00:41:10,154 DEBUG --- stderr --- 2024-06-12 00:41:10,154 DEBUG Order has been successfully forwarded to overseer 2024-06-12 00:41:10 - INFO: Check if Gatling simulation started successfully 2024-06-12 00:41:10,155 INFO 2024-06-12 00:41:10,155 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 00:41:10,155 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 00:41:10,462 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 00:41:10,463 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:10,463 DEBUG --- stdout --- 2024-06-12 00:41:10,463 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 00:41:10,463 DEBUG --- stderr --- 2024-06-12 00:41:10,463 DEBUG 2024-06-12 00:41:10,463 INFO 2024-06-12 00:41:10,463 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- head -n 25 /results/orders/login-tree-180/stdout.txt | grep "started..." 2024-06-12 00:41:10,463 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-06-12 00:41:11,200 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-06-12 00:41:16,947 INFO [loop_until]: Function succeeded after 6s (rc=0) - expected pattern found 2024-06-12 00:41:16,947 INFO [loop_until]: OK (rc = 0) 2024-06-12 00:41:16,947 DEBUG --- stdout --- 2024-06-12 00:41:16,947 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling Gatling 3.11.3 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-06-12 00:41:16,947 DEBUG --- stderr --- 2024-06-12 00:41:16,947 DEBUG ________________________________________________________________________________ [2024-06-12 00:41:16] login-tree-180 wait_stop_by_itself : Wait for the order to stop by itself ________________________________________________________________________________ Task may stop by itself (no stop defined in configuration file), wait until overseer ends the order - timeout is 1h 12m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 2m, retry Order is running since 4m, retry Order is running since 6m, 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 06s, retry Order is running since 46m 07s, retry Order is running since 48m 07s, retry Order is running since 50m 07s, 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-12 01:43:26] login-tree-180 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-06-12 01:43:27,000 INFO 2024-06-12 01:43:27,000 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:27,000 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:27,323 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:27,324 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:27,324 DEBUG --- stdout --- 2024-06-12 01:43:27,324 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:27,324 DEBUG --- stderr --- 2024-06-12 01:43:27,324 DEBUG 2024-06-12 01:43:27,324 INFO 2024-06-12 01:43:27,324 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z -- rm -f /results/orders/order.json 2024-06-12 01:43:27,324 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 01:43:28,095 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:28,095 DEBUG --- stdout --- 2024-06-12 01:43:28,095 DEBUG 2024-06-12 01:43:28,095 DEBUG --- stderr --- 2024-06-12 01:43:28,095 DEBUG Order ran during 1h 02m ________________________________________________________________________________ [2024-06-12 01:43:28] login-tree-180 download_logs : Download individual order files ________________________________________________________________________________ 2024-06-12 01:43:28 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/order.json 2024-06-12 01:43:28,096 INFO 2024-06-12 01:43:28,096 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:28,096 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:28,404 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:28,404 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:28,404 DEBUG --- stdout --- 2024-06-12 01:43:28,404 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:28,404 DEBUG --- stderr --- 2024-06-12 01:43:28,404 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 01:43:28,404 INFO 2024-06-12 01:43:28,404 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:28,404 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:28,706 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:28,707 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:28,709 DEBUG --- stdout --- 2024-06-12 01:43:28,710 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:28,710 DEBUG --- stderr --- 2024-06-12 01:43:28,710 DEBUG 2024-06-12 01:43:28,710 INFO 2024-06-12 01:43:28,711 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-180/rc.txt" ]; then echo "/results/orders/login-tree-180/rc.txt file found"; exit 0; else echo "/results/orders/login-tree-180/rc.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/rc.txt file found" 2024-06-12 01:43:28,711 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 01:43:29,519 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 01:43:29,520 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:29,520 DEBUG --- stdout --- 2024-06-12 01:43:29,520 DEBUG /results/orders/login-tree-180/rc.txt file found 2024-06-12 01:43:29,520 DEBUG --- stderr --- 2024-06-12 01:43:29,520 DEBUG 2024-06-12 01:43:29,520 INFO 2024-06-12 01:43:29,521 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:29,521 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:29,824 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:29,824 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:29,824 DEBUG --- stdout --- 2024-06-12 01:43:29,824 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:29,825 DEBUG --- stderr --- 2024-06-12 01:43:29,825 DEBUG 2024-06-12 01:43:29,825 INFO 2024-06-12 01:43:29,825 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-180/rc.txt 2024-06-12 01:43:30,688 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 01:43:30,689 DEBUG --- stdout --- 2024-06-12 01:43:30,689 DEBUG 4 /results/orders/login-tree-180/rc.txt 2024-06-12 01:43:30,689 DEBUG --- stderr --- 2024-06-12 01:43:30,689 DEBUG 2024-06-12 01:43:30,689 INFO - Download file (size 4k) 2024-06-12 01:43:30,689 INFO 2024-06-12 01:43:30,689 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:30,689 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:31,001 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:31,002 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:31,002 DEBUG --- stdout --- 2024-06-12 01:43:31,002 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:31,002 DEBUG --- stderr --- 2024-06-12 01:43:31,002 DEBUG source : /results/orders/login-tree-180/rc.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/rc.txt 2024-06-12 01:43:31,002 INFO 2024-06-12 01:43:31,002 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:31,002 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:31,313 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:31,313 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:31,313 DEBUG --- stdout --- 2024-06-12 01:43:31,313 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:31,313 DEBUG --- stderr --- 2024-06-12 01:43:31,313 DEBUG 2024-06-12 01:43:31,314 INFO 2024-06-12 01:43:31,314 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-180/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/rc.txt 2024-06-12 01:43:31,314 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 01:43:32,100 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:32,100 DEBUG --- stdout --- 2024-06-12 01:43:32,100 DEBUG tar: Removing leading `/' from member names 2024-06-12 01:43:32,100 DEBUG --- stderr --- 2024-06-12 01:43:32,100 DEBUG 2024-06-12 01:43:32,101 INFO 2024-06-12 01:43:32,101 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:32,101 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:32,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:32,417 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:32,417 DEBUG --- stdout --- 2024-06-12 01:43:32,417 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:32,417 DEBUG --- stderr --- 2024-06-12 01:43:32,417 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 01:43:32,418 INFO 2024-06-12 01:43:32,418 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:32,418 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:32,726 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:32,727 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:32,727 DEBUG --- stdout --- 2024-06-12 01:43:32,727 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:32,727 DEBUG --- stderr --- 2024-06-12 01:43:32,727 DEBUG 2024-06-12 01:43:32,727 INFO 2024-06-12 01:43:32,728 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-180/stderr.txt" ]; then echo "/results/orders/login-tree-180/stderr.txt file found"; exit 0; else echo "/results/orders/login-tree-180/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/stderr.txt file found" 2024-06-12 01:43:32,728 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 01:43:33,537 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 01:43:33,538 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:33,538 DEBUG --- stdout --- 2024-06-12 01:43:33,538 DEBUG /results/orders/login-tree-180/stderr.txt file found 2024-06-12 01:43:33,538 DEBUG --- stderr --- 2024-06-12 01:43:33,538 DEBUG 2024-06-12 01:43:33,538 INFO 2024-06-12 01:43:33,538 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:33,538 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:33,840 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:33,840 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:33,842 DEBUG --- stdout --- 2024-06-12 01:43:33,842 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:33,842 DEBUG --- stderr --- 2024-06-12 01:43:33,842 DEBUG 2024-06-12 01:43:33,842 INFO 2024-06-12 01:43:33,842 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-180/stderr.txt 2024-06-12 01:43:34,643 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 01:43:34,643 DEBUG --- stdout --- 2024-06-12 01:43:34,643 DEBUG 0 /results/orders/login-tree-180/stderr.txt 2024-06-12 01:43:34,643 DEBUG --- stderr --- 2024-06-12 01:43:34,643 DEBUG 2024-06-12 01:43:34,643 INFO - Download file (size 0k) 2024-06-12 01:43:34,644 INFO 2024-06-12 01:43:34,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-12 01:43:34,644 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:34,957 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:34,957 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:34,957 DEBUG --- stdout --- 2024-06-12 01:43:34,957 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:34,957 DEBUG --- stderr --- 2024-06-12 01:43:34,957 DEBUG source : /results/orders/login-tree-180/stderr.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stderr.txt 2024-06-12 01:43:34,957 INFO 2024-06-12 01:43:34,958 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:34,958 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:35,266 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:35,267 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:35,267 DEBUG --- stdout --- 2024-06-12 01:43:35,267 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:35,267 DEBUG --- stderr --- 2024-06-12 01:43:35,267 DEBUG 2024-06-12 01:43:35,268 INFO 2024-06-12 01:43:35,268 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-180/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stderr.txt 2024-06-12 01:43:35,268 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 01:43:36,001 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:36,001 DEBUG --- stdout --- 2024-06-12 01:43:36,001 DEBUG tar: Removing leading `/' from member names 2024-06-12 01:43:36,001 DEBUG --- stderr --- 2024-06-12 01:43:36,001 DEBUG 2024-06-12 01:43:36,002 INFO 2024-06-12 01:43:36,002 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:36,002 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:36,299 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:36,299 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:36,299 DEBUG --- stdout --- 2024-06-12 01:43:36,299 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:36,299 DEBUG --- stderr --- 2024-06-12 01:43:36,299 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 01:43:36,299 INFO 2024-06-12 01:43:36,299 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:36,299 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:36,604 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:36,605 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:36,605 DEBUG --- stdout --- 2024-06-12 01:43:36,605 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:36,605 DEBUG --- stderr --- 2024-06-12 01:43:36,605 DEBUG 2024-06-12 01:43:36,606 INFO 2024-06-12 01:43:36,606 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-180/stdout.txt" ]; then echo "/results/orders/login-tree-180/stdout.txt file found"; exit 0; else echo "/results/orders/login-tree-180/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/stdout.txt file found" 2024-06-12 01:43:36,606 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 01:43:37,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 01:43:37,510 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:37,510 DEBUG --- stdout --- 2024-06-12 01:43:37,510 DEBUG /results/orders/login-tree-180/stdout.txt file found 2024-06-12 01:43:37,511 DEBUG --- stderr --- 2024-06-12 01:43:37,511 DEBUG 2024-06-12 01:43:37,511 INFO 2024-06-12 01:43:37,511 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:37,511 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:37,814 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:37,815 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:37,815 DEBUG --- stdout --- 2024-06-12 01:43:37,815 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:37,815 DEBUG --- stderr --- 2024-06-12 01:43:37,815 DEBUG 2024-06-12 01:43:37,815 INFO 2024-06-12 01:43:37,815 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-180/stdout.txt 2024-06-12 01:43:38,618 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 01:43:38,618 DEBUG --- stdout --- 2024-06-12 01:43:38,618 DEBUG 752 /results/orders/login-tree-180/stdout.txt 2024-06-12 01:43:38,618 DEBUG --- stderr --- 2024-06-12 01:43:38,618 DEBUG 2024-06-12 01:43:38,618 INFO - Download file (size 752k) 2024-06-12 01:43:38,619 INFO 2024-06-12 01:43:38,619 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:38,619 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:38,932 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:38,932 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:38,934 DEBUG --- stdout --- 2024-06-12 01:43:38,934 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:38,934 DEBUG --- stderr --- 2024-06-12 01:43:38,934 DEBUG source : /results/orders/login-tree-180/stdout.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stdout.txt 2024-06-12 01:43:38,935 INFO 2024-06-12 01:43:38,935 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:38,935 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:39,245 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:39,245 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:39,245 DEBUG --- stdout --- 2024-06-12 01:43:39,245 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:39,245 DEBUG --- stderr --- 2024-06-12 01:43:39,245 DEBUG 2024-06-12 01:43:39,246 INFO 2024-06-12 01:43:39,246 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-180/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stdout.txt 2024-06-12 01:43:39,246 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 01:43:40,321 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:40,321 DEBUG --- stdout --- 2024-06-12 01:43:40,321 DEBUG tar: Removing leading `/' from member names 2024-06-12 01:43:40,321 DEBUG --- stderr --- 2024-06-12 01:43:40,321 DEBUG ________________________________________________________________________________ [2024-06-12 01:43:40] login-tree-180 show_cmd : Display order command ________________________________________________________________________________ 2024-06-12 01:43:40,322 INFO 2024-06-12 01:43:40,322 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:40,322 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:40,624 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:40,625 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:40,625 DEBUG --- stdout --- 2024-06-12 01:43:40,625 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:40,625 DEBUG --- stderr --- 2024-06-12 01:43:40,625 DEBUG Checking pod: overseer-0-6c7d65494f-sln6z for file exists, if not wait. 2024-06-12 01:43:40,625 INFO 2024-06-12 01:43:40,625 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:40,625 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:40,923 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:40,923 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:40,923 DEBUG --- stdout --- 2024-06-12 01:43:40,924 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:40,924 DEBUG --- stderr --- 2024-06-12 01:43:40,924 DEBUG 2024-06-12 01:43:40,924 INFO 2024-06-12 01:43:40,924 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- bash -c 'if [ -f "/results/orders/login-tree-180/cmd.txt" ]; then echo "/results/orders/login-tree-180/cmd.txt file found"; exit 0; else echo "/results/orders/login-tree-180/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/login-tree-180/cmd.txt file found" 2024-06-12 01:43:40,924 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-06-12 01:43:41,725 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-06-12 01:43:41,726 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:41,726 DEBUG --- stdout --- 2024-06-12 01:43:41,726 DEBUG /results/orders/login-tree-180/cmd.txt file found 2024-06-12 01:43:41,726 DEBUG --- stderr --- 2024-06-12 01:43:41,726 DEBUG 2024-06-12 01:43:41,726 INFO 2024-06-12 01:43:41,726 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:41,726 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:42,026 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:42,027 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:42,027 DEBUG --- stdout --- 2024-06-12 01:43:42,027 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:42,027 DEBUG --- stderr --- 2024-06-12 01:43:42,027 DEBUG 2024-06-12 01:43:42,027 INFO 2024-06-12 01:43:42,027 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-6c7d65494f-sln6z --stdin -- du -k /results/orders/login-tree-180/cmd.txt 2024-06-12 01:43:42,821 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-06-12 01:43:42,821 DEBUG --- stdout --- 2024-06-12 01:43:42,821 DEBUG 4 /results/orders/login-tree-180/cmd.txt 2024-06-12 01:43:42,821 DEBUG --- stderr --- 2024-06-12 01:43:42,821 DEBUG 2024-06-12 01:43:42,821 INFO - Download file (size 4k) 2024-06-12 01:43:42,822 INFO 2024-06-12 01:43:42,822 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:42,822 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:43,121 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:43,121 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:43,122 DEBUG --- stdout --- 2024-06-12 01:43:43,122 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:43,122 DEBUG --- stderr --- 2024-06-12 01:43:43,122 DEBUG source : /results/orders/login-tree-180/cmd.txt @ overseer-0-6c7d65494f-sln6z destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/cmd.txt 2024-06-12 01:43:43,122 INFO 2024-06-12 01:43:43,122 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-06-12 01:43:43,122 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-06-12 01:43:43,419 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-06-12 01:43:43,419 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:43,419 DEBUG --- stdout --- 2024-06-12 01:43:43,419 DEBUG overseer-0-6c7d65494f-sln6z 2024-06-12 01:43:43,419 DEBUG --- stderr --- 2024-06-12 01:43:43,419 DEBUG 2024-06-12 01:43:43,419 INFO 2024-06-12 01:43:43,419 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-6c7d65494f-sln6z:/results/orders/login-tree-180/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/cmd.txt 2024-06-12 01:43:43,419 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-06-12 01:43:44,148 INFO [loop_until]: OK (rc = 0) 2024-06-12 01:43:44,149 DEBUG --- stdout --- 2024-06-12 01:43:44,149 DEBUG tar: Removing leading `/' from member names 2024-06-12 01:43:44,149 DEBUG --- stderr --- 2024-06-12 01:43:44,149 DEBUG 2024-06-12 01:43:44,149 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/cmd.txt is small (1 lines), printing all lines: 2024-06-12 01:43:44,149 DEBUG ----- output ----- 2024-06-12 01:43:44,149 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling-open/ --run-description "idc.login@ema-perf-p1protect" --results-folder /results/gatling/login-tree-180 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-06-12 01:43:44,149 INFO ________________________________________________________________________________ [2024-06-12 01:43:44] login-tree-180 show_rc : Display rc order ________________________________________________________________________________ 2024-06-12 01:43:44,149 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/rc.txt is small (1 lines), printing all lines: 2024-06-12 01:43:44,149 DEBUG ----- output ----- 2024-06-12 01:43:44,149 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-06-12 01:43:44,149 INFO ________________________________________________________________________________ [2024-06-12 01:43:44] login-tree-180 show_stdout : Display stdout order ________________________________________________________________________________ 2024-06-12 01:43:44,151 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stdout.txt is 12388 lines): 2024-06-12 01:43:44,152 DEBUG ----- output ----- 2024-06-12 01:43:44,152 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-06-12 01:43:44,152 INFO Gatling 3.11.3 is available! (you're using 3.9.5) 2024-06-12 01:43:44,152 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,152 INFO ================================================================================ 2024-06-12 01:43:44,152 INFO 2024-06-12 01:41:19 5s elapsed 2024-06-12 01:43:44,152 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,152 INFO > Global (OK=161 KO=0 ) 2024-06-12 01:43:44,152 INFO > restLoginInitiate (OK=73 KO=0 ) 2024-06-12 01:43:44,152 INFO > restLoginUsernamePasswordCallback (OK=69 KO=0 ) 2024-06-12 01:43:44,152 INFO > restloginProgressiveProfileCallback (OK=19 KO=0 ) 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,152 INFO [- ] 0% 2024-06-12 01:43:44,152 INFO waiting: 651923 / active: 7 / done: 69 2024-06-12 01:43:44,152 INFO ================================================================================ 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,152 INFO ================================================================================ 2024-06-12 01:43:44,152 INFO 2024-06-12 01:41:24 10s elapsed 2024-06-12 01:43:44,152 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,152 INFO > Global (OK=648 KO=0 ) 2024-06-12 01:43:44,152 INFO > restLoginInitiate (OK=296 KO=0 ) 2024-06-12 01:43:44,152 INFO > restLoginUsernamePasswordCallback (OK=286 KO=0 ) 2024-06-12 01:43:44,152 INFO > restloginProgressiveProfileCallback (OK=66 KO=0 ) 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,152 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,152 INFO [- ] 0% 2024-06-12 01:43:44,152 INFO waiting: 651697 / active: 20 / done: 282 2024-06-12 01:43:44,152 INFO ================================================================================ 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,152 INFO 2024-06-12 01:43:44,153 INFO ================================================================================ 2024-06-12 01:43:44,153 INFO 2024-06-12 01:41:29 15s elapsed 2024-06-12 01:43:44,153 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,153 INFO > Global (OK=1475 KO=0 ) 2024-06-12 01:43:44,153 INFO > restLoginInitiate (OK=668 KO=0 ) 2024-06-12 01:43:44,153 INFO > restLoginUsernamePasswordCallback (OK=652 KO=0 ) 2024-06-12 01:43:44,153 INFO > restloginProgressiveProfileCallback (OK=155 KO=0 ) 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,153 INFO [- ] 0% 2024-06-12 01:43:44,153 INFO waiting: 651322 / active: 27 / done: 650 2024-06-12 01:43:44,153 INFO ================================================================================ 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO ================================================================================ 2024-06-12 01:43:44,153 INFO 2024-06-12 01:41:34 20s elapsed 2024-06-12 01:43:44,153 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,153 INFO > Global (OK=2618 KO=0 ) 2024-06-12 01:43:44,153 INFO > restLoginInitiate (OK=1188 KO=0 ) 2024-06-12 01:43:44,153 INFO > restLoginUsernamePasswordCallback (OK=1171 KO=0 ) 2024-06-12 01:43:44,153 INFO > restloginProgressiveProfileCallback (OK=259 KO=0 ) 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,153 INFO [- ] 0% 2024-06-12 01:43:44,153 INFO waiting: 650797 / active: 38 / done: 1164 2024-06-12 01:43:44,153 INFO ================================================================================ 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO ================================================================================ 2024-06-12 01:43:44,153 INFO 2024-06-12 01:41:39 25s elapsed 2024-06-12 01:43:44,153 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,153 INFO > Global (OK=4100 KO=0 ) 2024-06-12 01:43:44,153 INFO > restLoginInitiate (OK=1862 KO=0 ) 2024-06-12 01:43:44,153 INFO > restLoginUsernamePasswordCallback (OK=1837 KO=0 ) 2024-06-12 01:43:44,153 INFO > restloginProgressiveProfileCallback (OK=401 KO=0 ) 2024-06-12 01:43:44,153 INFO 2024-06-12 01:43:44,153 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,153 INFO [- ] 0% 2024-06-12 01:43:44,153 INFO waiting: 650121 / active: 53 / done: 1825 2024-06-12 01:43:44,153 INFO ================================================================================ 2024-06-12 01:43:44,154 INFO 2024-06-12 01:43:44,154 INFO 2024-06-12 01:43:44,154 INFO ================================================================================ 2024-06-12 01:43:44,154 INFO 2024-06-12 01:41:44 30s elapsed 2024-06-12 01:43:44,154 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,154 INFO > Global (OK=5919 KO=0 ) 2024-06-12 01:43:44,154 INFO > restLoginInitiate (OK=2682 KO=0 ) 2024-06-12 01:43:44,154 INFO > restLoginUsernamePasswordCallback (OK=2653 KO=0 ) 2024-06-12 01:43:44,154 INFO > restloginProgressiveProfileCallback (OK=584 KO=0 ) 2024-06-12 01:43:44,154 INFO 2024-06-12 01:43:44,154 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,154 INFO [- ] 0% 2024-06-12 01:43:44,154 INFO waiting: 649297 / active: 56 / done: 2646 2024-06-12 01:43:44,154 INFO ================================================================================ 2024-06-12 01:43:44,154 INFO 2024-06-12 01:43:44,154 INFO 2024-06-12 01:43:44,154 INFO ================================================================================ 2024-06-12 01:43:44,154 INFO 2024-06-12 01:41:49 35s elapsed 2024-06-12 01:43:44,154 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,154 INFO > Global (OK=7920 KO=0 ) 2024-06-12 01:43:44,154 INFO > restLoginInitiate (OK=3582 KO=0 ) 2024-06-12 01:43:44,154 INFO > restLoginUsernamePasswordCallback (OK=3557 KO=0 ) 2024-06-12 01:43:44,154 INFO > restloginProgressiveProfileCallback (OK=781 KO=0 ) 2024-06-12 01:43:44,154 INFO 2024-06-12 01:43:44,154 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,154 INFO [- ] 0% 2024-06-12 01:43:44,154 INFO waiting: 648397 / active: 51 / done: 3551 2024-06-12 01:43:44,154 INFO ================================================================================ 2024-06-12 01:43:44,154 INFO [...] 2024-06-12 01:43:44,157 INFO > status.find.is(200), but actually found 502 3 (75.00%) 2024-06-12 01:43:44,157 INFO > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 (25.00%) 2024-06-12 01:43:44,157 INFO r 10000ms 2024-06-12 01:43:44,157 INFO 2024-06-12 01:43:44,157 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,157 INFO [#########################################################################-] 99% 2024-06-12 01:43:44,157 INFO waiting: 2776 / active: 74 / done: 649149 2024-06-12 01:43:44,158 INFO ================================================================================ 2024-06-12 01:43:44,158 INFO 2024-06-12 01:43:44,158 INFO 2024-06-12 01:43:44,158 INFO ================================================================================ 2024-06-12 01:43:44,158 INFO 2024-06-12 02:41:34 3620s elapsed 2024-06-12 01:43:44,158 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,158 INFO > Global (OK=1458547 KO=4 ) 2024-06-12 01:43:44,158 INFO > restLoginInitiate (OK=650142 KO=4 ) 2024-06-12 01:43:44,158 INFO > restLoginUsernamePasswordCallback (OK=650110 KO=0 ) 2024-06-12 01:43:44,158 INFO > restloginProgressiveProfileCallback (OK=158295 KO=0 ) 2024-06-12 01:43:44,158 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 01:43:44,158 INFO > status.find.is(200), but actually found 502 3 (75.00%) 2024-06-12 01:43:44,158 INFO > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 (25.00%) 2024-06-12 01:43:44,158 INFO r 10000ms 2024-06-12 01:43:44,158 INFO 2024-06-12 01:43:44,158 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,158 INFO [#########################################################################-] 99% 2024-06-12 01:43:44,158 INFO waiting: 1834 / active: 59 / done: 650106 2024-06-12 01:43:44,158 INFO ================================================================================ 2024-06-12 01:43:44,158 INFO 2024-06-12 01:43:44,158 INFO 2024-06-12 01:43:44,158 INFO ================================================================================ 2024-06-12 01:43:44,158 INFO 2024-06-12 02:41:39 3625s elapsed 2024-06-12 01:43:44,158 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,158 INFO > Global (OK=1460630 KO=4 ) 2024-06-12 01:43:44,158 INFO > restLoginInitiate (OK=651055 KO=4 ) 2024-06-12 01:43:44,158 INFO > restLoginUsernamePasswordCallback (OK=651024 KO=0 ) 2024-06-12 01:43:44,158 INFO > restloginProgressiveProfileCallback (OK=158551 KO=0 ) 2024-06-12 01:43:44,158 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 01:43:44,158 INFO > status.find.is(200), but actually found 502 3 (75.00%) 2024-06-12 01:43:44,158 INFO > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 (25.00%) 2024-06-12 01:43:44,158 INFO r 10000ms 2024-06-12 01:43:44,158 INFO 2024-06-12 01:43:44,158 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,158 INFO [#########################################################################-] 99% 2024-06-12 01:43:44,158 INFO waiting: 920 / active: 62 / done: 651017 2024-06-12 01:43:44,159 INFO ================================================================================ 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO ================================================================================ 2024-06-12 01:43:44,159 INFO 2024-06-12 02:41:44 3630s elapsed 2024-06-12 01:43:44,159 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,159 INFO > Global (OK=1462699 KO=4 ) 2024-06-12 01:43:44,159 INFO > restLoginInitiate (OK=651973 KO=4 ) 2024-06-12 01:43:44,159 INFO > restLoginUsernamePasswordCallback (OK=651937 KO=0 ) 2024-06-12 01:43:44,159 INFO > restloginProgressiveProfileCallback (OK=158789 KO=0 ) 2024-06-12 01:43:44,159 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 01:43:44,159 INFO > status.find.is(200), but actually found 502 3 (75.00%) 2024-06-12 01:43:44,159 INFO > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 (25.00%) 2024-06-12 01:43:44,159 INFO r 10000ms 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,159 INFO [#########################################################################-] 99% 2024-06-12 01:43:44,159 INFO waiting: 0 / active: 70 / done: 651929 2024-06-12 01:43:44,159 INFO ================================================================================ 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO ================================================================================ 2024-06-12 01:43:44,159 INFO 2024-06-12 02:41:44 3630s elapsed 2024-06-12 01:43:44,159 INFO ---- Requests ------------------------------------------------------------------ 2024-06-12 01:43:44,159 INFO > Global (OK=1462806 KO=4 ) 2024-06-12 01:43:44,159 INFO > restLoginInitiate (OK=651995 KO=4 ) 2024-06-12 01:43:44,159 INFO > restLoginUsernamePasswordCallback (OK=651995 KO=0 ) 2024-06-12 01:43:44,159 INFO > restloginProgressiveProfileCallback (OK=158816 KO=0 ) 2024-06-12 01:43:44,159 INFO ---- Errors -------------------------------------------------------------------- 2024-06-12 01:43:44,159 INFO > status.find.is(200), but actually found 502 3 (75.00%) 2024-06-12 01:43:44,159 INFO > i.n.h.s.SslHandshakeTimeoutException: handshake timed out afte 1 (25.00%) 2024-06-12 01:43:44,159 INFO r 10000ms 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO ---- idc.login ----------------------------------------------------------------- 2024-06-12 01:43:44,159 INFO [##########################################################################]100% 2024-06-12 01:43:44,159 INFO waiting: 0 / active: 0 / done: 651999 2024-06-12 01:43:44,159 INFO ================================================================================ 2024-06-12 01:43:44,159 INFO 2024-06-12 01:43:44,159 INFO Simulation com.forgerock.gatling.Loadtest completed in 3630 seconds 2024-06-12 01:43:44,160 INFO 2024-06-12 01:43:44,160 INFO ---------------------- 2024-06-12 01:43:44,160 INFO [12/Jun/2024 02:41:44] Create tar file login-tree-180.tar.gz 2024-06-12 01:43:44,160 INFO ---------------------- 2024-06-12 01:43:44,160 INFO RESULTS_FOLDER=/results/gatling/ 2024-06-12 01:43:44,160 INFO RESULT_FOLDER=login-tree-180 2024-06-12 01:43:44,160 INFO SIMULATION_PATH=login-tree-180/loadtest-20240612004113825 2024-06-12 01:43:44,160 INFO PWD=/results/gatling/login-tree-180/loadtest-20240612004113825 2024-06-12 01:43:44,160 INFO [cmd] tar -czvf /results/gatling//login-tree-180.tar.gz --exclude=simulation.log * 2024-06-12 01:43:44,160 INFO /results/gatling 2024-06-12 01:43:44,160 INFO 2024-06-12 01:43:44,160 INFO ---------------------- 2024-06-12 01:43:44,160 INFO [12/Jun/2024 02:41:44] Update /results/gatling//latest 2024-06-12 01:43:44,160 INFO ---------------------- 2024-06-12 01:43:44,160 INFO 2024-06-12 01:43:44,160 INFO ---------------------- 2024-06-12 01:43:44,160 INFO [12/Jun/2024 02:41:44] Update /results/gatling//latest.tar.gz 2024-06-12 01:43:44,160 INFO ---------------------- 2024-06-12 01:43:44,160 INFO ________________________________________________________________________________ [2024-06-12 01:43:44] login-tree-180 show_stderr : Display stderr order ________________________________________________________________________________ 2024-06-12 01:43:44,160 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/idc_login_tree/07_Scenario_scenario/05_login-tree-180/stderr.txt is small (0 lines), printing all lines: 2024-06-12 01:43:44,160 DEBUG ----- output ----- 2024-06-12 01:43:44,160 DEBUG 2024-06-12 01:43:44,160 INFO ________________________________________________________________________________ [2024-06-12 01:43:44] login-tree-180 check_rc_and_logs : Check order's outputs ________________________________________________________________________________ PASS: no error found in rc log PASS: no error found in stdout log PASS: no error found in stderr log ________________________________________________________________________________ [2024-06-12 01:43:44] login-tree-180 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-06-12 01:43:44] login-tree-180 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped