--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 bellow loop: False interval: None dependencies: ['prepare-workload'] wait_for: ['Pre-check Num Users'] preceding_task: None options: {'check-before-preload': True, 'simulation': 'idm.simple_managed_users.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/disks/data/xslou/lodestar-fork/pyrock ________________________________________________________________________________ [2024-07-17 03:50:13] preload-with-gatling pre : N/A ________________________________________________________________________________ 2024-07-17 03:50:13 - INFO: Timeout for this Task has changed to 2h (7200 seconds) 2024-07-17 03:50:13 - 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-07-17 03:50:13,915 INFO 2024-07-17 03:50:13,915 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-07-17 03:50:13,915 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 03:50:14,223 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 03:50:14,223 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:14,223 DEBUG --- stdout --- 2024-07-17 03:50:14,223 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 03:50:14,223 DEBUG --- stderr --- 2024-07-17 03:50:14,223 DEBUG ________________________________________________________________________________ [2024-07-17 03:50:14] preload-with-gatling print_command : Display the command to be forwarded to overseer as an order ________________________________________________________________________________ 2024-07-17 03:50:14,224 INFO 2024-07-17 03:50:14,224 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-07-17 03:50:14,224 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 03:50:14,541 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 03:50:14,542 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:14,542 DEBUG --- stdout --- 2024-07-17 03:50:14,542 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 03:50:14,542 DEBUG --- stderr --- 2024-07-17 03:50:14,542 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation idm.simple_managed_users.ManagedUsersPreload --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description "idm.simple_managed_users.ManagedUsersPreload@ema-perf-benchmark" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options "-Xmx4G" --no-reports ________________________________________________________________________________ [2024-07-17 03:50:14] preload-with-gatling create_order : Create order for overseer with the command to run ________________________________________________________________________________ 2024-07-17 03:50:14,543 INFO 2024-07-17 03:50:14,543 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-07-17 03:50:14,543 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 03:50:14,863 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 03:50:14,863 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:14,863 DEBUG --- stdout --- 2024-07-17 03:50:14,863 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 03:50:14,863 DEBUG --- stderr --- 2024-07-17 03:50:14,863 DEBUG Order file: /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/order.json { "name": "preload-with-gatling", "command": " /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation idm.simple_managed_users.ManagedUsersPreload --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description \"idm.simple_managed_users.ManagedUsersPreload@ema-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-ema-perf-benchmark.forgeblocks.com -Dam_oauth2_scope=openid^fr:idm:* -DamadminPassword=**** -DamadminUsername=xiaosong.lou@forgerock.com -Dcheck_before_preload=True -Dconcurrency=35 -Dcsvfile=/results/idm/preload.csv -Didc=true -Didm_host=openam-ema-perf-benchmark.forgeblocks.com -Dlogin_cookie=0e26551c0104b88 -Dnum_users=1000000 -Doauth2_client_id=idmAdminClient -Doauth2_redirect_uri=/platform/appAuthHelperRedirect.html -Doutput=/results/idm/user_feeder.csv -Drealm=alpha -Duser_password=Pa_ssw0rd" } } ________________________________________________________________________________ [2024-07-17 03:50:14] 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-07-17 03:50:14,865 INFO 2024-07-17 03:50:14,865 INFO [loop_until]: curl --insecure https://overseer-0.xlou.iam.xlou-cdm.engineeringpit.com/orders/status.txt | grep "WAITING" 2024-07-17 03:50:14,865 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-17 03:50:15,165 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-17 03:50:15,165 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:15,165 DEBUG --- stdout --- 2024-07-17 03:50:15,165 DEBUG WAITING 2024-07-17 03:50:15,165 DEBUG --- stderr --- 2024-07-17 03:50:15,165 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-07-17 03:50:15] preload-with-gatling start_order : Send the order to the overseer pod ________________________________________________________________________________ 2024-07-17 03:50:15,166 INFO 2024-07-17 03:50:15,166 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-07-17 03:50:15,166 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 03:50:15,484 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 03:50:15,485 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:15,485 DEBUG --- stdout --- 2024-07-17 03:50:15,485 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 03:50:15,485 DEBUG --- stderr --- 2024-07-17 03:50:15,485 DEBUG 2024-07-17 03:50:15,485 INFO 2024-07-17 03:50:15,485 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/order.json overseer-0-7796fc5d9b-ngsxg:/results/orders/order.json 2024-07-17 03:50:15,485 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-17 03:50:16,842 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:16,842 DEBUG --- stdout --- 2024-07-17 03:50:16,842 DEBUG 2024-07-17 03:50:16,842 DEBUG --- stderr --- 2024-07-17 03:50:16,843 DEBUG Order has been successfully forwarded to overseer 2024-07-17 03:50:16 - INFO: Check if Gatling simulation started successfully 2024-07-17 03:50:16,843 INFO 2024-07-17 03:50:16,843 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-07-17 03:50:16,843 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 03:50:17,152 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 03:50:17,153 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:17,153 DEBUG --- stdout --- 2024-07-17 03:50:17,153 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 03:50:17,153 DEBUG --- stderr --- 2024-07-17 03:50:17,153 DEBUG 2024-07-17 03:50:17,153 INFO 2024-07-17 03:50:17,153 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg -- head -n 25 /results/orders/preload-with-gatling/stdout.txt | grep "started..." 2024-07-17 03:50:17,153 INFO [loop_until]: (max_time=300, interval=5, expected_rc=[0] 2024-07-17 03:50:17,893 INFO [loop_until]: Function succeeded after 0s (rc=0) - failed to find expected output: started... - retry 2024-07-17 03:50:23,641 INFO [loop_until]: Function succeeded after 6s (rc=0) - failed to find expected output: started... - retry 2024-07-17 03:50:29,408 INFO [loop_until]: Function succeeded after 12s (rc=0) - expected pattern found 2024-07-17 03:50:29,408 INFO [loop_until]: OK (rc = 0) 2024-07-17 03:50:29,408 DEBUG --- stdout --- 2024-07-17 03:50:29,408 DEBUG GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 04:50:19.081 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 04:50:24.909 [WARN ] i.g.c.ZincCompiler$ - one warning found Gatling 3.11.5 is available! (you're using 3.9.5) think time multiplier is 0x Simulation idm.simple_managed_users.ManagedUsersPreload started... 2024-07-17 03:50:29,409 DEBUG --- stderr --- 2024-07-17 03:50:29,409 DEBUG ________________________________________________________________________________ [2024-07-17 03:50:29] 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 34s, retry Order is running since 1m 39s, retry Order is running since 1m 44s, retry Order is running since 1m 49s, retry Order is running since 1m 54s, retry Order is running since 2m, retry Order is running since 2m 05s, retry Order is running since 2m 10s, retry Order is running since 2m 15s, retry Order is running since 2m 21s, retry Order is running since 2m 26s, retry Order is running since 2m 31s, retry Order is running since 2m 36s, retry Order is running since 2m 41s, retry Order is running since 2m 47s, retry Order is running since 2m 52s, retry Order is running since 2m 57s, retry Order is running since 3m 02s, retry Order is running since 3m 08s, retry Order is running since 3m 13s, retry Order is running since 3m 18s, retry Order is running since 3m 23s, retry Order is running since 3m 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 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 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 41s, 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 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 34s, 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 6m, 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 47s, 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 08s, retry Order is running since 7m 13s, 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 34s, 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 55s, retry Order is running since 8m, 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 21s, 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 47s, 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 08s, 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 34s, 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 55s, retry Order is running since 10m, 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 21s, retry Order is running since 10m 26s, retry Order is running since 10m 31s, retry Order is running since 10m 36s, retry Order is running since 10m 42s, retry Order is running since 10m 47s, 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 08s, retry Order is running since 11m 13s, retry Order is running since 11m 18s, retry Order is running since 11m 23s, retry Order is running since 11m 29s, retry Order is running since 11m 34s, 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 55s, retry Order is running since 12m, retry Order is running since 12m 05s, retry Order is running since 12m 10s, retry Order is running since 12m 16s, retry Order is running since 12m 21s, retry Order is running since 12m 26s, retry Order is running since 12m 31s, retry Order is running since 12m 36s, retry Order is running since 12m 42s, retry Order is running since 12m 47s, 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 08s, retry Order is running since 13m 13s, retry Order is running since 13m 18s, retry Order is running since 13m 23s, retry Order is running since 13m 29s, retry Order is running since 13m 34s, 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 55s, retry Order is running since 14m, 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 21s, retry Order is running since 14m 26s, retry Order is running since 14m 31s, retry Order is running since 14m 36s, retry Order is running since 14m 42s, retry Order is running since 14m 47s, 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 08s, retry Order is running since 15m 13s, retry Order is running since 15m 18s, retry Order is running since 15m 23s, retry Order is running since 15m 29s, retry Order is running since 15m 34s, 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 55s, retry Order is running since 16m, retry Order is running since 16m 05s, retry Order is running since 16m 10s, retry Order is running since 16m 16s, retry Order is running since 16m 21s, retry Order is running since 16m 26s, retry Order is running since 16m 31s, retry Order is running since 16m 37s, retry Order is running since 16m 42s, retry Order is running since 16m 47s, retry Order is running since 16m 52s, retry Order is running since 16m 57s, retry Order is running since 17m 03s, retry Order is running since 17m 08s, retry Order is running since 17m 13s, retry Order is running since 17m 18s, retry Order is running since 17m 24s, retry Order is running since 17m 29s, retry Order is running since 17m 34s, retry Order is running since 17m 39s, retry Order is running since 17m 44s, retry Order is running since 17m 50s, retry Order is running since 17m 55s, retry Order is running since 18m, retry Order is running since 18m 05s, retry Order is running since 18m 10s, retry Order is running since 18m 16s, retry Order is running since 18m 21s, retry Order is running since 18m 26s, retry Order is running since 18m 31s, retry Order is running since 18m 37s, retry Order is running since 18m 42s, retry Order is running since 18m 47s, retry Order is running since 18m 52s, retry Order is running since 18m 58s, retry Order is running since 19m 03s, retry Order is running since 19m 08s, retry Order is running since 19m 13s, retry Order is running since 19m 18s, retry Order is running since 19m 24s, retry Order is running since 19m 29s, retry Order is running since 19m 34s, retry Order is running since 19m 39s, retry Order is running since 19m 44s, retry Order is running since 19m 50s, retry Order is running since 19m 55s, retry Order is running since 20m, retry Order is running since 20m 05s, retry Order is running since 20m 11s, retry Order is running since 20m 16s, retry Order is running since 20m 21s, retry Order is running since 20m 26s, retry Order is running since 20m 32s, retry Order is running since 20m 37s, retry Order is running since 20m 42s, retry Order is running since 20m 47s, retry Order is running since 20m 52s, retry Order is running since 20m 58s, retry Order is running since 21m 03s, retry Order is running since 21m 08s, retry Order is running since 21m 13s, retry Order is running since 21m 19s, retry Order is running since 21m 24s, retry Order is running since 21m 29s, retry Order is running since 21m 34s, retry Order is running since 21m 39s, retry Order is running since 21m 45s, retry Order is running since 21m 50s, retry Order is running since 21m 55s, retry Order is running since 22m, retry Order is running since 22m 05s, retry Order is running since 22m 11s, retry Order is running since 22m 16s, retry Order is running since 22m 21s, retry Order is running since 22m 26s, retry Order is running since 22m 32s, retry Order is running since 22m 37s, retry Order is running since 22m 42s, retry Order is running since 22m 47s, retry Order is running since 22m 52s, retry Order is running since 22m 58s, retry Order is running since 23m 03s, retry Order is running since 23m 08s, retry Order is running since 23m 13s, retry Order is running since 23m 19s, retry Order is running since 23m 24s, retry Order is running since 23m 29s, retry Order is running since 23m 34s, retry Order is running since 23m 39s, retry Order is running since 23m 45s, retry Order is running since 23m 50s, retry Order is running since 23m 55s, retry Order is running since 24m, retry Order is running since 24m 06s, retry Order is running since 24m 11s, retry Order is running since 24m 16s, retry Order is running since 24m 21s, retry Order is running since 24m 26s, retry Order is running since 24m 32s, retry Order is running since 24m 37s, retry Order is running since 24m 42s, retry Order is running since 24m 47s, retry Order is running since 24m 52s, retry Order is running since 24m 58s, retry Order is running since 25m 03s, retry Order is running since 25m 08s, retry Order is running since 25m 13s, retry Order is running since 25m 19s, retry Order is running since 25m 24s, retry Order is running since 25m 29s, retry Order is running since 25m 34s, retry Order is running since 25m 39s, retry Order is running since 25m 45s, retry Order is running since 25m 50s, retry Order is running since 25m 55s, retry Order is running since 26m, retry Order is running since 26m 06s, retry Order is running since 26m 11s, retry Order is running since 26m 16s, retry Order is running since 26m 21s, retry Order is running since 26m 26s, retry Order is running since 26m 32s, retry Order is running since 26m 37s, retry Order is running since 26m 42s, retry Order is running since 26m 47s, retry Order is running since 26m 53s, retry Order is running since 26m 58s, retry Order is running since 27m 03s, retry Order is running since 27m 08s, retry Order is running since 27m 13s, retry Order is running since 27m 19s, retry Order is running since 27m 24s, retry Order is running since 27m 29s, retry Order is running since 27m 34s, retry Order is running since 27m 40s, retry Order is running since 27m 45s, retry Order is running since 27m 50s, retry Order is running since 27m 55s, retry Order is running since 28m, retry Order is running since 28m 06s, retry Order is running since 28m 11s, retry Order is running since 28m 16s, retry Order is running since 28m 21s, retry Order is running since 28m 26s, retry Order is running since 28m 32s, retry Order is running since 28m 37s, retry Order is running since 28m 42s, retry Order is running since 28m 47s, retry Order is running since 28m 53s, retry Order is running since 28m 58s, retry Order is running since 29m 03s, retry Order is running since 29m 08s, retry Order is running since 29m 13s, retry Order is running since 29m 19s, retry Order is running since 29m 24s, retry Order is running since 29m 29s, retry Order is running since 29m 34s, retry Order is running since 29m 40s, retry Order is running since 29m 45s, retry Order is running since 29m 50s, retry Order is running since 29m 55s, retry Order is running since 30m, retry Order is running since 30m 06s, retry Order is running since 30m 11s, retry Order is running since 30m 16s, retry Order is running since 30m 21s, retry Order is running since 30m 26s, retry Order is running since 30m 32s, retry Order is running since 30m 37s, retry Order is running since 30m 42s, retry Order is running since 30m 47s, retry Order is running since 30m 53s, retry Order is running since 30m 58s, retry Order is running since 31m 03s, retry Order is running since 31m 08s, retry Order is running since 31m 13s, retry Order is running since 31m 19s, retry Order is running since 31m 24s, retry Order is running since 31m 29s, retry Order is running since 31m 34s, retry Order is running since 31m 40s, retry Order is running since 31m 45s, retry Order is running since 31m 50s, retry Order is running since 31m 55s, retry Order is running since 32m, retry Order is running since 32m 06s, retry Order is running since 32m 11s, retry Order is running since 32m 16s, retry Order is running since 32m 21s, retry Order is running since 32m 26s, retry Order is running since 32m 32s, retry Order is running since 32m 37s, retry Order is running since 32m 42s, retry Order is running since 32m 47s, retry Order is running since 32m 53s, retry Order is running since 32m 58s, retry Order is running since 33m 03s, retry Order is running since 33m 08s, retry Order is running since 33m 13s, retry Order is running since 33m 19s, retry Order is running since 33m 24s, retry Order is running since 33m 29s, retry Order is running since 33m 34s, retry Order is running since 33m 40s, retry Order is running since 33m 45s, retry Order is running since 33m 50s, retry Order is running since 33m 55s, retry Order is running since 34m, retry Order is running since 34m 06s, retry Order is running since 34m 11s, retry Order is running since 34m 16s, retry Order is running since 34m 21s, retry Order is running since 34m 27s, retry Order is running since 34m 32s, retry Order is running since 34m 37s, retry Order is running since 34m 42s, retry Order is running since 34m 47s, retry Order is running since 34m 53s, retry Order is running since 34m 58s, retry Order is running since 35m 03s, retry Order is running since 35m 08s, retry Order is running since 35m 13s, retry Order is running since 35m 19s, retry Order is running since 35m 24s, retry Order is running since 35m 29s, retry Order is running since 35m 34s, retry Order is running since 35m 40s, retry Order is running since 35m 45s, retry Order is running since 35m 50s, retry Order is running since 35m 55s, retry Order is running since 36m, retry Order is running since 36m 06s, retry Order is running since 36m 11s, retry Order is running since 36m 16s, retry Order is running since 36m 21s, retry Order is running since 36m 27s, retry Order is running since 36m 32s, retry Order is running since 36m 37s, retry Order is running since 36m 42s, retry Order is running since 36m 47s, retry Order is running since 36m 53s, retry Order is running since 36m 58s, retry Order is running since 37m 03s, retry Order is running since 37m 08s, retry Order is running since 37m 14s, retry Order is running since 37m 19s, retry Order is running since 37m 24s, retry Order is running since 37m 29s, retry Order is running since 37m 34s, retry Order is running since 37m 40s, retry Order is running since 37m 45s, retry Order is running since 37m 50s, retry Order is running since 37m 55s, retry Order is running since 38m 01s, retry Order is running since 38m 06s, retry Order is running since 38m 11s, retry Order is running since 38m 16s, retry Order is running since 38m 21s, retry Order is running since 38m 27s, retry Order is running since 38m 32s, retry Order is running since 38m 37s, retry Order is running since 38m 42s, retry Order is running since 38m 48s, retry Order is running since 38m 53s, retry Order is running since 38m 58s, retry Order is running since 39m 03s, retry Order is running since 39m 08s, retry Order is running since 39m 14s, retry Order is running since 39m 19s, retry Order is running since 39m 24s, retry Order is running since 39m 29s, retry Order is running since 39m 35s, retry Order is running since 39m 40s, retry Order is running since 39m 45s, retry Order is running since 39m 50s, retry Order is running since 39m 55s, retry Order is running since 40m 01s, retry Order is running since 40m 06s, retry Order is running since 40m 11s, retry Order is running since 40m 16s, retry Order is running since 40m 22s, retry Order is running since 40m 27s, retry Order is running since 40m 32s, retry Order is running since 40m 37s, retry Order is running since 40m 42s, retry Order is running since 40m 48s, retry Order is running since 40m 53s, retry Order is running since 40m 58s, retry Order is running since 41m 03s, retry Order is running since 41m 08s, retry Order is running since 41m 14s, retry Order is running since 41m 19s, retry Order is running since 41m 24s, retry PASS : Order stopped by itself after 41m 29s and before timeout (2h) ________________________________________________________________________________ [2024-07-17 04:31:59] preload-with-gatling stop_order : Stop the order on overseer pod ________________________________________________________________________________ 2024-07-17 04:31:59,319 INFO 2024-07-17 04:31:59,319 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-07-17 04:31:59,320 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:31:59,667 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:31:59,667 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:31:59,667 DEBUG --- stdout --- 2024-07-17 04:31:59,667 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:31:59,667 DEBUG --- stderr --- 2024-07-17 04:31:59,667 DEBUG 2024-07-17 04:31:59,668 INFO 2024-07-17 04:31:59,668 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg -- rm -f /results/orders/order.json 2024-07-17 04:31:59,668 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-17 04:32:00,480 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:00,480 DEBUG --- stdout --- 2024-07-17 04:32:00,480 DEBUG 2024-07-17 04:32:00,480 DEBUG --- stderr --- 2024-07-17 04:32:00,480 DEBUG Order ran during 41m 43s ________________________________________________________________________________ [2024-07-17 04:32:00] preload-with-gatling download_logs : Download individual order files ________________________________________________________________________________ 2024-07-17 04:32:00 - PASS: file already downloaded /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/order.json 2024-07-17 04:32:00,482 INFO 2024-07-17 04:32:00,482 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-07-17 04:32:00,482 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:00,790 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:00,791 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:00,791 DEBUG --- stdout --- 2024-07-17 04:32:00,791 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:00,791 DEBUG --- stderr --- 2024-07-17 04:32:00,791 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-17 04:32:00,792 INFO 2024-07-17 04:32:00,792 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-07-17 04:32:00,792 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:01,103 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:01,103 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:01,103 DEBUG --- stdout --- 2024-07-17 04:32:01,103 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:01,103 DEBUG --- stderr --- 2024-07-17 04:32:01,103 DEBUG 2024-07-17 04:32:01,104 INFO 2024-07-17 04:32:01,104 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --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-07-17 04:32:01,104 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-17 04:32:01,919 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-17 04:32:01,919 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:01,919 DEBUG --- stdout --- 2024-07-17 04:32:01,919 DEBUG /results/orders/preload-with-gatling/rc.txt file found 2024-07-17 04:32:01,919 DEBUG --- stderr --- 2024-07-17 04:32:01,919 DEBUG 2024-07-17 04:32:01,920 INFO 2024-07-17 04:32:01,920 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-07-17 04:32:01,920 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:02,222 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:02,223 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:02,223 DEBUG --- stdout --- 2024-07-17 04:32:02,223 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:02,223 DEBUG --- stderr --- 2024-07-17 04:32:02,223 DEBUG 2024-07-17 04:32:02,223 INFO 2024-07-17 04:32:02,223 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/preload-with-gatling/rc.txt 2024-07-17 04:32:03,043 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-17 04:32:03,043 DEBUG --- stdout --- 2024-07-17 04:32:03,043 DEBUG 4 /results/orders/preload-with-gatling/rc.txt 2024-07-17 04:32:03,043 DEBUG --- stderr --- 2024-07-17 04:32:03,043 DEBUG 2024-07-17 04:32:03,043 INFO - Download file (size 4k) 2024-07-17 04:32:03,044 INFO 2024-07-17 04:32:03,044 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-07-17 04:32:03,044 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:03,352 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:03,352 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:03,352 DEBUG --- stdout --- 2024-07-17 04:32:03,352 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:03,352 DEBUG --- stderr --- 2024-07-17 04:32:03,352 DEBUG source : /results/orders/preload-with-gatling/rc.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/rc.txt 2024-07-17 04:32:03,353 INFO 2024-07-17 04:32:03,353 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-07-17 04:32:03,353 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:03,694 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:03,694 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:03,694 DEBUG --- stdout --- 2024-07-17 04:32:03,694 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:03,694 DEBUG --- stderr --- 2024-07-17 04:32:03,694 DEBUG 2024-07-17 04:32:03,695 INFO 2024-07-17 04:32:03,695 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/preload-with-gatling/rc.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/rc.txt 2024-07-17 04:32:03,695 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-17 04:32:04,490 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:04,490 DEBUG --- stdout --- 2024-07-17 04:32:04,490 DEBUG tar: Removing leading `/' from member names 2024-07-17 04:32:04,490 DEBUG --- stderr --- 2024-07-17 04:32:04,490 DEBUG 2024-07-17 04:32:04,490 INFO 2024-07-17 04:32:04,490 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-07-17 04:32:04,490 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:04,798 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:04,799 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:04,799 DEBUG --- stdout --- 2024-07-17 04:32:04,799 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:04,799 DEBUG --- stderr --- 2024-07-17 04:32:04,799 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-17 04:32:04,799 INFO 2024-07-17 04:32:04,799 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-07-17 04:32:04,799 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:05,112 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:05,113 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:05,113 DEBUG --- stdout --- 2024-07-17 04:32:05,113 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:05,113 DEBUG --- stderr --- 2024-07-17 04:32:05,113 DEBUG 2024-07-17 04:32:05,114 INFO 2024-07-17 04:32:05,114 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --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-07-17 04:32:05,114 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-17 04:32:05,972 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-17 04:32:05,972 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:05,972 DEBUG --- stdout --- 2024-07-17 04:32:05,972 DEBUG /results/orders/preload-with-gatling/stderr.txt file found 2024-07-17 04:32:05,972 DEBUG --- stderr --- 2024-07-17 04:32:05,972 DEBUG 2024-07-17 04:32:05,973 INFO 2024-07-17 04:32:05,973 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-07-17 04:32:05,973 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:06,285 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:06,286 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:06,286 DEBUG --- stdout --- 2024-07-17 04:32:06,286 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:06,286 DEBUG --- stderr --- 2024-07-17 04:32:06,286 DEBUG 2024-07-17 04:32:06,286 INFO 2024-07-17 04:32:06,286 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/preload-with-gatling/stderr.txt 2024-07-17 04:32:07,093 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-17 04:32:07,093 DEBUG --- stdout --- 2024-07-17 04:32:07,093 DEBUG 0 /results/orders/preload-with-gatling/stderr.txt 2024-07-17 04:32:07,093 DEBUG --- stderr --- 2024-07-17 04:32:07,093 DEBUG 2024-07-17 04:32:07,093 INFO - Download file (size 0k) 2024-07-17 04:32:07,093 INFO 2024-07-17 04:32:07,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-07-17 04:32:07,094 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:07,404 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:07,404 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:07,404 DEBUG --- stdout --- 2024-07-17 04:32:07,404 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:07,404 DEBUG --- stderr --- 2024-07-17 04:32:07,404 DEBUG source : /results/orders/preload-with-gatling/stderr.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/stderr.txt 2024-07-17 04:32:07,405 INFO 2024-07-17 04:32:07,405 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-07-17 04:32:07,405 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:07,727 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:07,727 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:07,727 DEBUG --- stdout --- 2024-07-17 04:32:07,727 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:07,727 DEBUG --- stderr --- 2024-07-17 04:32:07,727 DEBUG 2024-07-17 04:32:07,728 INFO 2024-07-17 04:32:07,728 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/preload-with-gatling/stderr.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/stderr.txt 2024-07-17 04:32:07,728 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-17 04:32:08,522 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:08,522 DEBUG --- stdout --- 2024-07-17 04:32:08,522 DEBUG tar: Removing leading `/' from member names 2024-07-17 04:32:08,522 DEBUG --- stderr --- 2024-07-17 04:32:08,522 DEBUG 2024-07-17 04:32:08,523 INFO 2024-07-17 04:32:08,523 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-07-17 04:32:08,523 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:08,844 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:08,845 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:08,845 DEBUG --- stdout --- 2024-07-17 04:32:08,845 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:08,845 DEBUG --- stderr --- 2024-07-17 04:32:08,845 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-17 04:32:08,845 INFO 2024-07-17 04:32:08,845 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-07-17 04:32:08,845 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:09,169 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:09,170 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:09,170 DEBUG --- stdout --- 2024-07-17 04:32:09,170 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:09,170 DEBUG --- stderr --- 2024-07-17 04:32:09,170 DEBUG 2024-07-17 04:32:09,171 INFO 2024-07-17 04:32:09,171 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --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-07-17 04:32:09,171 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-17 04:32:09,981 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-17 04:32:09,983 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:09,983 DEBUG --- stdout --- 2024-07-17 04:32:09,984 DEBUG /results/orders/preload-with-gatling/stdout.txt file found 2024-07-17 04:32:09,984 DEBUG --- stderr --- 2024-07-17 04:32:09,984 DEBUG 2024-07-17 04:32:09,984 INFO 2024-07-17 04:32:09,984 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-07-17 04:32:09,984 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:10,308 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:10,309 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:10,309 DEBUG --- stdout --- 2024-07-17 04:32:10,309 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:10,309 DEBUG --- stderr --- 2024-07-17 04:32:10,309 DEBUG 2024-07-17 04:32:10,309 INFO 2024-07-17 04:32:10,309 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/preload-with-gatling/stdout.txt 2024-07-17 04:32:11,120 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-17 04:32:11,120 DEBUG --- stdout --- 2024-07-17 04:32:11,120 DEBUG 544 /results/orders/preload-with-gatling/stdout.txt 2024-07-17 04:32:11,120 DEBUG --- stderr --- 2024-07-17 04:32:11,120 DEBUG 2024-07-17 04:32:11,120 INFO - Download file (size 544k) 2024-07-17 04:32:11,121 INFO 2024-07-17 04:32:11,121 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-07-17 04:32:11,121 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:11,430 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:11,431 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:11,431 DEBUG --- stdout --- 2024-07-17 04:32:11,431 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:11,431 DEBUG --- stderr --- 2024-07-17 04:32:11,431 DEBUG source : /results/orders/preload-with-gatling/stdout.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/stdout.txt 2024-07-17 04:32:11,431 INFO 2024-07-17 04:32:11,431 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-07-17 04:32:11,431 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:11,761 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:11,762 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:11,762 DEBUG --- stdout --- 2024-07-17 04:32:11,762 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:11,762 DEBUG --- stderr --- 2024-07-17 04:32:11,762 DEBUG 2024-07-17 04:32:11,762 INFO 2024-07-17 04:32:11,763 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/preload-with-gatling/stdout.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/stdout.txt 2024-07-17 04:32:11,763 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-17 04:32:12,851 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:12,851 DEBUG --- stdout --- 2024-07-17 04:32:12,851 DEBUG tar: Removing leading `/' from member names 2024-07-17 04:32:12,851 DEBUG --- stderr --- 2024-07-17 04:32:12,851 DEBUG ________________________________________________________________________________ [2024-07-17 04:32:12] preload-with-gatling show_cmd : Display order command ________________________________________________________________________________ 2024-07-17 04:32:12,852 INFO 2024-07-17 04:32:12,852 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-07-17 04:32:12,852 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:13,156 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:13,156 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:13,156 DEBUG --- stdout --- 2024-07-17 04:32:13,156 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:13,156 DEBUG --- stderr --- 2024-07-17 04:32:13,156 DEBUG Checking pod: overseer-0-7796fc5d9b-ngsxg for file exists, if not wait. 2024-07-17 04:32:13,156 INFO 2024-07-17 04:32:13,156 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-07-17 04:32:13,156 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:13,467 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:13,467 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:13,467 DEBUG --- stdout --- 2024-07-17 04:32:13,468 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:13,468 DEBUG --- stderr --- 2024-07-17 04:32:13,468 DEBUG 2024-07-17 04:32:13,469 INFO 2024-07-17 04:32:13,469 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --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-07-17 04:32:13,469 INFO [loop_until]: (max_time=180, interval=5, expected_rc=[0] 2024-07-17 04:32:14,316 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected pattern found 2024-07-17 04:32:14,316 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:14,316 DEBUG --- stdout --- 2024-07-17 04:32:14,316 DEBUG /results/orders/preload-with-gatling/cmd.txt file found 2024-07-17 04:32:14,317 DEBUG --- stderr --- 2024-07-17 04:32:14,317 DEBUG 2024-07-17 04:32:14,317 INFO 2024-07-17 04:32:14,317 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-07-17 04:32:14,317 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:14,618 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:14,618 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:14,619 DEBUG --- stdout --- 2024-07-17 04:32:14,619 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:14,619 DEBUG --- stderr --- 2024-07-17 04:32:14,619 DEBUG 2024-07-17 04:32:14,619 INFO 2024-07-17 04:32:14,619 INFO [run_command]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm exec overseer-0-7796fc5d9b-ngsxg --stdin -- du -k /results/orders/preload-with-gatling/cmd.txt 2024-07-17 04:32:15,423 INFO [run_command]: OK (rc = 0 - expected to be in [0]) 2024-07-17 04:32:15,423 DEBUG --- stdout --- 2024-07-17 04:32:15,425 DEBUG 4 /results/orders/preload-with-gatling/cmd.txt 2024-07-17 04:32:15,425 DEBUG --- stderr --- 2024-07-17 04:32:15,425 DEBUG 2024-07-17 04:32:15,425 INFO - Download file (size 4k) 2024-07-17 04:32:15,425 INFO 2024-07-17 04:32:15,426 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-07-17 04:32:15,426 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:15,730 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:15,731 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:15,731 DEBUG --- stdout --- 2024-07-17 04:32:15,731 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:15,731 DEBUG --- stderr --- 2024-07-17 04:32:15,731 DEBUG source : /results/orders/preload-with-gatling/cmd.txt @ overseer-0-7796fc5d9b-ngsxg destination : /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/cmd.txt 2024-07-17 04:32:15,731 INFO 2024-07-17 04:32:15,731 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-07-17 04:32:15,731 INFO [loop_until]: (max_time=180, interval=10, expected_rc=[0] 2024-07-17 04:32:16,032 INFO [loop_until]: Function succeeded after 0s (rc=0) - expected number of elements found 2024-07-17 04:32:16,032 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:16,032 DEBUG --- stdout --- 2024-07-17 04:32:16,033 DEBUG overseer-0-7796fc5d9b-ngsxg 2024-07-17 04:32:16,033 DEBUG --- stderr --- 2024-07-17 04:32:16,033 DEBUG 2024-07-17 04:32:16,033 INFO 2024-07-17 04:32:16,033 INFO [loop_until]: kubectl --namespace=xlou --context=gke_engineeringpit_us-east1-d_xlou-cdm cp --retries=100 overseer-0-7796fc5d9b-ngsxg:/results/orders/preload-with-gatling/cmd.txt /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/cmd.txt 2024-07-17 04:32:16,033 INFO [loop_until]: (max_time=-1, interval=5, expected_rc=[0] 2024-07-17 04:32:16,762 INFO [loop_until]: OK (rc = 0) 2024-07-17 04:32:16,763 DEBUG --- stdout --- 2024-07-17 04:32:16,763 DEBUG tar: Removing leading `/' from member names 2024-07-17 04:32:16,763 DEBUG --- stderr --- 2024-07-17 04:32:16,763 DEBUG 2024-07-17 04:32:16,763 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/cmd.txt is small (1 lines), printing all lines: 2024-07-17 04:32:16,763 DEBUG ----- output ----- 2024-07-17 04:32:16,764 DEBUG /lodestar/clients/gatling-for-pyrock.sh --run-mode local --simulation idm.simple_managed_users.ManagedUsersPreload --simulations-folder /lodestar/pyrock/shared/clients/gatling/ --run-description "idm.simple_managed_users.ManagedUsersPreload@ema-perf-benchmark" --results-folder /results/gatling/preload-with-gatling --extra-run-jvm-options "-Xmx4G" --no-reports 2024-07-17 04:32:16,764 INFO ________________________________________________________________________________ [2024-07-17 04:32:16] preload-with-gatling show_rc : Display rc order ________________________________________________________________________________ 2024-07-17 04:32:16,764 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/rc.txt is small (1 lines), printing all lines: 2024-07-17 04:32:16,764 DEBUG ----- output ----- 2024-07-17 04:32:16,764 DEBUG CMD SUCCESS: command returns code 0 (expected [0, -9, 143, 137, -15]) 2024-07-17 04:32:16,764 INFO ________________________________________________________________________________ [2024-07-17 04:32:16] preload-with-gatling show_stdout : Display stdout order ________________________________________________________________________________ 2024-07-17 04:32:16,766 DEBUG [print_head_tail]: Print head (100 lines) and tail (100 lines) of input file (/mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/stdout.txt is 8491 lines): 2024-07-17 04:32:16,766 DEBUG ----- output ----- 2024-07-17 04:32:16,766 INFO GATLING_HOME is set to /home/jenkins/lodestar/clients/gatling 2024-07-17 04:32:16,766 INFO 04:50:19.081 [WARN ] i.g.c.ZincCompiler$ - -target is deprecated: Use -release instead to compile against the correct platform API. 2024-07-17 04:32:16,766 INFO 04:50:24.909 [WARN ] i.g.c.ZincCompiler$ - one warning found 2024-07-17 04:32:16,766 INFO Gatling 3.11.5 is available! (you're using 3.9.5) 2024-07-17 04:32:16,766 INFO think time multiplier is 0x 2024-07-17 04:32:16,766 INFO Simulation idm.simple_managed_users.ManagedUsersPreload started... 2024-07-17 04:32:16,766 INFO 2024-07-17 04:32:16,766 INFO ================================================================================ 2024-07-17 04:32:16,766 INFO 2024-07-17 04:50:32 5s elapsed 2024-07-17 04:32:16,766 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,766 INFO > Global (OK=1848 KO=0 ) 2024-07-17 04:32:16,766 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,766 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,766 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,766 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,766 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,766 INFO > Create Managed User (OK=1673 KO=0 ) 2024-07-17 04:32:16,767 INFO 2024-07-17 04:32:16,767 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,767 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,767 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,767 INFO ================================================================================ 2024-07-17 04:32:16,767 INFO 2024-07-17 04:32:16,767 INFO 2024-07-17 04:32:16,767 INFO ================================================================================ 2024-07-17 04:32:16,767 INFO 2024-07-17 04:50:37 10s elapsed 2024-07-17 04:32:16,767 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,767 INFO > Global (OK=3304 KO=0 ) 2024-07-17 04:32:16,767 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,767 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,767 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,767 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,767 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,767 INFO > Create Managed User (OK=3129 KO=0 ) 2024-07-17 04:32:16,767 INFO 2024-07-17 04:32:16,767 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,767 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,767 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,767 INFO ================================================================================ 2024-07-17 04:32:16,767 INFO 2024-07-17 04:32:16,767 INFO 2024-07-17 04:32:16,767 INFO ================================================================================ 2024-07-17 04:32:16,767 INFO 2024-07-17 04:50:42 15s elapsed 2024-07-17 04:32:16,767 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,768 INFO > Global (OK=4704 KO=0 ) 2024-07-17 04:32:16,768 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > Create Managed User (OK=4529 KO=0 ) 2024-07-17 04:32:16,768 INFO 2024-07-17 04:32:16,768 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,768 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,768 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,768 INFO ================================================================================ 2024-07-17 04:32:16,768 INFO 2024-07-17 04:32:16,768 INFO 2024-07-17 04:32:16,768 INFO ================================================================================ 2024-07-17 04:32:16,768 INFO 2024-07-17 04:50:47 20s elapsed 2024-07-17 04:32:16,768 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,768 INFO > Global (OK=6781 KO=0 ) 2024-07-17 04:32:16,768 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,768 INFO > Create Managed User (OK=6606 KO=0 ) 2024-07-17 04:32:16,768 INFO 2024-07-17 04:32:16,768 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,768 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,768 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,768 INFO ================================================================================ 2024-07-17 04:32:16,769 INFO 2024-07-17 04:32:16,769 INFO 2024-07-17 04:32:16,769 INFO ================================================================================ 2024-07-17 04:32:16,769 INFO 2024-07-17 04:50:52 25s elapsed 2024-07-17 04:32:16,769 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,769 INFO > Global (OK=8864 KO=0 ) 2024-07-17 04:32:16,769 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > Create Managed User (OK=8689 KO=0 ) 2024-07-17 04:32:16,769 INFO 2024-07-17 04:32:16,769 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,769 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,769 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,769 INFO ================================================================================ 2024-07-17 04:32:16,769 INFO 2024-07-17 04:32:16,769 INFO 2024-07-17 04:32:16,769 INFO ================================================================================ 2024-07-17 04:32:16,769 INFO 2024-07-17 04:50:57 30s elapsed 2024-07-17 04:32:16,769 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,769 INFO > Global (OK=10928 KO=0 ) 2024-07-17 04:32:16,769 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,769 INFO [...] 2024-07-17 04:32:16,772 INFO > Global (OK=993153 KO=0 ) 2024-07-17 04:32:16,773 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > Create Managed User (OK=992978 KO=0 ) 2024-07-17 04:32:16,773 INFO 2024-07-17 04:32:16,773 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,773 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,773 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,773 INFO ================================================================================ 2024-07-17 04:32:16,773 INFO 2024-07-17 04:32:16,773 INFO 2024-07-17 04:32:16,773 INFO ================================================================================ 2024-07-17 04:32:16,773 INFO 2024-07-17 05:31:42 2475s elapsed 2024-07-17 04:32:16,773 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,773 INFO > Global (OK=995188 KO=0 ) 2024-07-17 04:32:16,773 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,773 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,774 INFO > Create Managed User (OK=995013 KO=0 ) 2024-07-17 04:32:16,774 INFO 2024-07-17 04:32:16,774 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,774 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,774 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,775 INFO ================================================================================ 2024-07-17 04:32:16,775 INFO 2024-07-17 04:32:16,775 INFO 2024-07-17 04:32:16,775 INFO ================================================================================ 2024-07-17 04:32:16,775 INFO 2024-07-17 05:31:47 2480s elapsed 2024-07-17 04:32:16,775 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,775 INFO > Global (OK=997228 KO=0 ) 2024-07-17 04:32:16,776 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,776 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,776 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,776 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,776 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,776 INFO > Create Managed User (OK=997053 KO=0 ) 2024-07-17 04:32:16,776 INFO 2024-07-17 04:32:16,777 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,777 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,777 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,777 INFO ================================================================================ 2024-07-17 04:32:16,777 INFO 2024-07-17 04:32:16,777 INFO 2024-07-17 04:32:16,777 INFO ================================================================================ 2024-07-17 04:32:16,777 INFO 2024-07-17 05:31:52 2485s elapsed 2024-07-17 04:32:16,777 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,777 INFO > Global (OK=999083 KO=0 ) 2024-07-17 04:32:16,777 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,777 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,777 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,777 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,777 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,778 INFO > Create Managed User (OK=998908 KO=0 ) 2024-07-17 04:32:16,778 INFO 2024-07-17 04:32:16,778 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,778 INFO [--------------------------------------------------------------------------] 0% 2024-07-17 04:32:16,778 INFO waiting: 0 / active: 35 / done: 0 2024-07-17 04:32:16,778 INFO ================================================================================ 2024-07-17 04:32:16,778 INFO 2024-07-17 04:32:16,778 INFO 2024-07-17 04:32:16,778 INFO ================================================================================ 2024-07-17 04:32:16,778 INFO 2024-07-17 05:31:54 2487s elapsed 2024-07-17 04:32:16,778 INFO ---- Requests ------------------------------------------------------------------ 2024-07-17 04:32:16,779 INFO > Global (OK=1000175 KO=0 ) 2024-07-17 04:32:16,779 INFO > restLoginInitiate (OK=35 KO=0 ) 2024-07-17 04:32:16,779 INFO > restLoginUsernamePasswordCallback (OK=35 KO=0 ) 2024-07-17 04:32:16,779 INFO > Skip 2FA (OK=35 KO=0 ) 2024-07-17 04:32:16,779 INFO > User Authorize PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,779 INFO > User AccessToken PKCE stage (OK=35 KO=0 ) 2024-07-17 04:32:16,779 INFO > Create Managed User (OK=1000000 KO=0 ) 2024-07-17 04:32:16,779 INFO 2024-07-17 04:32:16,779 INFO ---- Managed User Create Preload ----------------------------------------------- 2024-07-17 04:32:16,779 INFO [##########################################################################]100% 2024-07-17 04:32:16,779 INFO waiting: 0 / active: 0 / done: 35 2024-07-17 04:32:16,779 INFO ================================================================================ 2024-07-17 04:32:16,779 INFO 2024-07-17 04:32:16,779 INFO Simulation idm.simple_managed_users.ManagedUsersPreload completed in 2487 seconds 2024-07-17 04:32:16,779 INFO 2024-07-17 04:32:16,780 INFO ---------------------- 2024-07-17 04:32:16,780 INFO [17/Jul/2024 05:31:55] Create tar file preload-with-gatling.tar.gz 2024-07-17 04:32:16,780 INFO ---------------------- 2024-07-17 04:32:16,780 INFO RESULTS_FOLDER=/results/gatling/ 2024-07-17 04:32:16,780 INFO RESULT_FOLDER=preload-with-gatling 2024-07-17 04:32:16,780 INFO SIMULATION_PATH=preload-with-gatling/manageduserspreload-20240717035026405 2024-07-17 04:32:16,780 INFO PWD=/results/gatling/preload-with-gatling/manageduserspreload-20240717035026405 2024-07-17 04:32:16,780 INFO [cmd] tar -czvf /results/gatling//preload-with-gatling.tar.gz --exclude=simulation.log * 2024-07-17 04:32:16,780 INFO /results/gatling 2024-07-17 04:32:16,780 INFO 2024-07-17 04:32:16,780 INFO ---------------------- 2024-07-17 04:32:16,780 INFO [17/Jul/2024 05:31:55] Update /results/gatling//latest 2024-07-17 04:32:16,780 INFO ---------------------- 2024-07-17 04:32:16,780 INFO 2024-07-17 04:32:16,780 INFO ---------------------- 2024-07-17 04:32:16,780 INFO [17/Jul/2024 05:31:55] Update /results/gatling//latest.tar.gz 2024-07-17 04:32:16,781 INFO ---------------------- 2024-07-17 04:32:16,781 INFO ________________________________________________________________________________ [2024-07-17 04:32:16] preload-with-gatling show_stderr : Display stderr order ________________________________________________________________________________ 2024-07-17 04:32:16,782 DEBUG [print_head_tail]: Input file /mnt/disks/data/xslou/lodestar-fork/results/pyrock/benchmark/05_Data_Init/04_preload-with-gatling/stderr.txt is small (0 lines), printing all lines: 2024-07-17 04:32:16,782 DEBUG ----- output ----- 2024-07-17 04:32:16,782 DEBUG 2024-07-17 04:32:16,782 INFO ________________________________________________________________________________ [2024-07-17 04:32:16] 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-07-17 04:32:16] preload-with-gatling print_overseer_logs : Print overseer logs from the K8s pod ________________________________________________________________________________ Task result is INIT no need to display logs. ________________________________________________________________________________ [2024-07-17 04:32:16] preload-with-gatling post : Post ________________________________________________________________________________ Setting result to PASS Task has been successfully stopped