--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 20:59:07] authn-users-6000 pre : N/A ________________________________________________________________________________ [2024-04-15 20:59:07] - INFO: Timeout for this Task has changed to 36m (2160 seconds) [2024-04-15 20:59:07] - 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 20:59:07,282 INFO 2024-04-15 20:59:07,282 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 20:59:07,282 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 20:59:07,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 20:59:07,618 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:07,618 DEBUG --- stdout --- 2024-04-15 20:59:07,618 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 20:59:07,618 DEBUG --- stderr --- 2024-04-15 20:59:07,618 DEBUG [2024-04-15 20:59:07] - INFO: Get overseer pod resources requests memory 2024-04-15 20:59:07,619 INFO 2024-04-15 20:59:07,619 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 20:59:07,619 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 20:59:07,952 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:07,952 DEBUG --- stdout --- 2024-04-15 20:59:07,952 DEBUG 4Gi 2024-04-15 20:59:07,952 DEBUG --- stderr --- 2024-04-15 20:59:07,952 DEBUG ________________________________________________________________________________ [2024-04-15 20:59:07] authn-users-6000 print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-04-15 20:59:07,953 INFO 2024-04-15 20:59:07,953 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 20:59:07,953 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 20:59:08,270 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 20:59:08,270 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:08,270 DEBUG --- stdout --- 2024-04-15 20:59:08,270 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 20:59:08,270 DEBUG --- stderr --- 2024-04-15 20:59:08,270 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 20:59:08] authn-users-6000 create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-04-15 20:59:08,271 INFO 2024-04-15 20:59:08,271 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 20:59:08,271 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 20:59:08,586 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 20:59:08,586 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:08,586 DEBUG --- stdout --- 2024-04-15 20:59:08,586 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 20:59:08,586 DEBUG --- stderr --- 2024-04-15 20:59:08,586 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 20:59:08] 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 20:59:08,588 INFO 2024-04-15 20:59:08,588 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-04-15 20:59:08,588 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 20:59:08,902 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 20:59:08,902 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:08,902 DEBUG --- stdout --- 2024-04-15 20:59:08,902 DEBUG WAITING 2024-04-15 20:59:08,902 DEBUG --- stderr --- 2024-04-15 20:59:08,903 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 25 0 --:--:-- --:--:-- --:--:-- 25 ________________________________________________________________________________ [2024-04-15 20:59:08] authn-users-6000 start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-04-15 20:59:08,903 INFO 2024-04-15 20:59:08,903 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 20:59:08,903 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 20:59:09,246 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 20:59:09,246 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:09,246 DEBUG --- stdout --- 2024-04-15 20:59:09,246 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 20:59:09,246 DEBUG --- stderr --- 2024-04-15 20:59:09,246 DEBUG 2024-04-15 20:59:09,247 INFO 2024-04-15 20:59:09,247 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 20:59:09,247 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 20:59:10,670 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:10,670 DEBUG --- stdout --- 2024-04-15 20:59:10,671 DEBUG 2024-04-15 20:59:10,671 DEBUG --- stderr --- 2024-04-15 20:59:10,671 DEBUG Order has been successfully forwarded to overseer [2024-04-15 20:59:10] - INFO: Check if Gatling simulation started successfully 2024-04-15 20:59:10,671 INFO 2024-04-15 20:59:10,671 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 20:59:10,671 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 20:59:10,989 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 20:59:10,989 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:10,990 DEBUG --- stdout --- 2024-04-15 20:59:10,990 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 20:59:10,990 DEBUG --- stderr --- 2024-04-15 20:59:10,990 DEBUG 2024-04-15 20:59:10,990 INFO 2024-04-15 20:59:10,990 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 20:59:10,991 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-04-15 20:59:11,773 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-04-15 20:59:17,542 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-04-15 20:59:23,330 INFO [loop_until]: Function succeeded after 12s (rc=0) - failed to find expected output: started... - retry 2024-04-15 20:59:29,126 INFO [loop_until]: Function succeeded after 18s (rc=0) - expected pattern found 2024-04-15 20:59:29,127 INFO [loop_until]: OK (rc = 0) 2024-04-15 20:59:29,127 DEBUG --- stdout --- 2024-04-15 20:59:29,127 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 21:59:13.569 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 21:59:20.207 [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"); ^ 21:59:20.211 [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"); ^ 21:59:22.768 [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 20:59:29,127 DEBUG --- stderr --- 2024-04-15 20:59:29,127 DEBUG ________________________________________________________________________________ [2024-04-15 20:59:29] 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 46s, 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 33s, 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 20s, 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 28s, 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 15s, 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 44s, 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 31s, 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 18s, 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, 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 34s, 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 55s, 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 21s, 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 42s, 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 29s, 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 16s, 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 42s, 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 03s, 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 11s, 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 37s, 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 58s, 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 19s, 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 45s, 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 32s, 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 19s, 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 40s, 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 06s, 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 27s, 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 14s, 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 35s, 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 01s, 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 22s, 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 48s, 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 09s, 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 35s, 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 56s, 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 22s, 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 43s, 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 30s, 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 26s, 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 21:30:03] authn-users-6000 stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-04-15 21:30:03,093 INFO 2024-04-15 21:30:03,093 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 21:30:03,093 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:03,464 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:03,464 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:03,464 DEBUG --- stdout --- 2024-04-15 21:30:03,464 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:03,464 DEBUG --- stderr --- 2024-04-15 21:30:03,464 DEBUG 2024-04-15 21:30:03,464 INFO 2024-04-15 21:30:03,464 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 21:30:03,464 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:30:04,339 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:04,339 DEBUG --- stdout --- 2024-04-15 21:30:04,339 DEBUG 2024-04-15 21:30:04,340 DEBUG --- stderr --- 2024-04-15 21:30:04,340 DEBUG Order ran during 30m 53s ________________________________________________________________________________ [2024-04-15 21:30:04] authn-users-6000 download_logs : Download individual order files ________________________________________________________________________________ [2024-04-15 21:30:04] - 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 21:30:04,341 INFO 2024-04-15 21:30:04,341 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 21:30:04,341 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:04,650 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:04,650 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:04,650 DEBUG --- stdout --- 2024-04-15 21:30:04,650 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:04,650 DEBUG --- stderr --- 2024-04-15 21:30:04,650 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:30:04,651 INFO 2024-04-15 21:30:04,651 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 21:30:04,651 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:04,964 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:04,965 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:04,965 DEBUG --- stdout --- 2024-04-15 21:30:04,965 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:04,965 DEBUG --- stderr --- 2024-04-15 21:30:04,965 DEBUG 2024-04-15 21:30:04,965 INFO 2024-04-15 21:30:04,965 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 21:30:04,966 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:30:05,802 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:30:05,802 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:05,802 DEBUG --- stdout --- 2024-04-15 21:30:05,802 DEBUG /results/orders/authn-users-6000/rc.txt file found 2024-04-15 21:30:05,802 DEBUG --- stderr --- 2024-04-15 21:30:05,802 DEBUG 2024-04-15 21:30:05,803 INFO 2024-04-15 21:30:05,803 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 21:30:05,803 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:06,131 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:06,131 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:06,132 DEBUG --- stdout --- 2024-04-15 21:30:06,132 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:06,132 DEBUG --- stderr --- 2024-04-15 21:30:06,132 DEBUG 2024-04-15 21:30:06,132 INFO 2024-04-15 21:30:06,132 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 21:30:06,987 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:30:06,988 DEBUG --- stdout --- 2024-04-15 21:30:06,989 DEBUG 4 /results/orders/authn-users-6000/rc.txt 2024-04-15 21:30:06,989 DEBUG --- stderr --- 2024-04-15 21:30:06,989 DEBUG 2024-04-15 21:30:06,989 INFO - Download file (size 4k) 2024-04-15 21:30:06,990 INFO 2024-04-15 21:30:06,990 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 21:30:06,990 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:07,379 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:07,380 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:07,380 DEBUG --- stdout --- 2024-04-15 21:30:07,380 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:07,380 DEBUG --- stderr --- 2024-04-15 21:30:07,380 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 21:30:07,380 INFO 2024-04-15 21:30:07,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 21:30:07,380 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:07,757 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:07,757 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:07,757 DEBUG --- stdout --- 2024-04-15 21:30:07,757 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:07,757 DEBUG --- stderr --- 2024-04-15 21:30:07,757 DEBUG 2024-04-15 21:30:07,758 INFO 2024-04-15 21:30:07,758 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 21:30:07,758 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:30:08,597 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:08,597 DEBUG --- stdout --- 2024-04-15 21:30:08,597 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:30:08,598 DEBUG --- stderr --- 2024-04-15 21:30:08,598 DEBUG 2024-04-15 21:30:08,598 INFO 2024-04-15 21:30:08,598 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 21:30:08,598 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:08,953 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:08,953 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:08,953 DEBUG --- stdout --- 2024-04-15 21:30:08,953 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:08,953 DEBUG --- stderr --- 2024-04-15 21:30:08,953 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:30:08,953 INFO 2024-04-15 21:30:08,953 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 21:30:08,953 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:09,295 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:09,295 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:09,295 DEBUG --- stdout --- 2024-04-15 21:30:09,295 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:09,295 DEBUG --- stderr --- 2024-04-15 21:30:09,295 DEBUG 2024-04-15 21:30:09,296 INFO 2024-04-15 21:30:09,296 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 21:30:09,296 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:30:10,159 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:30:10,159 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:10,159 DEBUG --- stdout --- 2024-04-15 21:30:10,159 DEBUG /results/orders/authn-users-6000/stderr.txt file found 2024-04-15 21:30:10,159 DEBUG --- stderr --- 2024-04-15 21:30:10,159 DEBUG 2024-04-15 21:30:10,160 INFO 2024-04-15 21:30:10,160 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 21:30:10,160 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:10,478 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:10,478 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:10,478 DEBUG --- stdout --- 2024-04-15 21:30:10,478 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:10,478 DEBUG --- stderr --- 2024-04-15 21:30:10,478 DEBUG 2024-04-15 21:30:10,478 INFO 2024-04-15 21:30:10,479 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 21:30:11,333 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:30:11,333 DEBUG --- stdout --- 2024-04-15 21:30:11,333 DEBUG 0 /results/orders/authn-users-6000/stderr.txt 2024-04-15 21:30:11,333 DEBUG --- stderr --- 2024-04-15 21:30:11,333 DEBUG 2024-04-15 21:30:11,333 INFO - Download file (size 0k) 2024-04-15 21:30:11,334 INFO 2024-04-15 21:30:11,334 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 21:30:11,334 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:11,671 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:11,672 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:11,672 DEBUG --- stdout --- 2024-04-15 21:30:11,672 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:11,672 DEBUG --- stderr --- 2024-04-15 21:30:11,672 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 21:30:11,672 INFO 2024-04-15 21:30:11,672 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 21:30:11,672 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:12,009 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:12,009 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:12,009 DEBUG --- stdout --- 2024-04-15 21:30:12,009 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:12,009 DEBUG --- stderr --- 2024-04-15 21:30:12,009 DEBUG 2024-04-15 21:30:12,010 INFO 2024-04-15 21:30:12,010 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 21:30:12,010 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:30:12,775 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:12,775 DEBUG --- stdout --- 2024-04-15 21:30:12,775 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:30:12,776 DEBUG --- stderr --- 2024-04-15 21:30:12,776 DEBUG 2024-04-15 21:30:12,776 INFO 2024-04-15 21:30:12,776 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 21:30:12,776 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:13,127 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:13,128 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:13,128 DEBUG --- stdout --- 2024-04-15 21:30:13,128 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:13,128 DEBUG --- stderr --- 2024-04-15 21:30:13,128 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:30:13,129 INFO 2024-04-15 21:30:13,129 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 21:30:13,129 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:13,482 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:13,482 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:13,482 DEBUG --- stdout --- 2024-04-15 21:30:13,482 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:13,482 DEBUG --- stderr --- 2024-04-15 21:30:13,482 DEBUG 2024-04-15 21:30:13,483 INFO 2024-04-15 21:30:13,483 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 21:30:13,483 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:30:14,431 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:30:14,434 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:14,434 DEBUG --- stdout --- 2024-04-15 21:30:14,434 DEBUG /results/orders/authn-users-6000/stdout.txt file found 2024-04-15 21:30:14,434 DEBUG --- stderr --- 2024-04-15 21:30:14,434 DEBUG 2024-04-15 21:30:14,435 INFO 2024-04-15 21:30:14,435 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 21:30:14,435 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:14,778 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:14,778 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:14,778 DEBUG --- stdout --- 2024-04-15 21:30:14,778 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:14,778 DEBUG --- stderr --- 2024-04-15 21:30:14,778 DEBUG 2024-04-15 21:30:14,779 INFO 2024-04-15 21:30:14,779 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 21:30:15,683 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:30:15,683 DEBUG --- stdout --- 2024-04-15 21:30:15,683 DEBUG 260 /results/orders/authn-users-6000/stdout.txt 2024-04-15 21:30:15,683 DEBUG --- stderr --- 2024-04-15 21:30:15,683 DEBUG 2024-04-15 21:30:15,684 INFO - Download file (size 260k) 2024-04-15 21:30:15,684 INFO 2024-04-15 21:30:15,684 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 21:30:15,684 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:16,027 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:16,027 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:16,027 DEBUG --- stdout --- 2024-04-15 21:30:16,027 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:16,027 DEBUG --- stderr --- 2024-04-15 21:30:16,027 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 21:30:16,027 INFO 2024-04-15 21:30:16,027 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 21:30:16,028 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:16,363 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:16,363 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:16,363 DEBUG --- stdout --- 2024-04-15 21:30:16,363 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:16,363 DEBUG --- stderr --- 2024-04-15 21:30:16,363 DEBUG 2024-04-15 21:30:16,363 INFO 2024-04-15 21:30:16,363 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 21:30:16,363 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:30:17,398 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:17,398 DEBUG --- stdout --- 2024-04-15 21:30:17,398 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:30:17,398 DEBUG --- stderr --- 2024-04-15 21:30:17,398 DEBUG ________________________________________________________________________________ [2024-04-15 21:30:17] authn-users-6000 show_cmd : Display order command ________________________________________________________________________________ 2024-04-15 21:30:17,399 INFO 2024-04-15 21:30:17,399 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-04-15 21:30:17,399 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:17,724 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:17,725 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:17,725 DEBUG --- stdout --- 2024-04-15 21:30:17,725 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:17,725 DEBUG --- stderr --- 2024-04-15 21:30:17,725 DEBUG Checking pod: overseer-0-5584f6c4db-bcn5q for file exists, if not wait. 2024-04-15 21:30:17,726 INFO 2024-04-15 21:30:17,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-04-15 21:30:17,726 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:18,066 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:18,066 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:18,066 DEBUG --- stdout --- 2024-04-15 21:30:18,066 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:18,067 DEBUG --- stderr --- 2024-04-15 21:30:18,067 DEBUG 2024-04-15 21:30:18,067 INFO 2024-04-15 21:30:18,067 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 21:30:18,067 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-04-15 21:30:18,927 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-04-15 21:30:18,927 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:18,927 DEBUG --- stdout --- 2024-04-15 21:30:18,927 DEBUG /results/orders/authn-users-6000/cmd.txt file found 2024-04-15 21:30:18,927 DEBUG --- stderr --- 2024-04-15 21:30:18,927 DEBUG 2024-04-15 21:30:18,928 INFO 2024-04-15 21:30:18,928 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 21:30:18,928 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:19,268 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:19,269 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:19,269 DEBUG --- stdout --- 2024-04-15 21:30:19,269 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:19,269 DEBUG --- stderr --- 2024-04-15 21:30:19,269 DEBUG 2024-04-15 21:30:19,270 INFO 2024-04-15 21:30:19,270 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 21:30:20,161 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-04-15 21:30:20,162 DEBUG --- stdout --- 2024-04-15 21:30:20,162 DEBUG 4 /results/orders/authn-users-6000/cmd.txt 2024-04-15 21:30:20,162 DEBUG --- stderr --- 2024-04-15 21:30:20,162 DEBUG 2024-04-15 21:30:20,162 INFO - Download file (size 4k) 2024-04-15 21:30:20,162 INFO 2024-04-15 21:30:20,162 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 21:30:20,162 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:20,508 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:20,509 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:20,509 DEBUG --- stdout --- 2024-04-15 21:30:20,509 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:20,509 DEBUG --- stderr --- 2024-04-15 21:30:20,509 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 21:30:20,510 INFO 2024-04-15 21:30:20,510 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 21:30:20,510 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-04-15 21:30:20,868 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-04-15 21:30:20,868 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:20,868 DEBUG --- stdout --- 2024-04-15 21:30:20,868 DEBUG overseer-0-5584f6c4db-bcn5q 2024-04-15 21:30:20,868 DEBUG --- stderr --- 2024-04-15 21:30:20,868 DEBUG 2024-04-15 21:30:20,869 INFO 2024-04-15 21:30:20,869 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 21:30:20,869 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-04-15 21:30:21,691 INFO [loop_until]: OK (rc = 0) 2024-04-15 21:30:21,692 DEBUG --- stdout --- 2024-04-15 21:30:21,692 DEBUG tar: Removing leading `/' from member names 2024-04-15 21:30:21,692 DEBUG --- stderr --- 2024-04-15 21:30:21,692 DEBUG 2024-04-15 21:30:21,693 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 21:30:21,693 DEBUG ----- output ----- 2024-04-15 21:30:21,693 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 21:30:21,693 INFO ________________________________________________________________________________ [2024-04-15 21:30:21] authn-users-6000 show_rc : Display rc order ________________________________________________________________________________ 2024-04-15 21:30:21,694 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 21:30:21,694 DEBUG ----- output ----- 2024-04-15 21:30:21,694 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-04-15 21:30:21,694 INFO ________________________________________________________________________________ [2024-04-15 21:30:21] authn-users-6000 show_stdout : Display stdout order ________________________________________________________________________________ 2024-04-15 21:30:21,696 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 21:30:21,696 DEBUG ----- output ----- 2024-04-15 21:30:21,696 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-04-15 21:30:21,696 INFO 21:59:13.569 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-04-15 21:30:21,696 INFO 21:59:20.207 [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 21:30:21,696 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 21:30:21,696 INFO ^ 2024-04-15 21:30:21,696 INFO 21:59:20.211 [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 21:30:21,696 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-04-15 21:30:21,696 INFO ^ 2024-04-15 21:30:21,696 INFO 21:59:22.768 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-04-15 21:30:21,697 INFO Gatling 3.10.5 is available! (you're using 3.9.5) 2024-04-15 21:30:21,697 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-04-15 21:30:21,697 INFO 2024-04-15 21:30:21,697 INFO ================================================================================ 2024-04-15 21:30:21,697 INFO 2024-04-15 21:59:32 5s elapsed 2024-04-15 21:30:21,697 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,697 INFO > Global (OK=2502 KO=0 ) 2024-04-15 21:30:21,697 INFO > userRestLogin (OK=2502 KO=0 ) 2024-04-15 21:30:21,697 INFO 2024-04-15 21:30:21,697 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,697 INFO [- ] 0% 2024-04-15 21:30:21,697 INFO waiting: 10889670 / active: 13 / done: 2502 2024-04-15 21:30:21,697 INFO ================================================================================ 2024-04-15 21:30:21,697 INFO 2024-04-15 21:30:21,697 INFO 2024-04-15 21:30:21,697 INFO ================================================================================ 2024-04-15 21:30:21,697 INFO 2024-04-15 21:59:37 10s elapsed 2024-04-15 21:30:21,697 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,697 INFO > Global (OK=9999 KO=0 ) 2024-04-15 21:30:21,697 INFO > userRestLogin (OK=9999 KO=0 ) 2024-04-15 21:30:21,697 INFO 2024-04-15 21:30:21,697 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,697 INFO [- ] 0% 2024-04-15 21:30:21,697 INFO waiting: 10882159 / active: 27 / done: 9999 2024-04-15 21:30:21,698 INFO ================================================================================ 2024-04-15 21:30:21,698 INFO 2024-04-15 21:30:21,698 INFO 2024-04-15 21:30:21,698 INFO ================================================================================ 2024-04-15 21:30:21,698 INFO 2024-04-15 21:59:42 15s elapsed 2024-04-15 21:30:21,698 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,698 INFO > Global (OK=22502 KO=0 ) 2024-04-15 21:30:21,698 INFO > userRestLogin (OK=22502 KO=0 ) 2024-04-15 21:30:21,698 INFO 2024-04-15 21:30:21,698 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,698 INFO [- ] 0% 2024-04-15 21:30:21,698 INFO waiting: 10869647 / active: 36 / done: 22502 2024-04-15 21:30:21,699 INFO ================================================================================ 2024-04-15 21:30:21,699 INFO 2024-04-15 21:30:21,699 INFO 2024-04-15 21:30:21,699 INFO ================================================================================ 2024-04-15 21:30:21,699 INFO 2024-04-15 21:59:47 20s elapsed 2024-04-15 21:30:21,699 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,699 INFO > Global (OK=39997 KO=0 ) 2024-04-15 21:30:21,699 INFO > userRestLogin (OK=39997 KO=0 ) 2024-04-15 21:30:21,699 INFO 2024-04-15 21:30:21,699 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,699 INFO [- ] 0% 2024-04-15 21:30:21,699 INFO waiting: 10852139 / active: 52 / done: 39994 2024-04-15 21:30:21,699 INFO ================================================================================ 2024-04-15 21:30:21,699 INFO 2024-04-15 21:30:21,699 INFO 2024-04-15 21:30:21,699 INFO ================================================================================ 2024-04-15 21:30:21,699 INFO 2024-04-15 21:59:52 25s elapsed 2024-04-15 21:30:21,699 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,699 INFO > Global (OK=62492 KO=0 ) 2024-04-15 21:30:21,699 INFO > userRestLogin (OK=62492 KO=0 ) 2024-04-15 21:30:21,699 INFO 2024-04-15 21:30:21,700 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,700 INFO [- ] 0% 2024-04-15 21:30:21,700 INFO waiting: 10829623 / active: 71 / done: 62491 2024-04-15 21:30:21,700 INFO ================================================================================ 2024-04-15 21:30:21,700 INFO 2024-04-15 21:30:21,700 INFO 2024-04-15 21:30:21,700 INFO ================================================================================ 2024-04-15 21:30:21,700 INFO 2024-04-15 21:59:57 30s elapsed 2024-04-15 21:30:21,700 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,700 INFO > Global (OK=89839 KO=0 ) 2024-04-15 21:30:21,700 INFO > userRestLogin (OK=89839 KO=0 ) 2024-04-15 21:30:21,700 INFO 2024-04-15 21:30:21,700 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,700 INFO [- ] 0% 2024-04-15 21:30:21,700 INFO waiting: 10802263 / active: 88 / done: 89834 2024-04-15 21:30:21,701 INFO ================================================================================ 2024-04-15 21:30:21,701 INFO 2024-04-15 21:30:21,701 INFO 2024-04-15 21:30:21,701 INFO ================================================================================ 2024-04-15 21:30:21,701 INFO 2024-04-15 22:00:02 35s elapsed 2024-04-15 21:30:21,701 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,701 INFO > Global (OK=119714 KO=0 ) 2024-04-15 21:30:21,701 INFO > userRestLogin (OK=119714 KO=0 ) 2024-04-15 21:30:21,701 INFO 2024-04-15 21:30:21,701 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,701 INFO [- ] 1% 2024-04-15 21:30:21,701 INFO waiting: 10772306 / active: 167 / done: 119712 2024-04-15 21:30:21,701 INFO ================================================================================ 2024-04-15 21:30:21,701 INFO 2024-04-15 21:30:21,701 INFO 2024-04-15 21:30:21,701 INFO ================================================================================ 2024-04-15 21:30:21,701 INFO 2024-04-15 22:00:07 40s elapsed 2024-04-15 21:30:21,701 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,701 INFO > Global (OK=149267 KO=0 ) 2024-04-15 21:30:21,701 INFO [...] 2024-04-15 21:30:21,705 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,706 INFO > Global (OK=10742144 KO=0 ) 2024-04-15 21:30:21,706 INFO > userRestLogin (OK=10742144 KO=0 ) 2024-04-15 21:30:21,706 INFO 2024-04-15 21:30:21,706 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,706 INFO [########################################################################- ] 98% 2024-04-15 21:30:21,706 INFO waiting: 149956 / active: 85 / done: 10742144 2024-04-15 21:30:21,706 INFO ================================================================================ 2024-04-15 21:30:21,706 INFO 2024-04-15 21:30:21,706 INFO 2024-04-15 21:30:21,706 INFO ================================================================================ 2024-04-15 21:30:21,706 INFO 2024-04-15 22:29:37 1810s elapsed 2024-04-15 21:30:21,706 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,706 INFO > Global (OK=10772269 KO=0 ) 2024-04-15 21:30:21,707 INFO > userRestLogin (OK=10772269 KO=0 ) 2024-04-15 21:30:21,707 INFO 2024-04-15 21:30:21,707 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,707 INFO [#########################################################################-] 98% 2024-04-15 21:30:21,707 INFO waiting: 119699 / active: 217 / done: 10772269 2024-04-15 21:30:21,707 INFO ================================================================================ 2024-04-15 21:30:21,707 INFO 2024-04-15 21:30:21,707 INFO 2024-04-15 21:30:21,707 INFO ================================================================================ 2024-04-15 21:30:21,707 INFO 2024-04-15 22:29:42 1815s elapsed 2024-04-15 21:30:21,707 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,707 INFO > Global (OK=10802037 KO=0 ) 2024-04-15 21:30:21,707 INFO > userRestLogin (OK=10802037 KO=0 ) 2024-04-15 21:30:21,708 INFO 2024-04-15 21:30:21,708 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,708 INFO [#########################################################################-] 99% 2024-04-15 21:30:21,708 INFO waiting: 89789 / active: 360 / done: 10802036 2024-04-15 21:30:21,708 INFO ================================================================================ 2024-04-15 21:30:21,708 INFO 2024-04-15 21:30:21,708 INFO 2024-04-15 21:30:21,708 INFO ================================================================================ 2024-04-15 21:30:21,708 INFO 2024-04-15 22:29:47 1820s elapsed 2024-04-15 21:30:21,708 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,708 INFO > Global (OK=10832108 KO=0 ) 2024-04-15 21:30:21,708 INFO > userRestLogin (OK=10832108 KO=0 ) 2024-04-15 21:30:21,708 INFO 2024-04-15 21:30:21,708 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,708 INFO [#########################################################################-] 99% 2024-04-15 21:30:21,708 INFO waiting: 59820 / active: 257 / done: 10832108 2024-04-15 21:30:21,708 INFO ================================================================================ 2024-04-15 21:30:21,708 INFO 2024-04-15 21:30:21,709 INFO 2024-04-15 21:30:21,709 INFO ================================================================================ 2024-04-15 21:30:21,709 INFO 2024-04-15 22:29:52 1825s elapsed 2024-04-15 21:30:21,709 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,709 INFO > Global (OK=10862147 KO=0 ) 2024-04-15 21:30:21,709 INFO > userRestLogin (OK=10862147 KO=0 ) 2024-04-15 21:30:21,709 INFO 2024-04-15 21:30:21,709 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,709 INFO [#########################################################################-] 99% 2024-04-15 21:30:21,709 INFO waiting: 29882 / active: 160 / done: 10862143 2024-04-15 21:30:21,709 INFO ================================================================================ 2024-04-15 21:30:21,709 INFO 2024-04-15 21:30:21,709 INFO 2024-04-15 21:30:21,709 INFO ================================================================================ 2024-04-15 21:30:21,709 INFO 2024-04-15 22:29:57 1830s elapsed 2024-04-15 21:30:21,709 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,709 INFO > Global (OK=10891904 KO=0 ) 2024-04-15 21:30:21,709 INFO > userRestLogin (OK=10891904 KO=0 ) 2024-04-15 21:30:21,709 INFO 2024-04-15 21:30:21,709 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,710 INFO [#########################################################################-] 99% 2024-04-15 21:30:21,710 INFO waiting: 0 / active: 281 / done: 10891904 2024-04-15 21:30:21,710 INFO ================================================================================ 2024-04-15 21:30:21,710 INFO 2024-04-15 21:30:21,710 INFO 2024-04-15 21:30:21,710 INFO ================================================================================ 2024-04-15 21:30:21,710 INFO 2024-04-15 22:29:58 1830s elapsed 2024-04-15 21:30:21,711 INFO ---- Requests ------------------------------------------------------------------ 2024-04-15 21:30:21,711 INFO > Global (OK=10892185 KO=0 ) 2024-04-15 21:30:21,711 INFO > userRestLogin (OK=10892185 KO=0 ) 2024-04-15 21:30:21,711 INFO 2024-04-15 21:30:21,711 INFO ---- am.AMAuthN ---------------------------------------------------------------- 2024-04-15 21:30:21,711 INFO [##########################################################################]100% 2024-04-15 21:30:21,711 INFO waiting: 0 / active: 0 / done: 10892185 2024-04-15 21:30:21,711 INFO ================================================================================ 2024-04-15 21:30:21,711 INFO 2024-04-15 21:30:21,711 INFO Simulation com.forgerock.gatling.Loadtest completed in 1830 seconds 2024-04-15 21:30:21,711 INFO 2024-04-15 21:30:21,711 INFO ---------------------- 2024-04-15 21:30:21,711 INFO [15/Apr/2024 22:29:58] Create tar file authn-users-6000.tar.gz 2024-04-15 21:30:21,711 INFO ---------------------- 2024-04-15 21:30:21,711 INFO RESULTS_FOLDER=/results/gatling/ 2024-04-15 21:30:21,711 INFO RESULT_FOLDER=authn-users-6000 2024-04-15 21:30:21,711 INFO SIMULATION_PATH=authn-users-6000/loadtest-20240415205927096 2024-04-15 21:30:21,712 INFO PWD=/results/gatling/authn-users-6000/loadtest-20240415205927096 2024-04-15 21:30:21,712 INFO [cmd] tar -czvf /results/gatling//authn-users-6000.tar.gz --exclude=simulation.log * 2024-04-15 21:30:21,712 INFO /results/gatling 2024-04-15 21:30:21,712 INFO 2024-04-15 21:30:21,712 INFO ---------------------- 2024-04-15 21:30:21,712 INFO [15/Apr/2024 22:29:58] Update /results/gatling//latest 2024-04-15 21:30:21,712 INFO ---------------------- 2024-04-15 21:30:21,712 INFO 2024-04-15 21:30:21,712 INFO ---------------------- 2024-04-15 21:30:21,712 INFO [15/Apr/2024 22:29:58] Update /results/gatling//latest.tar.gz 2024-04-15 21:30:21,712 INFO ---------------------- 2024-04-15 21:30:21,712 INFO ________________________________________________________________________________ [2024-04-15 21:30:21] authn-users-6000 show_stderr : Display stderr order ________________________________________________________________________________ 2024-04-15 21:30:21,713 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 21:30:21,713 DEBUG ----- output ----- 2024-04-15 21:30:21,713 DEBUG 2024-04-15 21:30:21,713 INFO ________________________________________________________________________________ [2024-04-15 21:30:21] 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 21:30:21] 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 21:30:21] authn-users-6000 post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped