--Task-- name: authn-users-6000 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: [] preceding_task: None options: {'testMode': 'open', 'testCase': 'am.AMAuthN', 'throughput': 6000, 'duration': 1800} group_name: None Current dir: /mnt/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-04-15 22:09:00] authn-users-6000 pre : N/A ________________________________________________________________________________ [2024-04-15 22:09:00] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-15 22:09:00] - INFO: timeout was calculated based on simulation duration parameter Pod log file : /results/orders/authn-users-6000/stdout.txt Pod log : /results/orders/authn-users-6000 2024-04-15 22:09:00,380 INFO 2024-04-15 22:09:00,380 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-04-15 22:09:00,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:09:00,719 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:09:00,719 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:00,719 DEBUG --- stdout --- 2024-04-15 22:09:00,720 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:09:00,720 DEBUG --- stderr --- 2024-04-15 22:09:00,720 DEBUG [2024-04-15 22:09:00] - INFO: Get overseer pod resources requests memory 2024-04-15 22:09:00,720 INFO 2024-04-15 22:09:00,720 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods overseer-0-5584f6c4db-bcn5q --output jsonpath={.spec.containers[].resources.requests.memory} 2024-04-15 22:09:00,720 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:09:01,075 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:01,075 DEBUG --- stdout --- 2024-04-15 22:09:01,076 DEBUG 4Gi 2024-04-15 22:09:01,076 DEBUG --- stderr --- 2024-04-15 22:09:01,076 DEBUG ________________________________________________________________________________ [2024-04-15 22:09:01] authn-users-6000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-15 22:09:01,077 INFO 2024-04-15 22:09:01,077 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-04-15 22:09:01,077 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:09:01,417 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:09:01,417 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:01,418 DEBUG --- stdout --- 2024-04-15 22:09:01,418 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:09:01,418 DEBUG --- stderr --- 2024-04-15 22:09:01,418 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 "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-6000 --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-04-15 22:09:01] authn-users-6000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-15 22:09:01,419 INFO 2024-04-15 22:09:01,419 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-04-15 22:09:01,419 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:09:01,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:09:01,804 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:01,805 DEBUG --- stdout --- 2024-04-15 22:09:01,805 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:09:01,805 DEBUG --- stderr --- 2024-04-15 22:09:01,805 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/order.json { "name": "authn-users-6000", "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 \"am.AMAuthN@medium\" --results-folder /results/gatling/authn-users-6000 --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/authn-users-6000", "environment": { "JAVA_OPTS": "-Dam_host=xlou.iam.xlou-cdm.engineeringpit.com -DamadminPassword=**** -DamadminUsername=amadmin -Dduration=1800 -Dnum_users=1000000 -DtestCase=am.AMAuthN -DtestMode=open -Dthroughput=6000 -Duser_password=T35tr0ck123" } } ________________________________________________________________________________ [2024-04-15 22:09:01] authn-users-6000 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-04-15 22:09:01,807 INFO 2024-04-15 22:09:01,808 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-15 22:09:01,808 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 22:09:02,096 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:09:02,096 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:02,096 DEBUG --- stdout --- 2024-04-15 22:09:02,096 DEBUG WAITING 2024-04-15 22:09:02,096 DEBUG --- stderr --- 2024-04-15 22:09:02,097 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 27 0 --:--:-- --:--:-- --:--:-- 27 ________________________________________________________________________________ [2024-04-15 22:09:02] authn-users-6000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-15 22:09:02,097 INFO 2024-04-15 22:09:02,097 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-04-15 22:09:02,097 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:09:02,407 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:09:02,407 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:02,407 DEBUG --- stdout --- 2024-04-15 22:09:02,407 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:09:02,407 DEBUG --- stderr --- 2024-04-15 22:09:02,407 DEBUG 2024-04-15 22:09:02,407 INFO 2024-04-15 22:09:02,407 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/order.json overseer-0-5584f6c4db-bcn5q:/results/orders/order.json 2024-04-15 22:09:02,407 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:09:03,826 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:03,826 DEBUG --- stdout --- 2024-04-15 22:09:03,826 DEBUG 2024-04-15 22:09:03,826 DEBUG --- stderr --- 2024-04-15 22:09:03,827 DEBUG Order has been successfully forwarded to overseer [2024-04-15 22:09:03] - INFO: Check if Gatling simulation started successfully 2024-04-15 22:09:03,827 INFO 2024-04-15 22:09:03,827 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 22:09:03,827 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:09:04,161 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:09:04,161 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:04,161 DEBUG --- stdout --- 2024-04-15 22:09:04,162 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:09:04,162 DEBUG --- stderr --- 2024-04-15 22:09:04,162 DEBUG 2024-04-15 22:09:04,162 INFO 2024-04-15 22:09:04,162 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- head -n 25 /results/orders/authn-users-6000/stdout.txt | grep "started..." 2024-04-15 22:09:04,162 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 22:09:04,966 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-15 22:09:10,734 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-15 22:09:16,527 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-15 22:09:22,327 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-15 22:09:22,328 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:09:22,328 DEBUG --- stdout --- 2024-04-15 22:09:22,328 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 23:09:07.193 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 23:09:13.711 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 23:09:13.715 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 23:09:16.207 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.10.5 is available! (you're using 3.9.5) Simulation com.forgerock.gatling.Loadtest started... 2024-04-15 22:09:22,328 DEBUG --- stderr --- 2024-04-15 22:09:22,328 DEBUG ________________________________________________________________________________ [2024-04-15 22:09:22] authn-users-6000 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 36m Waiting for status (curl --fail --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt) == WAITING Order is running since 5s, retry Order is running since 10s, retry Order is running since 15s, retry Order is running since 20s, retry Order is running since 26s, retry Order is running since 31s, retry Order is running since 36s, retry Order is running since 41s, retry Order is running since 47s, retry Order is running since 52s, retry Order is running since 57s, retry Order is running since 1m 02s, retry Order is running since 1m 07s, retry Order is running since 1m 13s, retry Order is running since 1m 18s, retry Order is running since 1m 23s, retry Order is running since 1m 28s, retry Order is running since 1m 34s, retry Order is running since 1m 39s, retry Order is running since 1m 44s, retry Order is running since 1m 49s, retry Order is running since 1m 54s, retry Order is running since 2m, retry Order is running since 2m 05s, retry Order is running since 2m 10s, retry Order is running since 2m 15s, retry Order is running since 2m 21s, retry Order is running since 2m 26s, retry Order is running since 2m 31s, retry Order is running since 2m 36s, retry Order is running since 2m 41s, retry Order is running since 2m 47s, retry Order is running since 2m 52s, retry Order is running since 2m 57s, retry Order is running since 3m 02s, retry Order is running since 3m 08s, retry Order is running since 3m 13s, retry Order is running since 3m 18s, retry Order is running since 3m 23s, retry Order is running since 3m 29s, retry Order is running since 3m 34s, retry Order is running since 3m 39s, retry Order is running since 3m 44s, retry Order is running since 3m 49s, retry Order is running since 3m 55s, retry Order is running since 4m, retry Order is running since 4m 05s, retry Order is running since 4m 10s, retry Order is running since 4m 16s, retry Order is running since 4m 21s, retry Order is running since 4m 26s, retry Order is running since 4m 31s, retry Order is running since 4m 36s, retry Order is running since 4m 42s, retry Order is running since 4m 47s, retry Order is running since 4m 52s, retry Order is running since 4m 57s, retry Order is running since 5m 03s, retry Order is running since 5m 08s, retry Order is running since 5m 13s, retry Order is running since 5m 18s, retry Order is running since 5m 23s, retry Order is running since 5m 29s, retry Order is running since 5m 34s, retry Order is running since 5m 39s, retry Order is running since 5m 44s, retry Order is running since 5m 50s, retry Order is running since 5m 55s, retry Order is running since 6m, retry Order is running since 6m 05s, retry Order is running since 6m 10s, retry Order is running since 6m 16s, retry Order is running since 6m 21s, retry Order is running since 6m 26s, retry Order is running since 6m 31s, retry Order is running since 6m 37s, retry Order is running since 6m 42s, retry Order is running since 6m 47s, retry Order is running since 6m 52s, retry Order is running since 6m 57s, retry Order is running since 7m 03s, retry Order is running since 7m 08s, retry Order is running since 7m 13s, retry Order is running since 7m 18s, retry Order is running since 7m 24s, retry Order is running since 7m 29s, retry Order is running since 7m 34s, retry Order is running since 7m 39s, retry Order is running since 7m 45s, retry Order is running since 7m 50s, retry Order is running since 7m 55s, retry Order is running since 8m, retry Order is running since 8m 05s, retry Order is running since 8m 11s, retry Order is running since 8m 16s, retry Order is running since 8m 21s, retry Order is running since 8m 26s, retry Order is running since 8m 32s, retry Order is running since 8m 37s, retry Order is running since 8m 42s, retry Order is running since 8m 47s, retry Order is running since 8m 52s, retry Order is running since 8m 58s, retry Order is running since 9m 03s, retry Order is running since 9m 08s, retry Order is running since 9m 13s, retry Order is running since 9m 19s, retry Order is running since 9m 24s, retry Order is running since 9m 29s, retry Order is running since 9m 34s, retry Order is running since 9m 39s, retry Order is running since 9m 45s, retry Order is running since 9m 50s, retry Order is running since 9m 55s, retry Order is running since 10m, retry Order is running since 10m 06s, retry Order is running since 10m 11s, retry Order is running since 10m 16s, retry Order is running since 10m 21s, retry Order is running since 10m 26s, retry Order is running since 10m 32s, retry Order is running since 10m 37s, retry Order is running since 10m 42s, retry Order is running since 10m 47s, retry Order is running since 10m 53s, retry Order is running since 10m 58s, retry Order is running since 11m 03s, retry Order is running since 11m 08s, retry Order is running since 11m 13s, retry Order is running since 11m 19s, retry Order is running since 11m 24s, retry Order is running since 11m 29s, retry Order is running since 11m 34s, retry Order is running since 11m 40s, retry Order is running since 11m 45s, retry Order is running since 11m 50s, retry Order is running since 11m 55s, retry Order is running since 12m 01s, retry Order is running since 12m 06s, retry Order is running since 12m 11s, retry Order is running since 12m 16s, retry Order is running since 12m 21s, retry Order is running since 12m 27s, retry Order is running since 12m 32s, retry Order is running since 12m 37s, retry Order is running since 12m 42s, retry Order is running since 12m 48s, retry Order is running since 12m 53s, retry Order is running since 12m 58s, retry Order is running since 13m 03s, retry Order is running since 13m 08s, retry Order is running since 13m 14s, retry Order is running since 13m 19s, retry Order is running since 13m 24s, retry Order is running since 13m 29s, retry Order is running since 13m 35s, retry Order is running since 13m 40s, retry Order is running since 13m 45s, retry Order is running since 13m 50s, retry Order is running since 13m 56s, retry Order is running since 14m 01s, retry Order is running since 14m 06s, retry Order is running since 14m 11s, retry Order is running since 14m 16s, retry Order is running since 14m 22s, retry Order is running since 14m 27s, retry Order is running since 14m 32s, retry Order is running since 14m 37s, retry Order is running since 14m 43s, retry Order is running since 14m 48s, retry Order is running since 14m 53s, retry Order is running since 14m 58s, retry Order is running since 15m 03s, retry Order is running since 15m 09s, retry Order is running since 15m 14s, retry Order is running since 15m 19s, retry Order is running since 15m 24s, retry Order is running since 15m 30s, retry Order is running since 15m 35s, retry Order is running since 15m 40s, retry Order is running since 15m 45s, retry Order is running since 15m 50s, retry Order is running since 15m 56s, retry Order is running since 16m 01s, retry Order is running since 16m 06s, retry Order is running since 16m 11s, retry Order is running since 16m 17s, retry Order is running since 16m 22s, retry Order is running since 16m 27s, retry Order is running since 16m 32s, retry Order is running since 16m 37s, retry Order is running since 16m 43s, retry Order is running since 16m 48s, retry Order is running since 16m 53s, retry Order is running since 16m 58s, retry Order is running since 17m 04s, retry Order is running since 17m 09s, retry Order is running since 17m 14s, retry Order is running since 17m 19s, retry Order is running since 17m 24s, retry Order is running since 17m 30s, retry Order is running since 17m 35s, retry Order is running since 17m 40s, retry Order is running since 17m 45s, retry Order is running since 17m 51s, retry Order is running since 17m 56s, retry Order is running since 18m 01s, retry Order is running since 18m 06s, retry Order is running since 18m 12s, retry Order is running since 18m 17s, retry Order is running since 18m 22s, retry Order is running since 18m 27s, retry Order is running since 18m 32s, retry Order is running since 18m 38s, retry Order is running since 18m 43s, retry Order is running since 18m 48s, retry Order is running since 18m 53s, retry Order is running since 18m 59s, retry Order is running since 19m 04s, retry Order is running since 19m 09s, retry Order is running since 19m 14s, retry Order is running since 19m 20s, retry Order is running since 19m 25s, retry Order is running since 19m 30s, retry Order is running since 19m 35s, retry Order is running since 19m 40s, retry Order is running since 19m 46s, retry Order is running since 19m 51s, retry Order is running since 19m 56s, retry Order is running since 20m 01s, retry Order is running since 20m 06s, retry Order is running since 20m 12s, retry Order is running since 20m 17s, retry Order is running since 20m 22s, retry Order is running since 20m 27s, retry Order is running since 20m 33s, retry Order is running since 20m 38s, retry Order is running since 20m 43s, retry Order is running since 20m 48s, retry Order is running since 20m 53s, retry Order is running since 20m 59s, retry Order is running since 21m 04s, retry Order is running since 21m 09s, retry Order is running since 21m 14s, retry Order is running since 21m 20s, retry Order is running since 21m 25s, retry Order is running since 21m 30s, retry Order is running since 21m 35s, retry Order is running since 21m 41s, retry Order is running since 21m 46s, retry Order is running since 21m 51s, retry Order is running since 21m 56s, retry Order is running since 22m 01s, retry Order is running since 22m 07s, retry Order is running since 22m 12s, retry Order is running since 22m 17s, retry Order is running since 22m 22s, retry Order is running since 22m 28s, retry Order is running since 22m 33s, retry Order is running since 22m 38s, retry Order is running since 22m 43s, retry Order is running since 22m 48s, retry Order is running since 22m 54s, retry Order is running since 22m 59s, retry Order is running since 23m 04s, retry Order is running since 23m 09s, retry Order is running since 23m 15s, retry Order is running since 23m 20s, retry Order is running since 23m 25s, retry Order is running since 23m 30s, retry Order is running since 23m 36s, retry Order is running since 23m 41s, retry Order is running since 23m 46s, retry Order is running since 23m 51s, retry Order is running since 23m 56s, retry Order is running since 24m 02s, retry Order is running since 24m 07s, retry Order is running since 24m 12s, retry Order is running since 24m 17s, retry Order is running since 24m 23s, retry Order is running since 24m 28s, retry Order is running since 24m 33s, retry Order is running since 24m 38s, retry Order is running since 24m 43s, retry Order is running since 24m 49s, retry Order is running since 24m 54s, retry Order is running since 24m 59s, retry Order is running since 25m 04s, retry Order is running since 25m 10s, retry Order is running since 25m 15s, retry Order is running since 25m 20s, retry Order is running since 25m 25s, retry Order is running since 25m 30s, retry Order is running since 25m 36s, retry Order is running since 25m 41s, retry Order is running since 25m 46s, retry Order is running since 25m 51s, retry Order is running since 25m 57s, retry Order is running since 26m 02s, retry Order is running since 26m 07s, retry Order is running since 26m 12s, retry Order is running since 26m 17s, retry Order is running since 26m 23s, retry Order is running since 26m 28s, retry Order is running since 26m 33s, retry Order is running since 26m 38s, retry Order is running since 26m 44s, retry Order is running since 26m 49s, retry Order is running since 26m 54s, retry Order is running since 26m 59s, retry Order is running since 27m 04s, retry Order is running since 27m 10s, retry Order is running since 27m 15s, retry Order is running since 27m 20s, retry Order is running since 27m 25s, retry Order is running since 27m 31s, retry Order is running since 27m 36s, retry Order is running since 27m 41s, retry Order is running since 27m 46s, retry Order is running since 27m 51s, retry Order is running since 27m 57s, retry Order is running since 28m 02s, retry Order is running since 28m 07s, retry Order is running since 28m 12s, retry Order is running since 28m 18s, retry Order is running since 28m 23s, retry Order is running since 28m 28s, retry Order is running since 28m 33s, retry Order is running since 28m 38s, retry Order is running since 28m 44s, retry Order is running since 28m 49s, retry Order is running since 28m 54s, retry Order is running since 28m 59s, retry Order is running since 29m 05s, retry Order is running since 29m 10s, retry Order is running since 29m 15s, retry Order is running since 29m 20s, retry Order is running since 29m 25s, retry Order is running since 29m 31s, retry Order is running since 29m 36s, retry Order is running since 29m 41s, retry Order is running since 29m 46s, retry Order is running since 29m 52s, retry Order is running since 29m 57s, retry Order is running since 30m 02s, retry Order is running since 30m 07s, retry Order is running since 30m 13s, retry Order is running since 30m 18s, retry Order is running since 30m 23s, retry Order is running since 30m 28s, retry PASS : Order stopped by itself after 30m 33s and before timeout (36m) ________________________________________________________________________________ [2024-04-15 22:39:56] authn-users-6000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-15 22:39:56,233 INFO 2024-04-15 22:39:56,233 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-04-15 22:39:56,233 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:39:56,587 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:39:56,587 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:39:56,587 DEBUG --- stdout --- 2024-04-15 22:39:56,588 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:39:56,588 DEBUG --- stderr --- 2024-04-15 22:39:56,588 DEBUG 2024-04-15 22:39:56,588 INFO 2024-04-15 22:39:56,588 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q -- rm -f /results/orders/order.json 2024-04-15 22:39:56,588 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:39:57,406 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:39:57,406 DEBUG --- stdout --- 2024-04-15 22:39:57,406 DEBUG 2024-04-15 22:39:57,406 DEBUG --- stderr --- 2024-04-15 22:39:57,406 DEBUG Order ran during 30m 53s ________________________________________________________________________________ [2024-04-15 22:39:57] authn-users-6000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-15 22:39:57] - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/order.json 2024-04-15 22:39:57,407 INFO 2024-04-15 22:39:57,407 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-04-15 22:39:57,407 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:39:57,736 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:39:57,736 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:39:57,736 DEBUG --- stdout --- 2024-04-15 22:39:57,736 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:39:57,736 DEBUG --- stderr --- 2024-04-15 22:39:57,736 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:39:57,736 INFO 2024-04-15 22:39:57,737 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-04-15 22:39:57,737 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:39:58,061 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:39:58,061 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:39:58,061 DEBUG --- stdout --- 2024-04-15 22:39:58,062 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:39:58,062 DEBUG --- stderr --- 2024-04-15 22:39:58,062 DEBUG 2024-04-15 22:39:58,062 INFO 2024-04-15 22:39:58,062 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/rc.txt" ]; then echo "/results/orders/authn-users-6000/rc.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/rc.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/rc.txt file found" 2024-04-15 22:39:58,062 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:39:58,981 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:39:58,982 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:39:58,982 DEBUG --- stdout --- 2024-04-15 22:39:58,982 DEBUG /results/orders/authn-users-6000/rc.txt file found 2024-04-15 22:39:58,982 DEBUG --- stderr --- 2024-04-15 22:39:58,982 DEBUG 2024-04-15 22:39:58,982 INFO 2024-04-15 22:39:58,983 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-04-15 22:39:58,983 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:39:59,315 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:39:59,316 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:39:59,316 DEBUG --- stdout --- 2024-04-15 22:39:59,316 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:39:59,316 DEBUG --- stderr --- 2024-04-15 22:39:59,316 DEBUG 2024-04-15 22:39:59,316 INFO 2024-04-15 22:39:59,316 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/rc.txt 2024-04-15 22:40:00,166 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:40:00,166 DEBUG --- stdout --- 2024-04-15 22:40:00,166 DEBUG 4 /results/orders/authn-users-6000/rc.txt 2024-04-15 22:40:00,166 DEBUG --- stderr --- 2024-04-15 22:40:00,166 DEBUG 2024-04-15 22:40:00,166 INFO - Download file (size 4k) 2024-04-15 22:40:00,167 INFO 2024-04-15 22:40:00,167 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-04-15 22:40:00,167 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:00,526 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:00,526 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:00,526 DEBUG --- stdout --- 2024-04-15 22:40:00,527 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:00,527 DEBUG --- stderr --- 2024-04-15 22:40:00,527 DEBUG source : /results/orders/authn-users-6000/rc.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/rc.txt 2024-04-15 22:40:00,527 INFO 2024-04-15 22:40:00,527 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-04-15 22:40:00,527 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:00,880 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:00,880 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:00,882 DEBUG --- stdout --- 2024-04-15 22:40:00,882 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:00,882 DEBUG --- stderr --- 2024-04-15 22:40:00,882 DEBUG 2024-04-15 22:40:00,883 INFO 2024-04-15 22:40:00,883 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/rc.txt 2024-04-15 22:40:00,883 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:40:01,662 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:01,663 DEBUG --- stdout --- 2024-04-15 22:40:01,663 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:40:01,663 DEBUG --- stderr --- 2024-04-15 22:40:01,663 DEBUG 2024-04-15 22:40:01,664 INFO 2024-04-15 22:40:01,664 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-04-15 22:40:01,664 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:02,007 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:02,008 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:02,008 DEBUG --- stdout --- 2024-04-15 22:40:02,008 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:02,008 DEBUG --- stderr --- 2024-04-15 22:40:02,008 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:40:02,008 INFO 2024-04-15 22:40:02,008 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-04-15 22:40:02,008 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:02,346 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:02,347 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:02,347 DEBUG --- stdout --- 2024-04-15 22:40:02,347 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:02,347 DEBUG --- stderr --- 2024-04-15 22:40:02,347 DEBUG 2024-04-15 22:40:02,348 INFO 2024-04-15 22:40:02,348 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/stderr.txt" ]; then echo "/results/orders/authn-users-6000/stderr.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stderr.txt file found" 2024-04-15 22:40:02,348 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:40:03,220 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:40:03,220 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:03,220 DEBUG --- stdout --- 2024-04-15 22:40:03,220 DEBUG /results/orders/authn-users-6000/stderr.txt file found 2024-04-15 22:40:03,221 DEBUG --- stderr --- 2024-04-15 22:40:03,221 DEBUG 2024-04-15 22:40:03,221 INFO 2024-04-15 22:40:03,221 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-04-15 22:40:03,221 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:03,559 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:03,559 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:03,559 DEBUG --- stdout --- 2024-04-15 22:40:03,560 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:03,560 DEBUG --- stderr --- 2024-04-15 22:40:03,560 DEBUG 2024-04-15 22:40:03,560 INFO 2024-04-15 22:40:03,560 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/stderr.txt 2024-04-15 22:40:04,408 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:40:04,409 DEBUG --- stdout --- 2024-04-15 22:40:04,409 DEBUG 0 /results/orders/authn-users-6000/stderr.txt 2024-04-15 22:40:04,409 DEBUG --- stderr --- 2024-04-15 22:40:04,409 DEBUG 2024-04-15 22:40:04,409 INFO - Download file (size 0k) 2024-04-15 22:40:04,409 INFO 2024-04-15 22:40:04,409 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-04-15 22:40:04,409 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:04,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:04,753 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:04,753 DEBUG --- stdout --- 2024-04-15 22:40:04,753 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:04,753 DEBUG --- stderr --- 2024-04-15 22:40:04,753 DEBUG source : /results/orders/authn-users-6000/stderr.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/stderr.txt 2024-04-15 22:40:04,754 INFO 2024-04-15 22:40:04,754 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-04-15 22:40:04,754 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:05,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:05,166 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:05,166 DEBUG --- stdout --- 2024-04-15 22:40:05,166 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:05,166 DEBUG --- stderr --- 2024-04-15 22:40:05,166 DEBUG 2024-04-15 22:40:05,167 INFO 2024-04-15 22:40:05,167 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/stderr.txt 2024-04-15 22:40:05,167 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:40:05,964 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:05,964 DEBUG --- stdout --- 2024-04-15 22:40:05,965 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:40:05,965 DEBUG --- stderr --- 2024-04-15 22:40:05,965 DEBUG 2024-04-15 22:40:05,965 INFO 2024-04-15 22:40:05,965 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-04-15 22:40:05,965 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:06,326 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:06,326 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:06,326 DEBUG --- stdout --- 2024-04-15 22:40:06,326 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:06,326 DEBUG --- stderr --- 2024-04-15 22:40:06,326 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:40:06,327 INFO 2024-04-15 22:40:06,327 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-04-15 22:40:06,327 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:06,663 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:06,663 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:06,663 DEBUG --- stdout --- 2024-04-15 22:40:06,664 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:06,664 DEBUG --- stderr --- 2024-04-15 22:40:06,664 DEBUG 2024-04-15 22:40:06,664 INFO 2024-04-15 22:40:06,664 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/stdout.txt" ]; then echo "/results/orders/authn-users-6000/stdout.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/stdout.txt file found" 2024-04-15 22:40:06,664 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:40:07,526 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:40:07,527 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:07,527 DEBUG --- stdout --- 2024-04-15 22:40:07,527 DEBUG /results/orders/authn-users-6000/stdout.txt file found 2024-04-15 22:40:07,527 DEBUG --- stderr --- 2024-04-15 22:40:07,527 DEBUG 2024-04-15 22:40:07,528 INFO 2024-04-15 22:40:07,528 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-04-15 22:40:07,528 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:07,896 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:07,897 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:07,897 DEBUG --- stdout --- 2024-04-15 22:40:07,897 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:07,897 DEBUG --- stderr --- 2024-04-15 22:40:07,897 DEBUG 2024-04-15 22:40:07,897 INFO 2024-04-15 22:40:07,897 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/stdout.txt 2024-04-15 22:40:08,735 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:40:08,736 DEBUG --- stdout --- 2024-04-15 22:40:08,736 DEBUG 260 /results/orders/authn-users-6000/stdout.txt 2024-04-15 22:40:08,736 DEBUG --- stderr --- 2024-04-15 22:40:08,736 DEBUG 2024-04-15 22:40:08,736 INFO - Download file (size 260k) 2024-04-15 22:40:08,736 INFO 2024-04-15 22:40:08,736 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-04-15 22:40:08,736 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:09,049 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:09,050 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:09,050 DEBUG --- stdout --- 2024-04-15 22:40:09,050 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:09,050 DEBUG --- stderr --- 2024-04-15 22:40:09,050 DEBUG source : /results/orders/authn-users-6000/stdout.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/stdout.txt 2024-04-15 22:40:09,050 INFO 2024-04-15 22:40:09,050 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-04-15 22:40:09,050 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:09,361 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:09,362 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:09,362 DEBUG --- stdout --- 2024-04-15 22:40:09,362 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:09,362 DEBUG --- stderr --- 2024-04-15 22:40:09,362 DEBUG 2024-04-15 22:40:09,362 INFO 2024-04-15 22:40:09,362 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/stdout.txt 2024-04-15 22:40:09,362 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:40:10,382 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:10,382 DEBUG --- stdout --- 2024-04-15 22:40:10,382 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:40:10,382 DEBUG --- stderr --- 2024-04-15 22:40:10,382 DEBUG ________________________________________________________________________________ [2024-04-15 22:40:10] authn-users-6000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-15 22:40:10,383 INFO 2024-04-15 22:40:10,383 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-04-15 22:40:10,383 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:10,714 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:10,714 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:10,714 DEBUG --- stdout --- 2024-04-15 22:40:10,714 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:10,714 DEBUG --- stderr --- 2024-04-15 22:40:10,714 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 22:40:10,715 INFO 2024-04-15 22:40:10,715 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-04-15 22:40:10,715 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:11,041 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:11,041 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:11,041 DEBUG --- stdout --- 2024-04-15 22:40:11,041 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:11,041 DEBUG --- stderr --- 2024-04-15 22:40:11,041 DEBUG 2024-04-15 22:40:11,042 INFO 2024-04-15 22:40:11,042 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- bash -c 'if [ -f "/results/orders/authn-users-6000/cmd.txt" ]; then echo "/results/orders/authn-users-6000/cmd.txt file found"; exit 0; else echo "/results/orders/authn-users-6000/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/authn-users-6000/cmd.txt file found" 2024-04-15 22:40:11,042 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 22:40:11,895 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 22:40:11,896 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:11,896 DEBUG --- stdout --- 2024-04-15 22:40:11,896 DEBUG /results/orders/authn-users-6000/cmd.txt file found 2024-04-15 22:40:11,896 DEBUG --- stderr --- 2024-04-15 22:40:11,896 DEBUG 2024-04-15 22:40:11,897 INFO 2024-04-15 22:40:11,897 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-04-15 22:40:11,897 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:12,209 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:12,210 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:12,210 DEBUG --- stdout --- 2024-04-15 22:40:12,210 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:12,210 DEBUG --- stderr --- 2024-04-15 22:40:12,210 DEBUG 2024-04-15 22:40:12,210 INFO 2024-04-15 22:40:12,210 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-5584f6c4db-bcn5q --stdin -- du -k /results/orders/authn-users-6000/cmd.txt 2024-04-15 22:40:13,039 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 22:40:13,040 DEBUG --- stdout --- 2024-04-15 22:40:13,040 DEBUG 4 /results/orders/authn-users-6000/cmd.txt 2024-04-15 22:40:13,040 DEBUG --- stderr --- 2024-04-15 22:40:13,040 DEBUG 2024-04-15 22:40:13,040 INFO - Download file (size 4k) 2024-04-15 22:40:13,041 INFO 2024-04-15 22:40:13,041 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-04-15 22:40:13,041 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:13,409 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:13,409 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:13,409 DEBUG --- stdout --- 2024-04-15 22:40:13,409 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:13,409 DEBUG --- stderr --- 2024-04-15 22:40:13,409 DEBUG source : /results/orders/authn-users-6000/cmd.txt @ overseer-0-5584f6c4db-bcn5q destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/cmd.txt 2024-04-15 22:40:13,409 INFO 2024-04-15 22:40:13,410 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-04-15 22:40:13,410 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 22:40:13,834 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 22:40:13,834 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:13,834 DEBUG --- stdout --- 2024-04-15 22:40:13,834 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 22:40:13,834 DEBUG --- stderr --- 2024-04-15 22:40:13,834 DEBUG 2024-04-15 22:40:13,835 INFO 2024-04-15 22:40:13,835 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-5584f6c4db-bcn5q:/results/orders/authn-users-6000/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/cmd.txt 2024-04-15 22:40:13,835 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 22:40:14,619 INFO [loop_until]: OK (rc = 0) 2024-04-15 22:40:14,619 DEBUG --- stdout --- 2024-04-15 22:40:14,619 DEBUG tar: Removing leading `/' from member names 2024-04-15 22:40:14,620 DEBUG --- stderr --- 2024-04-15 22:40:14,620 DEBUG 2024-04-15 22:40:14,620 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/cmd.txt is small (1 lines), printing all lines: 2024-04-15 22:40:14,620 DEBUG ----- output ----- 2024-04-15 22:40:14,620 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 "am.AMAuthN@medium" --results-folder /results/gatling/authn-users-6000 --extra-run-jvm-options "-Xmx4G" --no-reports 2024-04-15 22:40:14,620 INFO ________________________________________________________________________________ [2024-04-15 22:40:14] authn-users-6000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-15 22:40:14,620 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/rc.txt is small (1 lines), printing all lines: 2024-04-15 22:40:14,620 DEBUG ----- output ----- 2024-04-15 22:40:14,620 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-15 22:40:14,620 INFO ________________________________________________________________________________ [2024-04-15 22:40:14] authn-users-6000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-15 22:40:14,621 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/stdout.txt is 4434 lines): 2024-04-15 22:40:14,621 DEBUG ----- output ----- 2024-04-15 22:40:14,621 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-15 22:40:14,621 INFO 23:09:07.193 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-15 22:40:14,621 INFO 23:09:13.711 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:122:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-15 22:40:14,621 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 22:40:14,621 INFO ^ 2024-04-15 22:40:14,621 INFO 23:09:13.715 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling-open/benchmark/Testcase.scala:225:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-04-15 22:40:14,621 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 22:40:14,621 INFO ^ 2024-04-15 22:40:14,621 INFO 23:09:16.207 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-15 22:40:14,621 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-15 22:40:14,621 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-15 22:40:14,621 INFO 2024-04-15 22:40:14,621 INFO ================================================================================ 2024-04-15 22:40:14,621 INFO 2024-04-15 23:09:26 5s elapsed 2024-04-15 22:40:14,621 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,622 INFO > Global (OK=2495 KO=0 ) 2024-04-15 22:40:14,622 INFO > userRestLogin (OK=2495 KO=0 ) 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,622 INFO [- ] 0% 2024-04-15 22:40:14,622 INFO waiting: 10891365 / active: 10 / done: 2495 2024-04-15 22:40:14,622 INFO ================================================================================ 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO ================================================================================ 2024-04-15 22:40:14,622 INFO 2024-04-15 23:09:31 10s elapsed 2024-04-15 22:40:14,622 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,622 INFO > Global (OK=9988 KO=0 ) 2024-04-15 22:40:14,622 INFO > userRestLogin (OK=9988 KO=0 ) 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,622 INFO [- ] 0% 2024-04-15 22:40:14,622 INFO waiting: 10883859 / active: 23 / done: 9988 2024-04-15 22:40:14,622 INFO ================================================================================ 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO ================================================================================ 2024-04-15 22:40:14,622 INFO 2024-04-15 23:09:36 15s elapsed 2024-04-15 22:40:14,622 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,622 INFO > Global (OK=22488 KO=0 ) 2024-04-15 22:40:14,622 INFO > userRestLogin (OK=22488 KO=0 ) 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,622 INFO [- ] 0% 2024-04-15 22:40:14,622 INFO waiting: 10871351 / active: 31 / done: 22488 2024-04-15 22:40:14,622 INFO ================================================================================ 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,622 INFO 2024-04-15 22:40:14,623 INFO ================================================================================ 2024-04-15 22:40:14,623 INFO 2024-04-15 23:09:41 20s elapsed 2024-04-15 22:40:14,623 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,623 INFO > Global (OK=39969 KO=0 ) 2024-04-15 22:40:14,623 INFO > userRestLogin (OK=39969 KO=0 ) 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,623 INFO [- ] 0% 2024-04-15 22:40:14,623 INFO waiting: 10853850 / active: 51 / done: 39969 2024-04-15 22:40:14,623 INFO ================================================================================ 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO ================================================================================ 2024-04-15 22:40:14,623 INFO 2024-04-15 23:09:46 25s elapsed 2024-04-15 22:40:14,623 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,623 INFO > Global (OK=62457 KO=0 ) 2024-04-15 22:40:14,623 INFO > userRestLogin (OK=62457 KO=0 ) 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,623 INFO [- ] 0% 2024-04-15 22:40:14,623 INFO waiting: 10831349 / active: 64 / done: 62457 2024-04-15 22:40:14,623 INFO ================================================================================ 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO ================================================================================ 2024-04-15 22:40:14,623 INFO 2024-04-15 23:09:51 30s elapsed 2024-04-15 22:40:14,623 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,623 INFO > Global (OK=89841 KO=0 ) 2024-04-15 22:40:14,623 INFO > userRestLogin (OK=89841 KO=0 ) 2024-04-15 22:40:14,623 INFO 2024-04-15 22:40:14,623 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,623 INFO [- ] 0% 2024-04-15 22:40:14,624 INFO waiting: 10803856 / active: 174 / done: 89840 2024-04-15 22:40:14,624 INFO ================================================================================ 2024-04-15 22:40:14,624 INFO 2024-04-15 22:40:14,624 INFO 2024-04-15 22:40:14,624 INFO ================================================================================ 2024-04-15 22:40:14,624 INFO 2024-04-15 23:09:56 35s elapsed 2024-04-15 22:40:14,624 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,624 INFO > Global (OK=120095 KO=0 ) 2024-04-15 22:40:14,624 INFO > userRestLogin (OK=120095 KO=0 ) 2024-04-15 22:40:14,624 INFO 2024-04-15 22:40:14,624 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,624 INFO [- ] 1% 2024-04-15 22:40:14,624 INFO waiting: 10773641 / active: 134 / done: 120095 2024-04-15 22:40:14,624 INFO ================================================================================ 2024-04-15 22:40:14,624 INFO 2024-04-15 22:40:14,624 INFO 2024-04-15 22:40:14,624 INFO ================================================================================ 2024-04-15 22:40:14,624 INFO 2024-04-15 23:10:01 40s elapsed 2024-04-15 22:40:14,624 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,624 INFO > Global (OK=150307 KO=0 ) 2024-04-15 22:40:14,624 INFO [...] 2024-04-15 22:40:14,625 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,625 INFO > Global (OK=10743480 KO=0 ) 2024-04-15 22:40:14,625 INFO > userRestLogin (OK=10743480 KO=0 ) 2024-04-15 22:40:14,625 INFO 2024-04-15 22:40:14,625 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,625 INFO [########################################################################- ] 98% 2024-04-15 22:40:14,626 INFO waiting: 150251 / active: 139 / done: 10743480 2024-04-15 22:40:14,626 INFO ================================================================================ 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO ================================================================================ 2024-04-15 22:40:14,626 INFO 2024-04-15 23:39:31 1810s elapsed 2024-04-15 22:40:14,626 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,626 INFO > Global (OK=10773708 KO=0 ) 2024-04-15 22:40:14,626 INFO > userRestLogin (OK=10773708 KO=0 ) 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,626 INFO [#########################################################################-] 98% 2024-04-15 22:40:14,626 INFO waiting: 119988 / active: 175 / done: 10773707 2024-04-15 22:40:14,626 INFO ================================================================================ 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO ================================================================================ 2024-04-15 22:40:14,626 INFO 2024-04-15 23:39:36 1815s elapsed 2024-04-15 22:40:14,626 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,626 INFO > Global (OK=10803658 KO=0 ) 2024-04-15 22:40:14,626 INFO > userRestLogin (OK=10803658 KO=0 ) 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,626 INFO [#########################################################################-] 99% 2024-04-15 22:40:14,626 INFO waiting: 89928 / active: 284 / done: 10803658 2024-04-15 22:40:14,626 INFO ================================================================================ 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO ================================================================================ 2024-04-15 22:40:14,626 INFO 2024-04-15 23:39:41 1820s elapsed 2024-04-15 22:40:14,626 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,626 INFO > Global (OK=10833692 KO=0 ) 2024-04-15 22:40:14,626 INFO > userRestLogin (OK=10833692 KO=0 ) 2024-04-15 22:40:14,626 INFO 2024-04-15 22:40:14,626 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,626 INFO [#########################################################################-] 99% 2024-04-15 22:40:14,626 INFO waiting: 59919 / active: 259 / done: 10833692 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 23:39:46 1825s elapsed 2024-04-15 22:40:14,627 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,627 INFO > Global (OK=10863682 KO=0 ) 2024-04-15 22:40:14,627 INFO > userRestLogin (OK=10863682 KO=0 ) 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,627 INFO [#########################################################################-] 99% 2024-04-15 22:40:14,627 INFO waiting: 29908 / active: 280 / done: 10863682 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 23:39:51 1830s elapsed 2024-04-15 22:40:14,627 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,627 INFO > Global (OK=10893499 KO=0 ) 2024-04-15 22:40:14,627 INFO > userRestLogin (OK=10893499 KO=0 ) 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,627 INFO [#########################################################################-] 99% 2024-04-15 22:40:14,627 INFO waiting: 0 / active: 371 / done: 10893499 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 23:39:51 1830s elapsed 2024-04-15 22:40:14,627 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 22:40:14,627 INFO > Global (OK=10893870 KO=0 ) 2024-04-15 22:40:14,627 INFO > userRestLogin (OK=10893870 KO=0 ) 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 22:40:14,627 INFO [##########################################################################]100% 2024-04-15 22:40:14,627 INFO waiting: 0 / active: 0 / done: 10893870 2024-04-15 22:40:14,627 INFO ================================================================================ 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-15 22:40:14,627 INFO 2024-04-15 22:40:14,627 INFO ---------------------- 2024-04-15 22:40:14,627 INFO [15/Apr/2024 23:39:51] Create tar file authn-users-6000.tar.gz 2024-04-15 22:40:14,628 INFO ---------------------- 2024-04-15 22:40:14,628 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-15 22:40:14,628 INFO RESULT_FOLDER=authn-users-6000 2024-04-15 22:40:14,628 INFO SIMULATION_PATH=authn-users-6000/loadtest-20240415220920419 2024-04-15 22:40:14,628 INFO PWD=/results/gatling/authn-users-6000/loadtest-20240415220920419 2024-04-15 22:40:14,628 INFO [cmd] tar -czvf /results/gatling//authn-users-6000.tar.gz --exclude=simulation.log * 2024-04-15 22:40:14,628 INFO /results/gatling 2024-04-15 22:40:14,628 INFO 2024-04-15 22:40:14,628 INFO ---------------------- 2024-04-15 22:40:14,628 INFO [15/Apr/2024 23:39:51] Update /results/gatling//latest 2024-04-15 22:40:14,628 INFO ---------------------- 2024-04-15 22:40:14,628 INFO 2024-04-15 22:40:14,628 INFO ---------------------- 2024-04-15 22:40:14,628 INFO [15/Apr/2024 23:39:51] Update /results/gatling//latest.tar.gz 2024-04-15 22:40:14,628 INFO ---------------------- 2024-04-15 22:40:14,628 INFO ________________________________________________________________________________ [2024-04-15 22:40:14] authn-users-6000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-15 22:40:14,628 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/authn_rest/07_Scenario_AuthN/01_authn-users-6000/stderr.txt is small (0 lines), printing all lines: 2024-04-15 22:40:14,628 DEBUG ----- output ----- 2024-04-15 22:40:14,628 DEBUG 2024-04-15 22:40:14,628 INFO ________________________________________________________________________________ [2024-04-15 22:40:14] authn-users-6000 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-04-15 22:40:14] authn-users-6000 print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-04-15 22:40:14] authn-users-6000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped