Skip to content
Snippets Groups Projects

rspec-ee background_migration pg14 1/3

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
122:08:51Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
222:08:51 on blue-3.shared-gitlab-org.runners-manager.gitlab.com/default sUrYYgEG, system ID: s_35f98e2af465
322:08:51 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:08:51Resolving secrets
5 22:08:51 Preparing the "docker+machine" executor 01:46
622:08:51Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
722:08:54Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
822:08:54Authenticating with credentials from job payload (GitLab Registry)
922:08:54Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:08:54Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...
1122:08:54WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:08:54WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:08:54Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1422:08:54Authenticating with credentials from job payload (GitLab Registry)
1522:08:54Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:08:55Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...
1722:08:55Starting service redis:6.2-alpine...
1822:08:55Pulling docker image redis:6.2-alpine ...
1922:08:56Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2022:08:56WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 is already created. Ignoring.
2122:08:56WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 is already created. Ignoring.
2222:08:56Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8...
2322:08:56Authenticating with credentials from job payload (GitLab Registry)
2422:08:56Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 ...
2522:08:58Using docker image sha256:df819140e3df737cdc7b8d6fb73ce804b05058c790e025b5f52840956feb1734 for registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:7bdf349fc3dd9da64bc6581d44c0e906bffd38977ddbd2668ba34adb5dedfe38 ...
2622:09:00Starting service elasticsearch:7.17.6...
2722:09:00Pulling docker image elasticsearch:7.17.6 ...
2822:09:10Using docker image sha256:5fad10241ffd65d817ed0ddfaf6e87eee1f7dc2a7db33db1047835560ea71fda for elasticsearch:7.17.6 with digest elasticsearch@sha256:6c128de5d01c0c130a806022d6bd99b3e4c27a9af5bfc33b6b81861ae117d028 ...
2922:09:14Starting service registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.18.0...
3022:09:14Authenticating with credentials from job payload (GitLab Registry)
3122:09:14Pulling docker image registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.18.0 ...
3222:09:49Using docker image sha256:eb97d149d5b7fa6f8d1c3551036054839115ce4ae8b1896d5b4cc1563b098719 for registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.18.0 with digest registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway@sha256:d8fb36abcbce56db6ebaaff6504a26ff7345d5f463f273b9f993e0cf65c85818 ...
3322:09:52Starting service ghcr.io/berriai/litellm:main-latest...
3422:09:52Pulling docker image ghcr.io/berriai/litellm:main-latest ...
3522:10:25Using docker image sha256:006af10d9c70ad67274d5937788e42ce2cddb97f5496178c013ed1eac59ab6ae for ghcr.io/berriai/litellm:main-latest with digest ghcr.io/berriai/litellm@sha256:e7a60e9428c4946ae4b6036206bcf98b2cdb8b74e54f75793b51592d1fc005cd ...
3622:10:31Waiting for services to be up and running (timeout 30 seconds)...
3722:10:37Authenticating with credentials from job payload (GitLab Registry)
3822:10:37Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
3922:10:37Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
40 22:10:37 Preparing environment 00:01
4122:10:38Running on runner-suryygeg-project-278964-concurrent-0 via runner-suryygeg-shared-gitlab-org-1731529931-cda0482a...
42 22:10:38 Getting source from Git repository 00:01
4322:10:39Skipping Git repository setup
4422:10:39Skipping Git checkout
4522:10:39Skipping Git submodules setup
46 22:10:39 Restoring cache 00:21
4722:10:39Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
4822:10:39Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 ETag="ba67364f761343c8f313528473af941a"
4922:10:55Successfully extracted cache
50 22:11:00 Downloading artifacts 00:52
5122:11:01Downloading artifacts for clone-gitlab-repo (8353286583)...
5222:11:06Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286583 responseStatus=200 OK token=glcbt-66
5322:11:06WARNING: Part of .git directory is on the list of files to extract
5422:11:06WARNING: This may introduce unexpected problems
5522:11:22Downloading artifacts for compile-test-assets (8353286630)...
5622:11:26Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286630 responseStatus=200 OK token=glcbt-66
5722:11:34Downloading artifacts for detect-tests (8353286649)...
5822:11:34Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286649 responseStatus=200 OK token=glcbt-66
5922:11:34Downloading artifacts for retrieve-tests-metadata (8353286664)...
6022:11:35Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286664 responseStatus=200 OK token=glcbt-66
6122:11:35Downloading artifacts for setup-test-env (8353286635)...
6222:11:40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286635 responseStatus=200 OK token=glcbt-66
63 22:11:52 Executing "step_script" stage of the job script
6422:11:52Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
6522:11:53$ echo $FOSS_ONLY
6622:11:53$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
6722:11:53$ export GOPATH=$CI_PROJECT_DIR/.go
6822:11:53$ mkdir -p $GOPATH
6922:11:53$ source scripts/utils.sh
7022:11:53$ log_disk_usage "true"
7122:11:53*******************************************************
7222:11:53This runner currently has 26G free disk space.
7322:11:53*******************************************************
74 22:11:53 Disk usage detail 00:08
7522:11:53df -h
7622:11:53Filesystem Size Used Avail Use% Mounted on
7722:11:53overlay 46G 20G 26G 44% /
7822:11:53tmpfs 64M 0 64M 0% /dev
7922:11:53shm 256M 0 256M 0% /dev/shm
8022:11:53/dev/sda1 46G 20G 26G 44% /cache
8122:11:53tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
8222:11:53tmpfs 3.9G 0 3.9G 0% /proc/acpi
8322:11:53tmpfs 3.9G 0 3.9G 0% /proc/scsi
8422:11:53tmpfs 3.9G 0 3.9G 0% /sys/firmware
8522:11:53du -h -d 1
8622:11:537.4M ./workhorse
8722:11:53148K ./bin
8822:11:531.2M ./.gitlab
8922:11:53167M ./.git
9022:11:534.0K ./builds
9122:11:5328M ./lib
9222:11:538.0K ./.vscode
9322:11:53560K ./storybook
9422:11:5324K ./log
9522:11:531.8M ./data
9622:11:5311M ./qa
9722:11:533.2M ./.rubocop_todo
9822:11:53856M ./vendor
9922:11:531023M ./public
10022:11:5462M ./doc
10122:11:54484K ./danger
10222:11:5432K ./rspec
10322:11:54556K ./tooling
10422:11:54145M ./ee
10522:11:541.3M ./knapsack
10622:11:54300K ./patches
10722:11:544.3M ./changelogs
10822:11:5420K ./haml_lint
10922:11:5428K ./metrics_server
11022:11:54108K ./keeps
11122:11:5436K ./shared
11222:11:5444K ./generator_templates
11322:11:541.3M ./scripts
11422:11:54763M ./tmp
11522:11:54127M ./spec
11622:11:5420K ./file_hooks
11722:11:5432K ./crystalball
11822:11:5416K ./sidekiq_cluster
11922:11:5417M ./gems
12022:11:541.2M ./fixtures
12122:11:54964K ./rubocop
12222:11:544.0K ./.go
12322:11:5433M ./db
12422:11:5412K ./.github
12522:11:54129M ./locale
12622:11:5420M ./config
12722:11:5482M ./app
12822:11:5420K ./.lefthook
12922:11:543.5G .
13022:11:54$ source scripts/prepare_build.sh
131 22:11:54 Installing gems 00:25
16222:12:19Using decomposed database config (config/database.yml.decomposed-postgresql)
16322:12:19Geo DB will be set up.
16422:12:19Embedding DB will be set up.
165 22:12:19 Setting up DBs 02:08
18822:14:27$ source ./scripts/rspec_helpers.sh
18922:14:27$ run_timed_command "gem install knapsack --no-document"
19022:14:26$ gem install knapsack --no-document
19122:14:28Successfully installed knapsack-4.0.0
19222:14:281 gem installed
19322:14:27==> 'gem install knapsack --no-document' succeeded in 1 seconds.
19422:14:28$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
195 22:14:28 Spawning Gitaly 00:06
19922:14:34$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
20022:14:34$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
20122:14:34$ tooling/bin/create_job_metrics_file || true
20222:14:37[job-metrics] Creating the job metrics file for the CI/CD job.
20322:14:37$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
204 22:14:37 RSpec 27:51
20522:14:37[22:14:37] Starting rspec_parallelized_job
20622:14:28RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
20722:14:38KNAPSACK_GENERATE_REPORT: true
20822:14:38FLAKY_RSPEC_GENERATE_REPORT: true
20922:14:38KNAPSACK_TEST_FILE_PATTERN: {ee/}spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
21022:14:38KNAPSACK_LOG_LEVEL: debug
21122:14:38KNAPSACK_REPORT_PATH: knapsack/rspec-ee_background_migration_pg14_1_3_278964_report.json
21222:14:38FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
21322:14:38FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec-ee_background_migration_pg14_1_3_278964_report.json
21422:14:38NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec-ee_background_migration_pg14_1_3_278964_report.json
21522:14:38RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8353288362.txt
21622:14:38CRYSTALBALL:
21722:14:38RSPEC_TESTS_MAPPING_ENABLED:
21822:14:38RSPEC_TESTS_FILTER_FILE:
21922:14:38Shell set options (set -o) enabled:
22022:14:38braceexpand on
22122:14:38hashall on
22222:14:38interactive-comments on
22322:14:38pipefail on
22422:14:46Knapsack report generator started!
22522:14:38/builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
22622:16:12DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <top (required)> at /builds/gitlab-org/gitlab/config/environment.rb:7)
22722:16:26warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
22822:16:26Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
22922:16:42/builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
23022:16:57DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <main> at /builds/gitlab-org/gitlab/config/environment.rb:7)
23122:17:30Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
23222:17:30# [RSpecRunTime] Starting RSpec timer...
23322:17:31[TEST PROF INFO] EventProf enabled (sql.active_record)
23422:17:31unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
23522:17:31unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
23622:17:43Test environment set up in 5.100924427 seconds
23722:17:43# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_missing_vulnerability_dismissal_details_spec.rb. Expected to take 3 minutes 55.12 seconds.
23822:17:43Gitlab::BackgroundMigration::BackfillMissingVulnerabilityDismissalDetails
23922:19:46 when the vulnerability is correctly dismissed
24022:20:08 does not modify correct vulnerabilities
24122:20:08 when the vulnerability is not correctly dismissed
24222:20:28 applies the applicable state transition information to malformed state transitions
24322:20:49 logs a successful outcome
24422:20:49 and does not have a corresponding vst
24522:21:11 does not modify the vulnerability
24622:21:32 logs an appropriate warning
24722:21:33# [RSpecRunTime] RSpec elapsed time: 4 minutes 1.88 seconds. Current RSS: ~1204M. Threads: 1. load average: 1.21 1.29 1.27 1/406 413
24822:21:33.
24922:21:34I, [2024-11-13T22:21:34.965301 #347] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
25022:21:59I, [2024-11-13T22:21:59.235966 #347] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
25122:22:01INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
25222:22:01INFO: analyzing "public.ci_pipeline_variables"
25322:22:01INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
25422:22:01I, [2024-11-13T22:22:01.068799 #347] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
25522:22:01I, [2024-11-13T22:22:01.070083 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25622:22:01I, [2024-11-13T22:22:01.070982 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25722:22:03INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
25822:22:03INFO: analyzing "public.ci_pipeline_variables"
25922:22:03INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26022:22:08INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
26122:22:08INFO: analyzing "public.ci_job_artifacts"
26222:22:08INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26322:22:08I, [2024-11-13T22:22:08.863072 #347] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
26422:22:08I, [2024-11-13T22:22:08.864430 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26522:22:08I, [2024-11-13T22:22:08.865450 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26622:22:12INFO: analyzing "public.p_ci_stages" inheritance tree
26722:22:12INFO: analyzing "public.ci_stages"
26822:22:12INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26922:22:12I, [2024-11-13T22:22:12.820040 #347] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
27022:22:12I, [2024-11-13T22:22:12.821282 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27122:22:12I, [2024-11-13T22:22:12.822168 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27222:22:14I, [2024-11-13T22:22:14.449877 #347] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
27322:22:22I, [2024-11-13T22:22:22.339866 #347] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
27422:22:39I, [2024-11-13T22:22:39.116553 #347] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
27522:22:39I, [2024-11-13T22:22:39.117935 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27622:22:39I, [2024-11-13T22:22:39.118777 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27722:22:54I, [2024-11-13T22:22:54.397945 #347] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
27822:23:02I, [2024-11-13T22:23:02.937301 #347] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
27922:23:12I, [2024-11-13T22:23:12.603796 #347] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
28022:23:15I, [2024-11-13T22:23:15.732175 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
28122:23:20INFO: analyzing "public.p_ci_pipelines" inheritance tree
28222:23:20INFO: analyzing "public.ci_pipelines"
28322:23:20INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
28422:23:20I, [2024-11-13T22:23:20.258835 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
28522:23:20I, [2024-11-13T22:23:20.260257 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28622:23:20I, [2024-11-13T22:23:20.261193 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28722:23:27I, [2024-11-13T22:23:27.518354 #347] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
28822:23:40I, [2024-11-13T22:23:40.347987 #347] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
28922:23:40I, [2024-11-13T22:23:40.349258 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29022:23:40I, [2024-11-13T22:23:40.350162 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29122:23:40I, [2024-11-13T22:23:40.353828 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
29222:23:40I, [2024-11-13T22:23:40.354964 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29322:23:40I, [2024-11-13T22:23:40.355804 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29422:23:40I, [2024-11-13T22:23:40.359553 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
29522:23:40I, [2024-11-13T22:23:40.360760 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29622:23:40I, [2024-11-13T22:23:40.361695 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29722:23:40I, [2024-11-13T22:23:40.365720 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
29822:23:40I, [2024-11-13T22:23:40.367139 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29922:23:40I, [2024-11-13T22:23:40.368021 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30022:23:41I, [2024-11-13T22:23:41.564760 #347] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
30122:23:41I, [2024-11-13T22:23:41.566179 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30222:23:41I, [2024-11-13T22:23:41.567193 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30322:23:41I, [2024-11-13T22:23:41.571297 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
30422:23:41I, [2024-11-13T22:23:41.572533 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30522:23:41I, [2024-11-13T22:23:41.573440 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30622:23:41I, [2024-11-13T22:23:41.577439 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
30722:23:41I, [2024-11-13T22:23:41.578799 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30822:23:41I, [2024-11-13T22:23:41.579724 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30922:23:41I, [2024-11-13T22:23:41.583980 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
31022:23:41I, [2024-11-13T22:23:41.585344 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
31122:23:41I, [2024-11-13T22:23:41.586337 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
31222:24:08# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_missing_vulnerability_dismissal_details_spec.rb. It took 6 minutes 24.36 seconds. Expected to take 3 minutes 55.12 seconds.
31322:24:08# [RSpecRunTime] RSpec elapsed time: 6 minutes 37.32 seconds. Current RSS: ~1213M. Threads: 1. load average: 0.97 1.14 1.21 1/404 414
31422:24:08.
31522:24:08# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb. Expected to take 2 minutes 9.45 seconds.
31622:24:08Gitlab::BackgroundMigration::PopulateDenormalizedColumnsForSbomOccurrences
31722:26:29 sets the denormalized columns
31822:26:30I, [2024-11-13T22:26:30.799234 #347] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
31922:26:55I, [2024-11-13T22:26:55.477809 #347] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
32022:26:57INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
32122:26:57INFO: analyzing "public.ci_pipeline_variables"
32222:26:57INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
32322:26:57I, [2024-11-13T22:26:57.377212 #347] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
32422:26:57I, [2024-11-13T22:26:57.378701 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32522:26:57I, [2024-11-13T22:26:57.379622 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32622:26:59INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
32722:26:59INFO: analyzing "public.ci_pipeline_variables"
32822:26:59INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
32922:27:05INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
33022:27:05INFO: analyzing "public.ci_job_artifacts"
33122:27:05INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
33222:27:05I, [2024-11-13T22:27:05.258414 #347] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
33322:27:05I, [2024-11-13T22:27:05.259754 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33422:27:05I, [2024-11-13T22:27:05.260704 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33522:27:09INFO: analyzing "public.p_ci_stages" inheritance tree
33622:27:09INFO: analyzing "public.ci_stages"
33722:27:09INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
33822:27:09I, [2024-11-13T22:27:09.358532 #347] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
33922:27:09I, [2024-11-13T22:27:09.359992 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34022:27:09I, [2024-11-13T22:27:09.360909 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34122:27:11I, [2024-11-13T22:27:11.039656 #347] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
34222:27:19I, [2024-11-13T22:27:19.042221 #347] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
34322:27:36I, [2024-11-13T22:27:36.410823 #347] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
34422:27:36I, [2024-11-13T22:27:36.412381 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34522:27:36I, [2024-11-13T22:27:36.413406 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34622:27:51I, [2024-11-13T22:27:51.847891 #347] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
34722:28:00I, [2024-11-13T22:28:00.752253 #347] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
34822:28:10I, [2024-11-13T22:28:10.712234 #347] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
34922:28:13I, [2024-11-13T22:28:13.849532 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
35022:28:18INFO: analyzing "public.p_ci_pipelines" inheritance tree
35122:28:18INFO: analyzing "public.ci_pipelines"
35222:28:18INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
35322:28:18I, [2024-11-13T22:28:18.415748 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
35422:28:18I, [2024-11-13T22:28:18.417418 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
35522:28:18I, [2024-11-13T22:28:18.418358 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
35622:28:25I, [2024-11-13T22:28:25.773717 #347] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
35722:28:38I, [2024-11-13T22:28:38.786701 #347] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
35822:28:38I, [2024-11-13T22:28:38.788066 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
35922:28:38I, [2024-11-13T22:28:38.789084 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36022:28:38I, [2024-11-13T22:28:38.793445 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
36122:28:38I, [2024-11-13T22:28:38.794782 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36222:28:38I, [2024-11-13T22:28:38.795684 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36322:28:38I, [2024-11-13T22:28:38.799678 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
36422:28:38I, [2024-11-13T22:28:38.800899 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36522:28:38I, [2024-11-13T22:28:38.801787 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36622:28:38I, [2024-11-13T22:28:38.805605 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
36722:28:38I, [2024-11-13T22:28:38.806810 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36822:28:38I, [2024-11-13T22:28:38.807719 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36922:28:40I, [2024-11-13T22:28:40.069435 #347] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
37022:28:40I, [2024-11-13T22:28:40.070985 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37122:28:40I, [2024-11-13T22:28:40.071951 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37222:28:40I, [2024-11-13T22:28:40.076061 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
37322:28:40I, [2024-11-13T22:28:40.077424 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37422:28:40I, [2024-11-13T22:28:40.078357 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37522:28:40I, [2024-11-13T22:28:40.082294 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
37622:28:40I, [2024-11-13T22:28:40.083559 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37722:28:40I, [2024-11-13T22:28:40.084533 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37822:28:40I, [2024-11-13T22:28:40.088477 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
37922:28:40I, [2024-11-13T22:28:40.089720 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
38022:28:40I, [2024-11-13T22:28:40.090571 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
38122:29:06# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb. It took 4 minutes 58.47 seconds. Expected to take 2 minutes 9.45 seconds.
38222:29:06# [RSpecRunTime] RSpec elapsed time: 11 minutes 35.85 seconds. Current RSS: ~1221M. Threads: 1. load average: 1.11 1.18 1.21 1/405 415
38322:29:06.
38422:29:06# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/purge_security_scans_with_empty_finding_data_spec.rb. Expected to take 1 minute 40.5 seconds.
38522:29:06Gitlab::BackgroundMigration::PurgeSecurityScansWithEmptyFindingData
38622:30:35WARNING: Active Record does not support composite primary key.
38722:30:35security_findings has composite primary key. Composite primary key is ignored.
38822:30:36WARNING: Active Record does not support composite primary key.
38922:30:36security_findings has composite primary key. Composite primary key is ignored.
39022:30:40 purges the correct `security_scans` records
39122:30:40 when migration encounters with an existing `finding_data`
39222:30:48WARNING: Active Record does not support composite primary key.
39322:30:48security_findings has composite primary key. Composite primary key is ignored.
39422:30:53 marks the migration as finished and does not purge the related `security_scans` record
39522:30:53# [RSpecRunTime] RSpec elapsed time: 13 minutes 22.43 seconds. Current RSS: ~1237M. Threads: 1. load average: 1.14 1.19 1.21 1/407 416
39622:30:53.
39722:30:57I, [2024-11-13T22:30:57.926069 #347] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
39822:31:15I, [2024-11-13T22:31:15.366472 #347] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
39922:31:15I, [2024-11-13T22:31:15.367853 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40022:31:15I, [2024-11-13T22:31:15.368735 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40122:31:30I, [2024-11-13T22:31:30.179456 #347] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
40222:31:39I, [2024-11-13T22:31:39.113289 #347] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
40322:31:48I, [2024-11-13T22:31:48.962825 #347] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
40422:31:52I, [2024-11-13T22:31:52.077179 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
40522:31:56INFO: analyzing "public.p_ci_pipelines" inheritance tree
40622:31:56INFO: analyzing "public.ci_pipelines"
40722:31:56INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
40822:31:56I, [2024-11-13T22:31:56.675167 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
40922:31:56I, [2024-11-13T22:31:56.676482 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41022:31:56I, [2024-11-13T22:31:56.677369 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41122:32:03I, [2024-11-13T22:32:03.924602 #347] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
41222:32:16I, [2024-11-13T22:32:16.848147 #347] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
41322:32:16I, [2024-11-13T22:32:16.849488 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41422:32:16I, [2024-11-13T22:32:16.850412 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41522:32:16I, [2024-11-13T22:32:16.854117 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
41622:32:16I, [2024-11-13T22:32:16.855317 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41722:32:16I, [2024-11-13T22:32:16.856175 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41822:32:16I, [2024-11-13T22:32:16.859892 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
41922:32:16I, [2024-11-13T22:32:16.861116 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
42022:32:16I, [2024-11-13T22:32:16.861985 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
42122:32:16I, [2024-11-13T22:32:16.866341 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
42222:32:16I, [2024-11-13T22:32:16.867596 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
42322:32:16I, [2024-11-13T22:32:16.868483 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
42422:32:18I, [2024-11-13T22:32:18.017726 #347] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
42522:32:18I, [2024-11-13T22:32:18.019129 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
42622:32:18I, [2024-11-13T22:32:18.020154 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
42722:32:18I, [2024-11-13T22:32:18.024261 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
42822:32:18I, [2024-11-13T22:32:18.025498 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
42922:32:18I, [2024-11-13T22:32:18.026417 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43022:32:18I, [2024-11-13T22:32:18.030702 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
43122:32:18I, [2024-11-13T22:32:18.032132 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
43222:32:18I, [2024-11-13T22:32:18.033001 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43322:32:18I, [2024-11-13T22:32:18.036770 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
43422:32:18I, [2024-11-13T22:32:18.038063 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
43522:32:18I, [2024-11-13T22:32:18.038928 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43622:32:44# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/purge_security_scans_with_empty_finding_data_spec.rb. It took 3 minutes 37.52 seconds. Expected to take 1 minute 40.5 seconds.
43722:32:44# [RSpecRunTime] RSpec elapsed time: 15 minutes 13.42 seconds. Current RSS: ~1213M. Threads: 1. load average: 1.06 1.15 1.19 2/407 417
43822:32:44.
43922:32:44# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_zoekt_replicas_spec.rb. Expected to take 59.85 seconds.
44022:32:44Gitlab::BackgroundMigration::BackfillZoektReplicas
44122:33:36 correctly backfills the zoekt_replica_id for zoekt_indices
44222:33:43 ignores zoekt indices with missing enabled namespace
44322:33:48I, [2024-11-13T22:33:48.463707 #347] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
44422:33:58I, [2024-11-13T22:33:58.309801 #347] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
44522:34:01I, [2024-11-13T22:34:01.673145 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
44622:34:06INFO: analyzing "public.p_ci_pipelines" inheritance tree
44722:34:06INFO: analyzing "public.ci_pipelines"
44822:34:06INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
44922:34:06I, [2024-11-13T22:34:06.163864 #347] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
45022:34:06I, [2024-11-13T22:34:06.165165 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
45122:34:06I, [2024-11-13T22:34:06.166124 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
45222:34:13I, [2024-11-13T22:34:13.389706 #347] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
45322:34:25I, [2024-11-13T22:34:25.900514 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
45422:34:25I, [2024-11-13T22:34:25.901850 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
45522:34:25I, [2024-11-13T22:34:25.902756 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
45622:34:25I, [2024-11-13T22:34:25.906536 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
45722:34:25I, [2024-11-13T22:34:25.907717 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
45822:34:25I, [2024-11-13T22:34:25.908567 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
45922:34:25I, [2024-11-13T22:34:25.912473 #347] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
46022:34:25I, [2024-11-13T22:34:25.913675 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
46122:34:25I, [2024-11-13T22:34:25.914517 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
46222:34:25I, [2024-11-13T22:34:25.918561 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
46322:34:25I, [2024-11-13T22:34:25.919753 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
46422:34:25I, [2024-11-13T22:34:25.920590 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
46522:34:27I, [2024-11-13T22:34:27.199241 #347] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
46622:34:27I, [2024-11-13T22:34:27.200972 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
46722:34:27I, [2024-11-13T22:34:27.201975 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
46822:34:27I, [2024-11-13T22:34:27.206707 #347] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
46922:34:27I, [2024-11-13T22:34:27.208132 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
47022:34:27I, [2024-11-13T22:34:27.209157 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
47122:34:27I, [2024-11-13T22:34:27.213294 #347] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
47222:34:27I, [2024-11-13T22:34:27.214797 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
47322:34:27I, [2024-11-13T22:34:27.215767 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
47422:34:27I, [2024-11-13T22:34:27.219988 #347] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
47522:34:27I, [2024-11-13T22:34:27.221421 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
47622:34:27I, [2024-11-13T22:34:27.222363 #347] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
47722:34:53# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_zoekt_replicas_spec.rb. It took 2 minutes 8.59 seconds. Expected to take 59.85 seconds.
47822:34:53# [RSpecRunTime] RSpec elapsed time: 17 minutes 22.08 seconds. Current RSS: ~1219M. Threads: 1. load average: 1.19 1.15 1.18 1/408 418
47922:34:53.
48022:34:53# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_reserved_storage_bytes_spec.rb. Expected to take 23.8 seconds.
48122:34:53Gitlab::BackgroundMigration::BackfillReservedStorageBytes
48222:35:00 ignores zoekt indices with missing enabled namespace
48322:35:06 does not overwrites the reserved_storage_bytes if it is already present
48422:35:12 backfills with default value of reserved_storage_bytes when root_storage_statistics is missing
48522:35:18 correctly backfills the zoekt_replica_id for zoekt_indices
48622:35:35# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_reserved_storage_bytes_spec.rb. It took 42.65 seconds. Expected to take 23.8 seconds.
48722:35:35# [RSpecRunTime] RSpec elapsed time: 18 minutes 4.79 seconds. Current RSS: ~1221M. Threads: 1. load average: 1.07 1.12 1.17 1/408 419
48822:35:35.
48922:35:35# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_has_vulnerability_resolution_spec.rb. Expected to take 15.75 seconds.
49022:35:35Gitlab::BackgroundMigration::BackfillHasVulnerabilityResolution
49122:35:38 when there are null values for vulnerability_reads.has_vulnerability_resolution
49222:35:45 updates the nil columns with a boolean
49322:35:45 with non-nil rows
49422:35:51 does not affect non-nil rows
49522:35:51# [RSpecRunTime] RSpec elapsed time: 18 minutes 20.54 seconds. Current RSS: ~1230M. Threads: 1. load average: 1.13 1.13 1.17 1/408 420
49622:35:51.
49722:36:11# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_has_vulnerability_resolution_spec.rb. It took 36.01 seconds. Expected to take 15.75 seconds.
49822:36:11# [RSpecRunTime] RSpec elapsed time: 18 minutes 40.86 seconds. Current RSS: ~1224M. Threads: 1. load average: 1.09 1.12 1.17 1/408 421
49922:36:11.
50022:36:11# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/disable_allow_runner_registration_on_namespace_settings_for_gitlab_com_spec.rb. Expected to take 13.08 seconds.
50122:36:11Gitlab::BackgroundMigration::DisableAllowRunnerRegistrationOnNamespaceSettingsForGitlabCom
50222:36:12 #perform
50322:36:18 disables the namespace setting for namespaces with settings
50422:36:18 with end_id set to start_id
50522:36:25 disables the namespace setting for first namespace with settings
50622:36:25# [RSpecRunTime] RSpec elapsed time: 18 minutes 54.07 seconds. Current RSS: ~1235M. Threads: 1. load average: 1.00 1.10 1.16 1/408 422
50722:36:25.
50822:36:42# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/disable_allow_runner_registration_on_namespace_settings_for_gitlab_com_spec.rb. It took 30.54 seconds. Expected to take 13.08 seconds.
50922:36:42# [RSpecRunTime] RSpec elapsed time: 19 minutes 11.45 seconds. Current RSS: ~1235M. Threads: 1. load average: 1.07 1.10 1.16 1/408 423
51022:36:42.
51122:36:42# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/set_total_number_of_vulnerabilities_for_existing_projects_spec.rb. Expected to take 6.87 seconds.
51222:36:42Gitlab::BackgroundMigration::SetTotalNumberOfVulnerabilitiesForExistingProjects
51322:36:50 updates vulnerability_count for vulnerable projects
51422:37:07# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/set_total_number_of_vulnerabilities_for_existing_projects_spec.rb. It took 25.21 seconds. Expected to take 6.87 seconds.
51522:37:07# [RSpecRunTime] RSpec elapsed time: 19 minutes 36.72 seconds. Current RSS: ~1211M. Threads: 1. load average: 0.92 1.06 1.14 1/408 424
51622:37:07.
51722:37:07# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_security_policies_spec.rb. Missing expected duration from Knapsack report for ee/spec/lib/ee/gitlab/background_migration/backfill_security_policies_spec.rb.
51822:37:07Gitlab::BackgroundMigration::BackfillSecurityPolicies
51922:37:08 with approval policies
52022:37:15 creates approval policies
52122:37:15 with multiple rules
52222:37:21 creates multiple approval_policy_rules
52322:37:21# [RSpecRunTime] RSpec elapsed time: 19 minutes 50.81 seconds. Current RSS: ~1227M. Threads: 3. load average: 0.86 1.04 1.14 1/419 436
52422:37:21.
52522:37:21 with group level policies
52622:37:28 creates records for every project records
52722:37:28 when policy is scoped to project
52822:37:34 creates links only for the project in scope
52922:37:34# [RSpecRunTime] RSpec elapsed time: 20 minutes 3.79 seconds. Current RSS: ~1224M. Threads: 3. load average: 1.05 1.08 1.14 1/419 437
53022:37:34.
53122:37:34 when policy is scoped to a group
53222:37:41 creates links only for the project in group scope
53322:37:41# [RSpecRunTime] RSpec elapsed time: 20 minutes 10.29 seconds. Current RSS: ~1227M. Threads: 3. load average: 1.04 1.07 1.14 1/419 438
53422:37:41.
53522:37:41 when policy is unscoped to a group
53622:37:47 creates links only for the project with excluding group scope
53722:37:47# [RSpecRunTime] RSpec elapsed time: 20 minutes 16.89 seconds. Current RSS: ~1226M. Threads: 3. load average: 1.04 1.07 1.14 1/419 439
53822:37:47.
53922:37:47 when policy is scoped to compliance framework
54022:37:54 creates links only for the project in scope
54122:37:54# [RSpecRunTime] RSpec elapsed time: 20 minutes 23.42 seconds. Current RSS: ~1227M. Threads: 3. load average: 1.11 1.09 1.15 1/419 440
54222:37:54.
54322:37:54 for invalid policies
54422:38:00 does not create any records
54522:38:00# [RSpecRunTime] RSpec elapsed time: 20 minutes 29.63 seconds. Current RSS: ~1228M. Threads: 3. load average: 1.19 1.10 1.15 1/419 441
54622:38:00.
54722:38:00 with scan execution policies
54822:38:06 creates scan_execution policies
54922:38:06# [RSpecRunTime] RSpec elapsed time: 20 minutes 35.94 seconds. Current RSS: ~1230M. Threads: 3. load average: 1.16 1.10 1.15 1/419 442
55022:38:06.
55122:38:06 with pipeline_execution_policies
55222:38:13 creates pipeline execution policies
55322:38:13# [RSpecRunTime] RSpec elapsed time: 20 minutes 42.22 seconds. Current RSS: ~1230M. Threads: 3. load average: 1.22 1.11 1.15 1/419 443
55422:38:13.
55522:38:13 with vulnerability_management_policies
55622:38:19 creates vulnerability_management_policy_rules
55722:38:19# [RSpecRunTime] RSpec elapsed time: 20 minutes 48.46 seconds. Current RSS: ~1231M. Threads: 3. load average: 1.29 1.13 1.16 1/419 444
55822:38:19.
55922:38:36# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_security_policies_spec.rb. It took 1 minute 28.91 seconds. Missing expected duration from Knapsack report for ee/spec/lib/ee/gitlab/background_migration/backfill_security_policies_spec.rb.
56022:38:36# [RSpecRunTime] RSpec elapsed time: 21 minutes 5.69 seconds. Current RSS: ~1223M. Threads: 3. load average: 1.15 1.11 1.15 1/419 445
56122:38:36.
56222:39:07auto_explain log contains 1185 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-background-migration-pg14-1-3.347.main.ndjson.gz
56322:39:07took 31.191613121
56422:39:35auto_explain log contains 1186 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-background-migration-pg14-1-3.347.ci.ndjson.gz
56522:39:35took 27.398010306
56622:39:35[TEST PROF INFO] EventProf results for sql.active_record
56722:39:35Total time: 10:55.475 of 20:52.776 (52.32%)
56822:39:35Total events: 461938
56922:39:35Top 5 slowest suites (by time):
57022:39:35Gitlab::Backg...smissalDetails (./ee/spec/lib/ee/gitlab/background_migration/backfill_missing_vulnerability_dismissal_details_spec.rb:5) – 03:10.013 (126944 / 5) of 06:24.418 (49.43%)
57122:39:35Gitlab::Backg...bomOccurrences (./ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb:5) – 03:04.314 (117444 / 1) of 04:58.527 (61.74%)
57222:39:35Gitlab::Backg...ptyFindingData (./ee/spec/lib/ee/gitlab/background_migration/purge_security_scans_with_empty_finding_data_spec.rb:5) – 02:07.090 (83770 / 2) of 03:37.584 (58.41%)
57322:39:35Gitlab::Backg...lZoektReplicas (./ee/spec/lib/ee/gitlab/background_migration/backfill_zoekt_replicas_spec.rb:5) – 01:13.094 (47208 / 2) of 02:08.649 (56.82%)
57422:39:35Gitlab::Backg...curityPolicies (./ee/spec/lib/ee/gitlab/background_migration/backfill_security_policies_spec.rb:5) – 00:30.900 (34522 / 11) of 01:28.965 (34.73%)
57522:39:35Knapsack report was generated. Preview:
57622:39:35{
57722:39:35 "ee/spec/lib/ee/gitlab/background_migration/backfill_missing_vulnerability_dismissal_details_spec.rb": 229.64519268000004,
57822:39:35 "ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb": 141.01056352100022,
57922:39:35 "ee/spec/lib/ee/gitlab/background_migration/purge_security_scans_with_empty_finding_data_spec.rb": 106.59449640999992,
58022:39:35 "ee/spec/lib/ee/gitlab/background_migration/backfill_zoekt_replicas_spec.rb": 59.50176009699953,
58122:39:35 "ee/spec/lib/ee/gitlab/background_migration/backfill_reserved_storage_bytes_spec.rb": 25.427167880000525,
58222:39:35 "ee/spec/lib/ee/gitlab/background_migration/backfill_has_vulnerability_resolution_spec.rb": 15.763108223000017,
58322:39:35 "ee/spec/lib/ee/gitlab/background_migration/disable_allow_runner_registration_on_namespace_settings_for_gitlab_com_spec.rb": 13.2319927919998,
58422:39:35 "ee/spec/lib/ee/gitlab/background_migration/set_total_number_of_vulnerabilities_for_existing_projects_spec.rb": 8.405087881000327,
58522:39:35 "ee/spec/lib/ee/gitlab/background_migration/backfill_security_policies_spec.rb": 71.76166293499955
58622:39:35}
58722:39:35Knapsack global time execution for tests: 11m 11s
58822:39:35Finished in 22 minutes 5 seconds (files took 2 minutes 50 seconds to load)
58922:39:3530 examples, 0 failures
59022:39:35Randomized with seed 22233
59122:39:42[TEST PROF INFO] Time spent in factories: 00:00.111 (0.01% of total time)
59222:39:42RSpec exited with 0.
59322:39:42No examples to retry, congrats!
594 22:39:43 Running after_script 00:18
59522:39:44Running after script...
59622:39:44$ source scripts/utils.sh
59722:39:44$ log_disk_usage
59822:39:44*******************************************************
59922:39:44This runner currently has 25G free disk space.
60022:39:44*******************************************************
601 22:39:44 Disk usage detail 00:08
66022:39:52$ bundle exec gem list gitlab_quality-test_tooling
66122:39:55gitlab_quality-test_tooling (2.1.0)
66222:39:55$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
663 22:39:55 Report test failures 00:00
66522:39:55$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
666 22:39:55 Report test flakiness 00:00
66822:39:55$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
669 22:39:55 Report test slowness 00:00
67122:39:55$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
672 22:39:55 Report test slowness in MR note 00:02
67522:39:57$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
676 22:39:57 Report test files close to timing out 00:02
68022:39:59$ tooling/bin/push_job_metrics || true
68122:40:01[job-metrics] Pushing job metrics file for the CI/CD job.
68222:40:01[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
683 22:40:01 Saving cache for successful job 00:02
68422:40:02Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
685 22:40:03 Uploading artifacts for successful job 00:07
68622:40:05Uploading artifacts...
68722:40:05auto_explain/: found 3 matching artifact files and directories
68822:40:05coverage/: found 5 matching artifact files and directories
68922:40:05crystalball/: found 2 matching artifact files and directories
69022:40:05WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
69122:40:05knapsack/: found 4 matching artifact files and directories
69222:40:05rspec/: found 16 matching artifact files and directories
69322:40:05WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
69422:40:05log/*.log: found 13 matching artifact files and directories
69522:40:06WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353288362/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
69622:40:06WARNING: Retrying... context=artifacts-uploader error=request redirected
69722:40:08Uploading artifacts as "archive" to coordinator... 201 Created id=8353288362 responseStatus=201 Created token=glcbt-66
69822:40:08Uploading artifacts...
69922:40:08rspec/rspec-*.xml: found 1 matching artifact files and directories
70022:40:09WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353288362/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
70122:40:09WARNING: Retrying... context=artifacts-uploader error=request redirected
70222:40:10Uploading artifacts as "junit" to coordinator... 201 Created id=8353288362 responseStatus=201 Created token=glcbt-66
703 22:40:10 Cleaning up project directory and file based variables 00:01
70422:40:21Job succeeded