--Task-- name: preload-with-gatling enabled: True class_name: GatlingTask source_name: overseer-0 source_namespace: >default< target_name: idm target_namespace: >default< start: 0.0 stop: None timeout: not yet initialized for this Task as timeout was not explicitly defined, see updates below loop: False interval: None dependencies: ['prepare-workload'] wait_for: ['Pre-check Num Users'] preceding_task: None options: {'check-before-preload': True, 'testCase': 'idm.ManagedUsersPreload', 'concurrency': 35, 'java-opts': '-Dcsvfile=/results/idm/preload.csv -Doutput=/results/idm/user_feeder.csv -Drealm=alpha -Duser_password=Pa_ssw0rd'} group_name: None Current dir: /mnt/disk1/xslou/workshop/lodestar-fork/pyrock ________________________________________________________________________________ [2024-10-16 19:49:56] preload-with-gatling pre : N/A ________________________________________________________________________________ 2024-10-16 19:49:56 - INFO: Timeout for this Task has changed to 2h (7200 seconds) 2024-10-16 19:49:56 - INFO: timeout was set to GatlingTask default, because it was unset Pod log file : /results/orders/preload-with-gatling/stdout.txt Pod log : /results/orders/preload-with-gatling 2024-10-16 19:49:56,974 INFO 2024-10-16 19:49:56,974 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-10-16 19:49:56,974 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 19:49:57,243 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 19:49:57,243 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:57,243 DEBUG --- stdout --- 2024-10-16 19:49:57,243 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 19:49:57,243 DEBUG --- stderr --- 2024-10-16 19:49:57,243 DEBUG ________________________________________________________________________________ [2024-10-16 19:49:57] preload-with-gatling print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-10-16 19:49:57,244 INFO 2024-10-16 19:49:57,244 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-10-16 19:49:57,244 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 19:49:57,514 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 19:49:57,514 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:57,515 DEBUG --- stdout --- 2024-10-16 19:49:57,515 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 19:49:57,515 DEBUG --- stderr --- 2024-10-16 19:49:57,515 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description "idm.ManagedUsersPreload@perf-benchmark" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-10-16 19:49:57] preload-with-gatling create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-10-16 19:49:57,515 INFO 2024-10-16 19:49:57,515 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-10-16 19:49:57,515 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 19:49:57,786 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 19:49:57,786 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:57,786 DEBUG --- stdout --- 2024-10-16 19:49:57,786 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 19:49:57,786 DEBUG --- stderr --- 2024-10-16 19:49:57,786 DEBUG Order file: /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/order.json { "name": "preload-with-gatling", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description \"idm.ManagedUsersPreload@perf-benchmark\" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options \"-Xmx4G\" --no-reports", "out-dir": "/results/orders/preload-with-gatling", "environment": { "JAVA_OPTS": "-Dam_host=openam-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@pingidentity.com -Dcheck_before_preload=True -Dconcurrency=35 -Dcsvfile=/results/idm/preload.csv -Didc=true -Didm_host=openam-perf-benchmark.forgeblocks.com -Dlogin_cookie=9b75a55744995d0 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Doutput=/results/idm/user_feeder.csv -Drealm=alpha -DtestCase=idm.ManagedUsersPreload -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-10-16 19:49:57] preload-with-gatling 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-10-16 19:49:57,788 INFO 2024-10-16 19:49:57,788 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-10-16 19:49:57,788 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-10-16 19:49:58,045 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-10-16 19:49:58,045 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:58,045 DEBUG --- stdout --- 2024-10-16 19:49:58,045 DEBUG WAITING 2024-10-16 19:49:58,045 DEBUG --- stderr --- 2024-10-16 19:49:58,045 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 28 0 --:--:-- --:--:-- --:--:-- 28 ________________________________________________________________________________ [2024-10-16 19:49:58] preload-with-gatling start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-10-16 19:49:58,046 INFO 2024-10-16 19:49:58,046 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-10-16 19:49:58,046 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 19:49:58,318 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 19:49:58,318 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:58,318 DEBUG --- stdout --- 2024-10-16 19:49:58,319 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 19:49:58,319 DEBUG --- stderr --- 2024-10-16 19:49:58,319 DEBUG 2024-10-16 19:49:58,319 INFO 2024-10-16 19:49:58,319 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/order.json overseer-0-68b86489-ztj5j:/results/orders/order.json 2024-10-16 19:49:58,319 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-10-16 19:49:59,650 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:59,650 DEBUG --- stdout --- 2024-10-16 19:49:59,650 DEBUG 2024-10-16 19:49:59,650 DEBUG --- stderr --- 2024-10-16 19:49:59,650 DEBUG Order has been successfully forwarded to overseer 2024-10-16 19:49:59 - INFO: Check if Gatling simulation started successfully 2024-10-16 19:49:59,650 INFO 2024-10-16 19:49:59,650 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-10-16 19:49:59,650 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 19:49:59,921 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 19:49:59,921 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:49:59,921 DEBUG --- stdout --- 2024-10-16 19:49:59,921 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 19:49:59,921 DEBUG --- stderr --- 2024-10-16 19:49:59,921 DEBUG 2024-10-16 19:49:59,921 INFO 2024-10-16 19:49:59,921 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j -- head -n 25 /results/orders/preload-with-gatling/stdout.txt | grep "started..." 2024-10-16 19:49:59,921 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-10-16 19:50:00,632 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-10-16 19:50:06,348 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-10-16 19:50:12,068 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-10-16 19:50:12,068 INFO [loop_until]: OK (rc = 0) 2024-10-16 19:50:12,068 DEBUG --- stdout --- 2024-10-16 19:50:12,068 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 20:50:01.863 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 20:50:06.106 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling/benchmark/Testcase.scala:176:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 20:50:06.108 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling/benchmark/Testcase.scala:279:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map crossSessionVariables.-("poliEvalTokenId"); ^ 20:50:07.877 [WARN ] i.g.c.ZincCompiler$ - three warnings found Gatling 3.12.0 is available! (you're using 3.9.5) System property think_time_multiplier not set. No think time emulation will be done in the simulation. Simulation com.forgerock.gatling.Loadtest started... 2024-10-16 19:50:12,068 DEBUG --- stderr --- 2024-10-16 19:50:12,068 DEBUG ________________________________________________________________________________ [2024-10-16 19:50:12] preload-with-gatling 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 2h. 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 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 1m 59s, 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 46s, 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 07s, 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 33s, 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 54s, 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 20s, 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 41s, retry Order is running since 4m 46s, retry Order is running since 4m 52s, retry Order is running since 4m 57s, retry Order is running since 5m 02s, retry Order is running since 5m 07s, 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 28s, retry Order is running since 5m 33s, retry Order is running since 5m 39s, retry Order is running since 5m 44s, retry Order is running since 5m 49s, retry Order is running since 5m 54s, retry Order is running since 5m 59s, retry Order is running since 6m 05s, retry Order is running since 6m 10s, retry Order is running since 6m 15s, retry Order is running since 6m 20s, retry Order is running since 6m 26s, retry Order is running since 6m 31s, retry Order is running since 6m 36s, retry Order is running since 6m 41s, retry Order is running since 6m 46s, retry Order is running since 6m 52s, retry Order is running since 6m 57s, retry Order is running since 7m 02s, retry Order is running since 7m 07s, retry Order is running since 7m 12s, retry Order is running since 7m 18s, retry Order is running since 7m 23s, retry Order is running since 7m 28s, retry Order is running since 7m 33s, retry Order is running since 7m 39s, retry Order is running since 7m 44s, retry Order is running since 7m 49s, retry Order is running since 7m 54s, retry Order is running since 7m 59s, retry Order is running since 8m 05s, retry Order is running since 8m 10s, retry Order is running since 8m 15s, retry Order is running since 8m 20s, retry Order is running since 8m 26s, retry Order is running since 8m 31s, retry Order is running since 8m 36s, retry Order is running since 8m 41s, retry Order is running since 8m 46s, retry Order is running since 8m 52s, retry Order is running since 8m 57s, retry Order is running since 9m 02s, retry Order is running since 9m 07s, retry Order is running since 9m 13s, retry Order is running since 9m 18s, retry Order is running since 9m 23s, retry Order is running since 9m 28s, retry Order is running since 9m 33s, retry Order is running since 9m 39s, retry Order is running since 9m 44s, retry Order is running since 9m 49s, retry Order is running since 9m 54s, retry Order is running since 9m 59s, retry Order is running since 10m 05s, retry Order is running since 10m 10s, retry Order is running since 10m 15s, retry Order is running since 10m 20s, retry Order is running since 10m 25s, retry Order is running since 10m 31s, retry Order is running since 10m 36s, retry Order is running since 10m 41s, retry Order is running since 10m 46s, retry Order is running since 10m 52s, retry Order is running since 10m 57s, retry Order is running since 11m 02s, retry Order is running since 11m 07s, retry Order is running since 11m 12s, retry Order is running since 11m 18s, retry Order is running since 11m 23s, retry Order is running since 11m 28s, retry Order is running since 11m 33s, retry Order is running since 11m 39s, retry Order is running since 11m 44s, retry Order is running since 11m 49s, retry Order is running since 11m 54s, retry Order is running since 11m 59s, retry Order is running since 12m 05s, retry Order is running since 12m 10s, retry Order is running since 12m 15s, retry Order is running since 12m 20s, retry Order is running since 12m 25s, retry Order is running since 12m 31s, retry Order is running since 12m 36s, retry Order is running since 12m 41s, retry Order is running since 12m 46s, retry Order is running since 12m 52s, retry Order is running since 12m 57s, retry Order is running since 13m 02s, retry Order is running since 13m 07s, retry Order is running since 13m 12s, retry Order is running since 13m 18s, retry Order is running since 13m 23s, retry Order is running since 13m 28s, retry Order is running since 13m 33s, retry Order is running since 13m 39s, retry Order is running since 13m 44s, retry Order is running since 13m 49s, retry Order is running since 13m 54s, retry Order is running since 13m 59s, retry Order is running since 14m 05s, retry Order is running since 14m 10s, retry Order is running since 14m 15s, retry Order is running since 14m 20s, retry Order is running since 14m 25s, retry Order is running since 14m 31s, retry Order is running since 14m 36s, retry Order is running since 14m 41s, retry Order is running since 14m 46s, retry Order is running since 14m 52s, retry Order is running since 14m 57s, retry Order is running since 15m 02s, retry Order is running since 15m 07s, retry Order is running since 15m 12s, retry Order is running since 15m 18s, retry Order is running since 15m 23s, retry Order is running since 15m 28s, retry Order is running since 15m 33s, retry Order is running since 15m 39s, retry Order is running since 15m 44s, retry Order is running since 15m 49s, retry Order is running since 15m 54s, retry Order is running since 15m 59s, retry Order is running since 16m 05s, retry Order is running since 16m 10s, retry Order is running since 16m 15s, retry Order is running since 16m 20s, retry Order is running since 16m 25s, retry Order is running since 16m 31s, retry Order is running since 16m 36s, retry Order is running since 16m 41s, retry Order is running since 16m 46s, retry Order is running since 16m 51s, retry Order is running since 16m 57s, retry Order is running since 17m 02s, retry Order is running since 17m 07s, retry Order is running since 17m 12s, retry Order is running since 17m 18s, retry Order is running since 17m 23s, retry Order is running since 17m 28s, retry Order is running since 17m 33s, retry Order is running since 17m 38s, retry Order is running since 17m 44s, retry Order is running since 17m 49s, retry Order is running since 17m 54s, retry Order is running since 17m 59s, retry Order is running since 18m 05s, retry Order is running since 18m 10s, retry Order is running since 18m 15s, retry Order is running since 18m 20s, retry Order is running since 18m 25s, retry Order is running since 18m 31s, retry Order is running since 18m 36s, retry Order is running since 18m 41s, retry Order is running since 18m 46s, retry Order is running since 18m 52s, retry Order is running since 18m 57s, retry Order is running since 19m 02s, retry Order is running since 19m 07s, retry Order is running since 19m 12s, retry Order is running since 19m 18s, retry Order is running since 19m 23s, retry Order is running since 19m 28s, retry Order is running since 19m 33s, retry Order is running since 19m 38s, retry Order is running since 19m 44s, retry Order is running since 19m 49s, retry Order is running since 19m 54s, retry Order is running since 19m 59s, retry Order is running since 20m 05s, retry Order is running since 20m 10s, retry Order is running since 20m 15s, retry Order is running since 20m 20s, retry Order is running since 20m 25s, retry Order is running since 20m 31s, retry Order is running since 20m 36s, retry Order is running since 20m 41s, retry Order is running since 20m 46s, retry Order is running since 20m 51s, retry Order is running since 20m 57s, retry Order is running since 21m 02s, retry Order is running since 21m 07s, retry Order is running since 21m 12s, retry Order is running since 21m 18s, retry Order is running since 21m 23s, retry Order is running since 21m 28s, retry Order is running since 21m 33s, retry Order is running since 21m 39s, retry Order is running since 21m 44s, retry Order is running since 21m 49s, retry Order is running since 21m 54s, retry Order is running since 21m 59s, retry Order is running since 22m 05s, retry Order is running since 22m 10s, retry Order is running since 22m 15s, retry Order is running since 22m 20s, retry Order is running since 22m 25s, retry Order is running since 22m 31s, retry Order is running since 22m 36s, retry Order is running since 22m 41s, retry Order is running since 22m 46s, retry Order is running since 22m 52s, retry Order is running since 22m 57s, retry Order is running since 23m 02s, retry Order is running since 23m 07s, retry Order is running since 23m 12s, retry Order is running since 23m 18s, retry Order is running since 23m 23s, retry Order is running since 23m 28s, retry Order is running since 23m 33s, retry Order is running since 23m 39s, retry Order is running since 23m 44s, retry Order is running since 23m 49s, retry Order is running since 23m 54s, retry Order is running since 23m 59s, retry Order is running since 24m 05s, retry Order is running since 24m 10s, retry Order is running since 24m 15s, retry Order is running since 24m 20s, retry Order is running since 24m 25s, retry Order is running since 24m 31s, retry Order is running since 24m 36s, retry Order is running since 24m 41s, retry Order is running since 24m 46s, retry Order is running since 24m 52s, retry Order is running since 24m 57s, retry Order is running since 25m 02s, retry Order is running since 25m 07s, retry Order is running since 25m 12s, retry Order is running since 25m 18s, retry Order is running since 25m 23s, retry Order is running since 25m 28s, retry Order is running since 25m 33s, retry Order is running since 25m 39s, retry Order is running since 25m 44s, retry Order is running since 25m 49s, retry Order is running since 25m 54s, retry Order is running since 25m 59s, retry Order is running since 26m 05s, retry Order is running since 26m 10s, retry Order is running since 26m 15s, retry Order is running since 26m 20s, retry Order is running since 26m 25s, retry Order is running since 26m 31s, retry Order is running since 26m 36s, retry Order is running since 26m 41s, retry Order is running since 26m 46s, retry Order is running since 26m 52s, retry Order is running since 26m 57s, retry Order is running since 27m 02s, retry Order is running since 27m 07s, retry Order is running since 27m 12s, retry Order is running since 27m 18s, retry Order is running since 27m 23s, retry Order is running since 27m 28s, retry Order is running since 27m 33s, retry Order is running since 27m 39s, retry Order is running since 27m 44s, retry Order is running since 27m 49s, retry Order is running since 27m 54s, retry Order is running since 27m 59s, retry Order is running since 28m 05s, retry Order is running since 28m 10s, retry Order is running since 28m 15s, retry Order is running since 28m 20s, retry Order is running since 28m 26s, retry Order is running since 28m 31s, retry Order is running since 28m 36s, retry Order is running since 28m 41s, retry Order is running since 28m 46s, retry Order is running since 28m 52s, retry Order is running since 28m 57s, retry Order is running since 29m 02s, retry Order is running since 29m 07s, retry Order is running since 29m 13s, retry Order is running since 29m 18s, retry Order is running since 29m 23s, retry Order is running since 29m 28s, retry Order is running since 29m 33s, retry Order is running since 29m 39s, retry Order is running since 29m 44s, retry Order is running since 29m 49s, retry Order is running since 29m 54s, retry Order is running since 29m 59s, retry Order is running since 30m 05s, retry Order is running since 30m 10s, retry Order is running since 30m 15s, retry Order is running since 30m 20s, retry Order is running since 30m 26s, retry Order is running since 30m 31s, retry Order is running since 30m 36s, retry Order is running since 30m 41s, retry Order is running since 30m 46s, retry Order is running since 30m 52s, retry Order is running since 30m 57s, retry Order is running since 31m 02s, retry Order is running since 31m 07s, retry Order is running since 31m 12s, retry Order is running since 31m 18s, retry Order is running since 31m 23s, retry Order is running since 31m 28s, retry Order is running since 31m 33s, retry Order is running since 31m 39s, retry Order is running since 31m 44s, retry Order is running since 31m 49s, retry Order is running since 31m 54s, retry Order is running since 31m 59s, retry Order is running since 32m 05s, retry Order is running since 32m 10s, retry Order is running since 32m 15s, retry Order is running since 32m 20s, retry Order is running since 32m 26s, retry Order is running since 32m 31s, retry Order is running since 32m 36s, retry Order is running since 32m 41s, retry Order is running since 32m 46s, retry Order is running since 32m 52s, retry Order is running since 32m 57s, retry Order is running since 33m 02s, retry Order is running since 33m 07s, retry Order is running since 33m 12s, retry Order is running since 33m 18s, retry Order is running since 33m 23s, retry Order is running since 33m 28s, retry Order is running since 33m 33s, retry Order is running since 33m 39s, retry Order is running since 33m 44s, retry Order is running since 33m 49s, retry Order is running since 33m 54s, retry Order is running since 33m 59s, retry Order is running since 34m 05s, retry Order is running since 34m 10s, retry Order is running since 34m 15s, retry Order is running since 34m 20s, retry Order is running since 34m 26s, retry Order is running since 34m 31s, retry Order is running since 34m 36s, retry Order is running since 34m 41s, retry Order is running since 34m 46s, retry Order is running since 34m 52s, retry Order is running since 34m 57s, retry Order is running since 35m 02s, retry Order is running since 35m 07s, retry Order is running since 35m 12s, retry Order is running since 35m 18s, retry Order is running since 35m 23s, retry Order is running since 35m 28s, retry Order is running since 35m 33s, retry Order is running since 35m 39s, retry Order is running since 35m 44s, retry Order is running since 35m 49s, retry Order is running since 35m 54s, retry Order is running since 35m 59s, retry Order is running since 36m 05s, retry Order is running since 36m 10s, retry Order is running since 36m 15s, retry Order is running since 36m 20s, retry Order is running since 36m 26s, retry Order is running since 36m 31s, retry Order is running since 36m 36s, retry Order is running since 36m 41s, retry Order is running since 36m 46s, retry Order is running since 36m 52s, retry Order is running since 36m 57s, retry Order is running since 37m 02s, retry Order is running since 37m 07s, retry Order is running since 37m 13s, retry Order is running since 37m 18s, retry Order is running since 37m 23s, retry Order is running since 37m 28s, retry Order is running since 37m 33s, retry Order is running since 37m 39s, retry Order is running since 37m 44s, retry Order is running since 37m 49s, retry Order is running since 37m 54s, retry Order is running since 37m 59s, retry Order is running since 38m 05s, retry Order is running since 38m 10s, retry Order is running since 38m 15s, retry Order is running since 38m 20s, retry Order is running since 38m 26s, retry Order is running since 38m 31s, retry Order is running since 38m 36s, retry Order is running since 38m 41s, retry Order is running since 38m 46s, retry Order is running since 38m 52s, retry Order is running since 38m 57s, retry Order is running since 39m 02s, retry Order is running since 39m 07s, retry Order is running since 39m 12s, retry Order is running since 39m 18s, retry Order is running since 39m 23s, retry Order is running since 39m 28s, retry Order is running since 39m 33s, retry Order is running since 39m 38s, retry Order is running since 39m 44s, retry Order is running since 39m 49s, retry Order is running since 39m 54s, retry Order is running since 39m 59s, retry Order is running since 40m 05s, retry Order is running since 40m 10s, retry Order is running since 40m 15s, retry Order is running since 40m 20s, retry Order is running since 40m 25s, retry Order is running since 40m 31s, retry Order is running since 40m 36s, retry Order is running since 40m 41s, retry Order is running since 40m 46s, retry Order is running since 40m 51s, retry Order is running since 40m 57s, retry Order is running since 41m 02s, retry Order is running since 41m 07s, retry Order is running since 41m 12s, retry Order is running since 41m 18s, retry Order is running since 41m 23s, retry Order is running since 41m 28s, retry Order is running since 41m 33s, retry Order is running since 41m 38s, retry Order is running since 41m 44s, retry Order is running since 41m 49s, retry Order is running since 41m 54s, retry Order is running since 41m 59s, retry Order is running since 42m 04s, retry Order is running since 42m 10s, retry Order is running since 42m 15s, retry Order is running since 42m 20s, retry Order is running since 42m 25s, retry Order is running since 42m 31s, retry Order is running since 42m 36s, retry Order is running since 42m 41s, retry Order is running since 42m 46s, retry Order is running since 42m 51s, retry PASS : Order stopped by itself after 42m 57s and before timeout (2h) ________________________________________________________________________________ [2024-10-16 20:33:09] preload-with-gatling stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-10-16 20:33:09,170 INFO 2024-10-16 20:33:09,170 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-10-16 20:33:09,170 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:09,459 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:09,460 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:09,460 DEBUG --- stdout --- 2024-10-16 20:33:09,460 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:09,460 DEBUG --- stderr --- 2024-10-16 20:33:09,460 DEBUG 2024-10-16 20:33:09,460 INFO 2024-10-16 20:33:09,460 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j -- rm -f /results/orders/order.json 2024-10-16 20:33:09,460 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-10-16 20:33:10,208 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:10,208 DEBUG --- stdout --- 2024-10-16 20:33:10,208 DEBUG 2024-10-16 20:33:10,208 DEBUG --- stderr --- 2024-10-16 20:33:10,208 DEBUG Order ran during 43m 10s ________________________________________________________________________________ [2024-10-16 20:33:10] preload-with-gatling download_logs : Download individual order files ________________________________________________________________________________ 2024-10-16 20:33:10 - PASS: file already downloaded /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/order.json 2024-10-16 20:33:10,209 INFO 2024-10-16 20:33:10,209 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-10-16 20:33:10,209 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:10,480 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:10,480 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:10,480 DEBUG --- stdout --- 2024-10-16 20:33:10,480 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:10,480 DEBUG --- stderr --- 2024-10-16 20:33:10,480 DEBUG Checking pod: overseer-0-68b86489-ztj5j for file exists, if not wait. 2024-10-16 20:33:10,480 INFO 2024-10-16 20:33:10,480 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-10-16 20:33:10,480 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:10,752 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:10,752 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:10,752 DEBUG --- stdout --- 2024-10-16 20:33:10,752 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:10,752 DEBUG --- stderr --- 2024-10-16 20:33:10,752 DEBUG 2024-10-16 20:33:10,753 INFO 2024-10-16 20:33:10,753 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/rc.txt" ]; then echo "/results/orders/preload-with-gatling/rc.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/rc.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/rc.txt file found" 2024-10-16 20:33:10,753 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-10-16 20:33:11,540 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-10-16 20:33:11,540 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:11,540 DEBUG --- stdout --- 2024-10-16 20:33:11,540 DEBUG /results/orders/preload-with-gatling/rc.txt file found 2024-10-16 20:33:11,540 DEBUG --- stderr --- 2024-10-16 20:33:11,540 DEBUG 2024-10-16 20:33:11,541 INFO 2024-10-16 20:33:11,541 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-10-16 20:33:11,541 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:11,810 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:11,810 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:11,810 DEBUG --- stdout --- 2024-10-16 20:33:11,810 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:11,810 DEBUG --- stderr --- 2024-10-16 20:33:11,810 DEBUG 2024-10-16 20:33:11,810 INFO 2024-10-16 20:33:11,811 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- du -k /results/orders/preload-with-gatling/rc.txt 2024-10-16 20:33:12,592 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-10-16 20:33:12,592 DEBUG --- stdout --- 2024-10-16 20:33:12,592 DEBUG 4 /results/orders/preload-with-gatling/rc.txt 2024-10-16 20:33:12,592 DEBUG --- stderr --- 2024-10-16 20:33:12,592 DEBUG 2024-10-16 20:33:12,592 INFO - Download file (size 4k) 2024-10-16 20:33:12,593 INFO 2024-10-16 20:33:12,593 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-10-16 20:33:12,593 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:12,864 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:12,864 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:12,864 DEBUG --- stdout --- 2024-10-16 20:33:12,864 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:12,864 DEBUG --- stderr --- 2024-10-16 20:33:12,864 DEBUG source : /results/orders/preload-with-gatling/rc.txt @ overseer-0-68b86489-ztj5j destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/rc.txt 2024-10-16 20:33:12,865 INFO 2024-10-16 20:33:12,865 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-10-16 20:33:12,865 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:13,137 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:13,138 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:13,138 DEBUG --- stdout --- 2024-10-16 20:33:13,138 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:13,138 DEBUG --- stderr --- 2024-10-16 20:33:13,138 DEBUG 2024-10-16 20:33:13,138 INFO 2024-10-16 20:33:13,138 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68b86489-ztj5j:/results/orders/preload-with-gatling/rc.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/rc.txt 2024-10-16 20:33:13,138 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-10-16 20:33:13,899 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:13,899 DEBUG --- stdout --- 2024-10-16 20:33:13,899 DEBUG tar: Removing leading `/' from member names 2024-10-16 20:33:13,899 DEBUG --- stderr --- 2024-10-16 20:33:13,899 DEBUG 2024-10-16 20:33:13,900 INFO 2024-10-16 20:33:13,900 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-10-16 20:33:13,900 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:14,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:14,173 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:14,173 DEBUG --- stdout --- 2024-10-16 20:33:14,173 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:14,173 DEBUG --- stderr --- 2024-10-16 20:33:14,173 DEBUG Checking pod: overseer-0-68b86489-ztj5j for file exists, if not wait. 2024-10-16 20:33:14,174 INFO 2024-10-16 20:33:14,174 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-10-16 20:33:14,174 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:14,447 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:14,447 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:14,447 DEBUG --- stdout --- 2024-10-16 20:33:14,447 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:14,447 DEBUG --- stderr --- 2024-10-16 20:33:14,447 DEBUG 2024-10-16 20:33:14,448 INFO 2024-10-16 20:33:14,448 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/stderr.txt" ]; then echo "/results/orders/preload-with-gatling/stderr.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/stderr.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/stderr.txt file found" 2024-10-16 20:33:14,449 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-10-16 20:33:15,235 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-10-16 20:33:15,235 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:15,235 DEBUG --- stdout --- 2024-10-16 20:33:15,235 DEBUG /results/orders/preload-with-gatling/stderr.txt file found 2024-10-16 20:33:15,235 DEBUG --- stderr --- 2024-10-16 20:33:15,235 DEBUG 2024-10-16 20:33:15,236 INFO 2024-10-16 20:33:15,236 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-10-16 20:33:15,236 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:15,510 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:15,510 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:15,510 DEBUG --- stdout --- 2024-10-16 20:33:15,510 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:15,510 DEBUG --- stderr --- 2024-10-16 20:33:15,510 DEBUG 2024-10-16 20:33:15,510 INFO 2024-10-16 20:33:15,510 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- du -k /results/orders/preload-with-gatling/stderr.txt 2024-10-16 20:33:16,294 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-10-16 20:33:16,294 DEBUG --- stdout --- 2024-10-16 20:33:16,294 DEBUG 0 /results/orders/preload-with-gatling/stderr.txt 2024-10-16 20:33:16,294 DEBUG --- stderr --- 2024-10-16 20:33:16,294 DEBUG 2024-10-16 20:33:16,294 INFO - Download file (size 0k) 2024-10-16 20:33:16,294 INFO 2024-10-16 20:33:16,294 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-10-16 20:33:16,294 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:16,566 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:16,566 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:16,566 DEBUG --- stdout --- 2024-10-16 20:33:16,566 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:16,567 DEBUG --- stderr --- 2024-10-16 20:33:16,567 DEBUG source : /results/orders/preload-with-gatling/stderr.txt @ overseer-0-68b86489-ztj5j destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/stderr.txt 2024-10-16 20:33:16,567 INFO 2024-10-16 20:33:16,567 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-10-16 20:33:16,567 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:16,838 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:16,838 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:16,838 DEBUG --- stdout --- 2024-10-16 20:33:16,838 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:16,838 DEBUG --- stderr --- 2024-10-16 20:33:16,838 DEBUG 2024-10-16 20:33:16,838 INFO 2024-10-16 20:33:16,838 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68b86489-ztj5j:/results/orders/preload-with-gatling/stderr.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/stderr.txt 2024-10-16 20:33:16,839 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-10-16 20:33:17,579 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:17,580 DEBUG --- stdout --- 2024-10-16 20:33:17,580 DEBUG tar: Removing leading `/' from member names 2024-10-16 20:33:17,580 DEBUG --- stderr --- 2024-10-16 20:33:17,580 DEBUG 2024-10-16 20:33:17,580 INFO 2024-10-16 20:33:17,580 INFO [loop_until]: awk -F" " "{print NF}" <<< `kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm get pods --selector app=overseer-0 --output jsonpath={.items[*].metadata.name}` | grep 1 2024-10-16 20:33:17,580 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:17,849 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:17,849 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:17,849 DEBUG --- stdout --- 2024-10-16 20:33:17,849 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:17,849 DEBUG --- stderr --- 2024-10-16 20:33:17,849 DEBUG Checking pod: overseer-0-68b86489-ztj5j for file exists, if not wait. 2024-10-16 20:33:17,849 INFO 2024-10-16 20:33:17,849 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-10-16 20:33:17,849 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:18,122 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:18,122 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:18,122 DEBUG --- stdout --- 2024-10-16 20:33:18,122 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:18,122 DEBUG --- stderr --- 2024-10-16 20:33:18,122 DEBUG 2024-10-16 20:33:18,123 INFO 2024-10-16 20:33:18,123 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/stdout.txt" ]; then echo "/results/orders/preload-with-gatling/stdout.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/stdout.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/stdout.txt file found" 2024-10-16 20:33:18,123 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-10-16 20:33:18,897 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-10-16 20:33:18,897 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:18,897 DEBUG --- stdout --- 2024-10-16 20:33:18,897 DEBUG /results/orders/preload-with-gatling/stdout.txt file found 2024-10-16 20:33:18,897 DEBUG --- stderr --- 2024-10-16 20:33:18,897 DEBUG 2024-10-16 20:33:18,897 INFO 2024-10-16 20:33:18,898 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-10-16 20:33:18,898 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:19,200 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:19,200 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:19,200 DEBUG --- stdout --- 2024-10-16 20:33:19,200 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:19,200 DEBUG --- stderr --- 2024-10-16 20:33:19,200 DEBUG 2024-10-16 20:33:19,201 INFO 2024-10-16 20:33:19,201 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- du -k /results/orders/preload-with-gatling/stdout.txt 2024-10-16 20:33:19,986 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-10-16 20:33:19,986 DEBUG --- stdout --- 2024-10-16 20:33:19,986 DEBUG 676 /results/orders/preload-with-gatling/stdout.txt 2024-10-16 20:33:19,986 DEBUG --- stderr --- 2024-10-16 20:33:19,986 DEBUG 2024-10-16 20:33:19,986 INFO - Download file (size 676k) 2024-10-16 20:33:19,986 INFO 2024-10-16 20:33:19,986 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-10-16 20:33:19,986 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:20,258 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:20,258 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:20,258 DEBUG --- stdout --- 2024-10-16 20:33:20,258 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:20,258 DEBUG --- stderr --- 2024-10-16 20:33:20,258 DEBUG source : /results/orders/preload-with-gatling/stdout.txt @ overseer-0-68b86489-ztj5j destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/stdout.txt 2024-10-16 20:33:20,258 INFO 2024-10-16 20:33:20,258 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-10-16 20:33:20,258 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:20,538 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:20,538 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:20,538 DEBUG --- stdout --- 2024-10-16 20:33:20,538 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:20,538 DEBUG --- stderr --- 2024-10-16 20:33:20,538 DEBUG 2024-10-16 20:33:20,539 INFO 2024-10-16 20:33:20,539 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68b86489-ztj5j:/results/orders/preload-with-gatling/stdout.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/stdout.txt 2024-10-16 20:33:20,539 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-10-16 20:33:21,580 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:21,580 DEBUG --- stdout --- 2024-10-16 20:33:21,580 DEBUG tar: Removing leading `/' from member names 2024-10-16 20:33:21,580 DEBUG --- stderr --- 2024-10-16 20:33:21,580 DEBUG ________________________________________________________________________________ [2024-10-16 20:33:21] preload-with-gatling show_cmd : Display order command ________________________________________________________________________________ 2024-10-16 20:33:21,581 INFO 2024-10-16 20:33:21,581 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-10-16 20:33:21,581 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:21,853 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:21,853 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:21,853 DEBUG --- stdout --- 2024-10-16 20:33:21,853 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:21,853 DEBUG --- stderr --- 2024-10-16 20:33:21,853 DEBUG Checking pod: overseer-0-68b86489-ztj5j for file exists, if not wait. 2024-10-16 20:33:21,853 INFO 2024-10-16 20:33:21,853 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-10-16 20:33:21,853 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:22,124 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:22,124 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:22,124 DEBUG --- stdout --- 2024-10-16 20:33:22,124 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:22,124 DEBUG --- stderr --- 2024-10-16 20:33:22,124 DEBUG 2024-10-16 20:33:22,125 INFO 2024-10-16 20:33:22,125 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- bash -c 'if [ -f "/results/orders/preload-with-gatling/cmd.txt" ]; then echo "/results/orders/preload-with-gatling/cmd.txt file found"; exit 0; else echo "/results/orders/preload-with-gatling/cmd.txt file not found"; exit 1; fi' | grep "/results/orders/preload-with-gatling/cmd.txt file found" 2024-10-16 20:33:22,125 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-10-16 20:33:22,903 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-10-16 20:33:22,903 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:22,903 DEBUG --- stdout --- 2024-10-16 20:33:22,903 DEBUG /results/orders/preload-with-gatling/cmd.txt file found 2024-10-16 20:33:22,903 DEBUG --- stderr --- 2024-10-16 20:33:22,903 DEBUG 2024-10-16 20:33:22,903 INFO 2024-10-16 20:33:22,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-10-16 20:33:22,903 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:23,173 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:23,173 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:23,173 DEBUG --- stdout --- 2024-10-16 20:33:23,174 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:23,174 DEBUG --- stderr --- 2024-10-16 20:33:23,174 DEBUG 2024-10-16 20:33:23,174 INFO 2024-10-16 20:33:23,174 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-68b86489-ztj5j --stdin -- du -k /results/orders/preload-with-gatling/cmd.txt 2024-10-16 20:33:23,954 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-10-16 20:33:23,954 DEBUG --- stdout --- 2024-10-16 20:33:23,954 DEBUG 4 /results/orders/preload-with-gatling/cmd.txt 2024-10-16 20:33:23,954 DEBUG --- stderr --- 2024-10-16 20:33:23,954 DEBUG 2024-10-16 20:33:23,954 INFO - Download file (size 4k) 2024-10-16 20:33:23,954 INFO 2024-10-16 20:33:23,954 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-10-16 20:33:23,954 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:24,226 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:24,226 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:24,227 DEBUG --- stdout --- 2024-10-16 20:33:24,228 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:24,228 DEBUG --- stderr --- 2024-10-16 20:33:24,228 DEBUG source : /results/orders/preload-with-gatling/cmd.txt @ overseer-0-68b86489-ztj5j destination : /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/cmd.txt 2024-10-16 20:33:24,228 INFO 2024-10-16 20:33:24,228 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-10-16 20:33:24,228 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-10-16 20:33:24,500 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-10-16 20:33:24,500 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:24,500 DEBUG --- stdout --- 2024-10-16 20:33:24,500 DEBUG overseer-0-68b86489-ztj5j 2024-10-16 20:33:24,500 DEBUG --- stderr --- 2024-10-16 20:33:24,500 DEBUG 2024-10-16 20:33:24,501 INFO 2024-10-16 20:33:24,501 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-68b86489-ztj5j:/results/orders/preload-with-gatling/cmd.txt /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/cmd.txt 2024-10-16 20:33:24,501 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-10-16 20:33:25,241 INFO [loop_until]: OK (rc = 0) 2024-10-16 20:33:25,241 DEBUG --- stdout --- 2024-10-16 20:33:25,241 DEBUG tar: Removing leading `/' from member names 2024-10-16 20:33:25,241 DEBUG --- stderr --- 2024-10-16 20:33:25,241 DEBUG 2024-10-16 20:33:25,241 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/cmd.txt is small (1 lines), printing all lines: 2024-10-16 20:33:25,241 DEBUG ----- output ----- 2024-10-16 20:33:25,241 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation com.forgerock.gatling.Loadtest --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description "idm.ManagedUsersPreload@perf-benchmark" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options "-Xmx4G" --no-reports 2024-10-16 20:33:25,241 INFO ________________________________________________________________________________ [2024-10-16 20:33:25] preload-with-gatling show_rc : Display rc order ________________________________________________________________________________ 2024-10-16 20:33:25,242 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/rc.txt is small (1 lines), printing all lines: 2024-10-16 20:33:25,242 DEBUG ----- output ----- 2024-10-16 20:33:25,242 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-10-16 20:33:25,242 INFO ________________________________________________________________________________ [2024-10-16 20:33:25] preload-with-gatling show_stdout : Display stdout order ________________________________________________________________________________ 2024-10-16 20:33:25,243 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/stdout.txt is 10351 lines): 2024-10-16 20:33:25,243 DEBUG ----- output ----- 2024-10-16 20:33:25,243 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-10-16 20:33:25,243 INFO 20:50:01.863 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-10-16 20:33:25,243 INFO 20:50:06.106 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling/benchmark/Testcase.scala:176:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-10-16 20:33:25,243 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-10-16 20:33:25,243 INFO ^ 2024-10-16 20:33:25,243 INFO 20:50:06.108 [WARN ] i.g.c.ZincCompiler$ - /lodestar/pyrock/shared/clients/gatling/benchmark/Testcase.scala:279:35: method - in trait MapOps is deprecated (since 2.13.0): Use - or remove on an immutable Map 2024-10-16 20:33:25,243 INFO crossSessionVariables.-("poliEvalTokenId"); 2024-10-16 20:33:25,243 INFO ^ 2024-10-16 20:33:25,243 INFO 20:50:07.877 [WARN ] i.g.c.ZincCompiler$ - three warnings found 2024-10-16 20:33:25,243 INFO Gatling 3.12.0 is available! (you're using 3.9.5) 2024-10-16 20:33:25,243 INFO System property think_time_multiplier not set. No think time emulation will be done in the simulation. 2024-10-16 20:33:25,243 INFO Simulation com.forgerock.gatling.Loadtest started... 2024-10-16 20:33:25,243 INFO 2024-10-16 20:33:25,243 INFO ================================================================================ 2024-10-16 20:33:25,244 INFO 2024-10-16 20:50:15 5s elapsed 2024-10-16 20:33:25,244 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,244 INFO > Global (OK=1511 KO=0 ) 2024-10-16 20:33:25,244 INFO > restLoginInitiate (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > Skip 2FA (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > User Authorize PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > User AccessToken PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > CreateManagedUser (OK=1506 KO=0 ) 2024-10-16 20:33:25,244 INFO 2024-10-16 20:33:25,244 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,244 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,244 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,244 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,244 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,244 INFO waiting: 0 / active: 35 / done: 0 2024-10-16 20:33:25,244 INFO ================================================================================ 2024-10-16 20:33:25,244 INFO 2024-10-16 20:33:25,244 INFO 2024-10-16 20:33:25,244 INFO ================================================================================ 2024-10-16 20:33:25,244 INFO 2024-10-16 20:50:20 10s elapsed 2024-10-16 20:33:25,244 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,244 INFO > Global (OK=3523 KO=0 ) 2024-10-16 20:33:25,244 INFO > restLoginInitiate (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > Skip 2FA (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > User Authorize PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > User AccessToken PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > CreateManagedUser (OK=3518 KO=0 ) 2024-10-16 20:33:25,244 INFO 2024-10-16 20:33:25,244 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,244 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,244 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,244 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,244 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,244 INFO waiting: 0 / active: 35 / done: 0 2024-10-16 20:33:25,244 INFO ================================================================================ 2024-10-16 20:33:25,244 INFO 2024-10-16 20:33:25,244 INFO 2024-10-16 20:33:25,244 INFO ================================================================================ 2024-10-16 20:33:25,244 INFO 2024-10-16 20:50:25 15s elapsed 2024-10-16 20:33:25,244 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,244 INFO > Global (OK=5516 KO=0 ) 2024-10-16 20:33:25,244 INFO > restLoginInitiate (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > Skip 2FA (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > User Authorize PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > User AccessToken PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,244 INFO > CreateManagedUser (OK=5511 KO=0 ) 2024-10-16 20:33:25,245 INFO 2024-10-16 20:33:25,245 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,245 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,245 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,245 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,245 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,245 INFO waiting: 0 / active: 35 / done: 0 2024-10-16 20:33:25,245 INFO ================================================================================ 2024-10-16 20:33:25,245 INFO 2024-10-16 20:33:25,245 INFO 2024-10-16 20:33:25,245 INFO ================================================================================ 2024-10-16 20:33:25,245 INFO 2024-10-16 20:50:30 20s elapsed 2024-10-16 20:33:25,245 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,245 INFO > Global (OK=7532 KO=0 ) 2024-10-16 20:33:25,245 INFO > restLoginInitiate (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > Skip 2FA (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > User Authorize PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > User AccessToken PKCE stage (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > CreateManagedUser (OK=7527 KO=0 ) 2024-10-16 20:33:25,245 INFO 2024-10-16 20:33:25,245 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,245 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,245 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,245 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,245 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,245 INFO waiting: 0 / active: 35 / done: 0 2024-10-16 20:33:25,245 INFO ================================================================================ 2024-10-16 20:33:25,245 INFO 2024-10-16 20:33:25,245 INFO 2024-10-16 20:33:25,245 INFO ================================================================================ 2024-10-16 20:33:25,245 INFO 2024-10-16 20:50:35 25s elapsed 2024-10-16 20:33:25,245 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,245 INFO > Global (OK=9515 KO=0 ) 2024-10-16 20:33:25,245 INFO > restLoginInitiate (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > restLoginUsernamePasswordCallback (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO > Skip 2FA (OK=1 KO=0 ) 2024-10-16 20:33:25,245 INFO [...] 2024-10-16 20:33:25,247 INFO 2024-10-16 20:33:25,247 INFO 2024-10-16 20:33:25,247 INFO ================================================================================ 2024-10-16 20:33:25,248 INFO 2024-10-16 21:32:55 2565s elapsed 2024-10-16 20:33:25,248 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,248 INFO > Global (OK=996994 KO=0 ) 2024-10-16 20:33:25,248 INFO > restLoginInitiate (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > restLoginUsernamePasswordCallback (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > Skip 2FA (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > User Authorize PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > User AccessToken PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > CreateManagedUser (OK=996779 KO=0 ) 2024-10-16 20:33:25,248 INFO 2024-10-16 20:33:25,248 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,248 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,248 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,248 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,248 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,248 INFO waiting: 0 / active: 35 / done: 0 2024-10-16 20:33:25,248 INFO ================================================================================ 2024-10-16 20:33:25,248 INFO 2024-10-16 20:33:25,248 INFO 2024-10-16 20:33:25,248 INFO ================================================================================ 2024-10-16 20:33:25,248 INFO 2024-10-16 21:33:00 2570s elapsed 2024-10-16 20:33:25,248 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,248 INFO > Global (OK=998949 KO=0 ) 2024-10-16 20:33:25,248 INFO > restLoginInitiate (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > restLoginUsernamePasswordCallback (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > Skip 2FA (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > User Authorize PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > User AccessToken PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > CreateManagedUser (OK=998734 KO=0 ) 2024-10-16 20:33:25,248 INFO 2024-10-16 20:33:25,248 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,248 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,248 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,248 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,248 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,248 INFO waiting: 0 / active: 35 / done: 0 2024-10-16 20:33:25,248 INFO ================================================================================ 2024-10-16 20:33:25,248 INFO 2024-10-16 20:33:25,248 INFO 2024-10-16 20:33:25,248 INFO ================================================================================ 2024-10-16 20:33:25,248 INFO 2024-10-16 21:33:05 2575s elapsed 2024-10-16 20:33:25,248 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,248 INFO > Global (OK=1000215 KO=0 ) 2024-10-16 20:33:25,248 INFO > restLoginInitiate (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > restLoginUsernamePasswordCallback (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > Skip 2FA (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > User Authorize PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > User AccessToken PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,248 INFO > CreateManagedUser (OK=1000000 KO=0 ) 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,249 INFO [--------------------------------------------------------------------------] 0% 2024-10-16 20:33:25,249 INFO waiting: 0 / active: 1 / done: 0 2024-10-16 20:33:25,249 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,249 INFO [##########################################################################]100% 2024-10-16 20:33:25,249 INFO waiting: 0 / active: 0 / done: 35 2024-10-16 20:33:25,249 INFO ================================================================================ 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO ================================================================================ 2024-10-16 20:33:25,249 INFO 2024-10-16 21:33:06 2575s elapsed 2024-10-16 20:33:25,249 INFO ---- Requests ------------------------------------------------------------------ 2024-10-16 20:33:25,249 INFO > Global (OK=1000215 KO=0 ) 2024-10-16 20:33:25,249 INFO > restLoginInitiate (OK=43 KO=0 ) 2024-10-16 20:33:25,249 INFO > restLoginUsernamePasswordCallback (OK=43 KO=0 ) 2024-10-16 20:33:25,249 INFO > Skip 2FA (OK=43 KO=0 ) 2024-10-16 20:33:25,249 INFO > User Authorize PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,249 INFO > User AccessToken PKCE stage (OK=43 KO=0 ) 2024-10-16 20:33:25,249 INFO > CreateManagedUser (OK=1000000 KO=0 ) 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO ---- getToken ------------------------------------------------------------------ 2024-10-16 20:33:25,249 INFO [##########################################################################]100% 2024-10-16 20:33:25,249 INFO waiting: 0 / active: 0 / done: 1 2024-10-16 20:33:25,249 INFO ---- idm.ManagedUsersPreload --------------------------------------------------- 2024-10-16 20:33:25,249 INFO [##########################################################################]100% 2024-10-16 20:33:25,249 INFO waiting: 0 / active: 0 / done: 35 2024-10-16 20:33:25,249 INFO ================================================================================ 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO Simulation com.forgerock.gatling.Loadtest completed in 2575 seconds 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO ---------------------- 2024-10-16 20:33:25,249 INFO [16/Oct/2024 21:33:06] Create tar file preload-with-gatling.tar.gz 2024-10-16 20:33:25,249 INFO ---------------------- 2024-10-16 20:33:25,249 INFO RESULTS_FOLDER=/results/gatling/ 2024-10-16 20:33:25,249 INFO RESULT_FOLDER=preload-with-gatling 2024-10-16 20:33:25,249 INFO SIMULATION_PATH=preload-with-gatling/loadtest-20241016195009981 2024-10-16 20:33:25,249 INFO PWD=/results/gatling/preload-with-gatling/loadtest-20241016195009981 2024-10-16 20:33:25,249 INFO [cmd] tar -czvf /results/gatling//preload-with-gatling.tar.gz --exclude=simulation.log * 2024-10-16 20:33:25,249 INFO /results/gatling 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO ---------------------- 2024-10-16 20:33:25,249 INFO [16/Oct/2024 21:33:06] Update /results/gatling//latest 2024-10-16 20:33:25,249 INFO ---------------------- 2024-10-16 20:33:25,249 INFO 2024-10-16 20:33:25,249 INFO ---------------------- 2024-10-16 20:33:25,249 INFO [16/Oct/2024 21:33:06] Update /results/gatling//latest.tar.gz 2024-10-16 20:33:25,249 INFO ---------------------- 2024-10-16 20:33:25,249 INFO ________________________________________________________________________________ [2024-10-16 20:33:25] preload-with-gatling show_stderr : Display stderr order ________________________________________________________________________________ 2024-10-16 20:33:25,250 DEBUG [print_head_tail]: Input file /mnt/disk1/xslou/workshop/lodestar-fork/results/pyrock/idc_benchmark_ch2023/05_Data_Init/04_preload-with-gatling/stderr.txt is small (0 lines), printing all lines: 2024-10-16 20:33:25,250 DEBUG ----- output ----- 2024-10-16 20:33:25,250 DEBUG 2024-10-16 20:33:25,250 INFO ________________________________________________________________________________ [2024-10-16 20:33:25] preload-with-gatling 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-10-16 20:33:25] preload-with-gatling print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-10-16 20:33:25] preload-with-gatling post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped