Skip to content
Snippets Groups Projects

rspec background_migration pg14 7/10

Passed Started by
George Koltsov
@georgekoltsov
Log timestamps in UTC.
116:54:04Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
216:54:04 on blue-5.private.runners-manager.gitlab.com/gitlab.com/gitlab-org PYwgZLQB, system ID: s_4bff81aff7f7
316:54:04 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
416:54:04Resolving secrets
5 16:54:04 Preparing the "docker+machine" executor 00:11
616:54:04Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
716:54:06Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
816:54:07Authenticating with credentials from job payload (GitLab Registry)
916:54:07Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1016:54:07Using 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 ...
1116:54:07WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1216:54:07WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1316:54:07Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1416:54:08Authenticating with credentials from job payload (GitLab Registry)
1516:54:08Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1616:54:08Using 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 ...
1716:54:08Starting service redis:6.2-alpine...
1816:54:09Pulling docker image redis:6.2-alpine ...
1916:54:09Using docker image sha256:ec1b0a84ddf9124276ac189cc4d98ef28f0aea95ebd148d9e4c18b28928dd999 for redis:6.2-alpine with digest redis@sha256:2ba50e1ac3a0ea17b736ce9db2b0a9f6f8b85d4c27d5f5accc6a416d8f42c6d5 ...
2016:54:10Waiting for services to be up and running (timeout 30 seconds)...
2116:54:14Authenticating with credentials from job payload (GitLab Registry)
2216:54:14Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
2316:54:15Using docker image sha256:ab7961692458f506f4ffa89e6e60bf1fc6de783a0d358136ca0b3e84c63c84a8 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-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.22-rust-1.73-node-20.12-postgresql-14@sha256:8ec7e4c24be92019bc9072c7049231de71d9a56301d7562522d046f1494470be ...
24 16:54:15 Preparing environment 00:00
2516:54:15Running on runner-pywgzlqb-project-278964-concurrent-0 via runner-pywgzlqb-private-1729257786-6d926d8d...
26 16:54:15 Getting source from Git repository 00:01
2716:54:16Skipping Git repository setup
2816:54:16Skipping Git checkout
2916:54:16Skipping Git submodules setup
30 16:54:16 Restoring cache 00:18
3116:54:17Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19...
3216:54:17cache.zip is up to date
3316:54:27Successfully extracted cache
34 16:54:34 Downloading artifacts 00:49
3516:54:35Downloading artifacts for clone-gitlab-repo (8126558001)...
3616:54:42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8126558001 responseStatus=200 OK token=glcbt-66
3716:54:42WARNING: Part of .git directory is on the list of files to extract
3816:54:42WARNING: This may introduce unexpected problems
3916:54:54Downloading artifacts for compile-test-assets (8126558064)...
4016:54:58Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8126558064 responseStatus=200 OK token=glcbt-66
4116:55:04Downloading artifacts for detect-tests (8126558090)...
4216:55:05Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8126558090 responseStatus=200 OK token=glcbt-66
4316:55:05Downloading artifacts for retrieve-tests-metadata (8126558100)...
4416:55:05Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8126558100 responseStatus=200 OK token=glcbt-66
4516:55:05Downloading artifacts for setup-test-env (8126558072)...
4616:55:10Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8126558072 responseStatus=200 OK token=glcbt-66
47 16:55:23 Executing "step_script" stage of the job script
4816:55:23Using docker image sha256:ab7961692458f506f4ffa89e6e60bf1fc6de783a0d358136ca0b3e84c63c84a8 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-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.22-rust-1.73-node-20.12-postgresql-14@sha256:8ec7e4c24be92019bc9072c7049231de71d9a56301d7562522d046f1494470be ...
4916:55:23$ echo $FOSS_ONLY
5016:55:23$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5116:55:23$ export GOPATH=$CI_PROJECT_DIR/.go
5216:55:23$ mkdir -p $GOPATH
5316:55:23$ source scripts/utils.sh
5416:55:23$ log_disk_usage "true"
5516:55:23*******************************************************
5616:55:23This runner currently has 73G free disk space.
5716:55:23*******************************************************
58 16:55:23 Disk usage detail 00:13
5916:55:23df -h
6016:55:23Filesystem Size Used Avail Use% Mounted on
6116:55:23overlay 95G 23G 73G 24% /
6216:55:23tmpfs 64M 0 64M 0% /dev
6316:55:23shm 64M 0 64M 0% /dev/shm
6416:55:23/dev/sda1 95G 23G 73G 24% /cache
6516:55:23tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6616:55:23du -h -d 1
6716:55:237.4M ./workhorse
6816:55:23148K ./bin
6916:55:231.2M ./.gitlab
7016:55:23177M ./.git
7116:55:234.0K ./builds
7216:55:2328M ./lib
7316:55:238.0K ./.vscode
7416:55:23532K ./storybook
7516:55:2320K ./log
7616:55:231.8M ./data
7716:55:2310M ./qa
7816:55:232.8M ./.rubocop_todo
7916:55:24819M ./vendor
8016:55:241006M ./public
8116:55:2459M ./doc
8216:55:24492K ./danger
8316:55:24108K ./rspec
8416:55:24552K ./tooling
8516:55:24142M ./ee
8616:55:241.3M ./knapsack
8716:55:24296K ./patches
8816:55:244.3M ./changelogs
8916:55:2420K ./haml_lint
9016:55:2428K ./metrics_server
9116:55:2488K ./keeps
9216:55:2448K ./shared
9316:55:2444K ./generator_templates
9416:55:241.2M ./scripts
9516:55:24690M ./tmp
9616:55:24126M ./spec
9716:55:2420K ./file_hooks
9816:55:2432K ./crystalball
9916:55:2416K ./sidekiq_cluster
10016:55:2417M ./gems
10116:55:241.2M ./fixtures
10216:55:24968K ./rubocop
10316:55:244.0K ./.go
10416:55:2431M ./db
10516:55:2412K ./.github
10616:55:24129M ./locale
10716:55:2419M ./config
10816:55:2481M ./app
10916:55:2420K ./.lefthook
11016:55:243.3G .
11116:55:24$ source scripts/prepare_build.sh
112 16:55:24 Installing gems 00:19
14316:55:43Using decomposed database config (config/database.yml.decomposed-postgresql)
14416:55:43Geo DB won't be set up.
14516:55:43Embedding DB won't be set up.
146 16:55:43 Setting up DBs 01:07
15816:56:50$ source ./scripts/rspec_helpers.sh
15916:56:50$ run_timed_command "gem install knapsack --no-document"
16016:56:50$ gem install knapsack --no-document
16116:56:51Successfully installed knapsack-4.0.0
16216:56:511 gem installed
16316:56:50==> 'gem install knapsack --no-document' succeeded in 2 seconds.
16416:56:52$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
165 16:56:52 Spawning Gitaly 00:04
16916:56:56$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17016:56:56$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17116:56:56$ tooling/bin/create_job_metrics_file || true
17216:56:58[job-metrics] Creating the job metrics file for the CI/CD job.
17316:56:58$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
174 16:56:58 RSpec 45:16
17516:56:58[16:56:58] Starting rspec_parallelized_job
17616:56:52RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
17716:56:58KNAPSACK_GENERATE_REPORT: true
17816:56:58FLAKY_RSPEC_GENERATE_REPORT: true
17916:56:58KNAPSACK_TEST_FILE_PATTERN: spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
18016:56:58KNAPSACK_LOG_LEVEL: debug
18116:56:58KNAPSACK_REPORT_PATH: knapsack/rspec_background_migration_pg14_7_10_278964_report.json
18216:56:58FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18316:56:58FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_background_migration_pg14_7_10_278964_report.json
18416:56:58NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_background_migration_pg14_7_10_278964_report.json
18516:56:58RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8126558229.txt
18616:56:58CRYSTALBALL:
18716:56:58RSPEC_TESTS_MAPPING_ENABLED:
18816:56:58RSPEC_TESTS_FILTER_FILE:
18916:56:58Shell set options (set -o) enabled:
19016:56:58braceexpand on
19116:56:58hashall on
19216:56:58interactive-comments on
19316:56:58pipefail on
19416:57:05Knapsack report generator started!
19516:56:58/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!
19616:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:19: warning: already initialized constant Integrations::Base::BaseIntegration::UnknownType
19716:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:19: warning: previous definition of UnknownType was here
19816:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:24: warning: already initialized constant Integrations::Base::BaseIntegration::INTEGRATION_NAMES
19916:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:24: warning: previous definition of INTEGRATION_NAMES was here
20016:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:34: warning: already initialized constant Integrations::Base::BaseIntegration::INSTANCE_LEVEL_ONLY_INTEGRATION_NAMES
20116:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:34: warning: previous definition of INSTANCE_LEVEL_ONLY_INTEGRATION_NAMES was here
20216:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:39: warning: already initialized constant Integrations::Base::BaseIntegration::PROJECT_LEVEL_ONLY_INTEGRATION_NAMES
20316:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:39: warning: previous definition of PROJECT_LEVEL_ONLY_INTEGRATION_NAMES was here
20416:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:44: warning: already initialized constant Integrations::Base::BaseIntegration::PROJECT_AND_GROUP_LEVEL_ONLY_INTEGRATION_NAMES
20516:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:44: warning: previous definition of PROJECT_AND_GROUP_LEVEL_ONLY_INTEGRATION_NAMES was here
20616:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:49: warning: already initialized constant Integrations::Base::BaseIntegration::DEV_INTEGRATION_NAMES
20716:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:49: warning: previous definition of DEV_INTEGRATION_NAMES was here
20816:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:54: warning: already initialized constant Integrations::Base::BaseIntegration::BASE_CLASSES
20916:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:54: warning: previous definition of BASE_CLASSES was here
21016:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:64: warning: already initialized constant Integrations::Base::BaseIntegration::BASE_ATTRIBUTES
21116:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:64: warning: previous definition of BASE_ATTRIBUTES was here
21216:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:67: warning: already initialized constant Integrations::Base::BaseIntegration::SECTION_TYPE_CONFIGURATION
21316:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:67: warning: previous definition of SECTION_TYPE_CONFIGURATION was here
21416:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:68: warning: already initialized constant Integrations::Base::BaseIntegration::SECTION_TYPE_CONNECTION
21516:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:68: warning: previous definition of SECTION_TYPE_CONNECTION was here
21616:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:69: warning: already initialized constant Integrations::Base::BaseIntegration::SECTION_TYPE_TRIGGER
21716:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:69: warning: previous definition of SECTION_TYPE_TRIGGER was here
21816:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:71: warning: already initialized constant Integrations::Base::BaseIntegration::SNOWPLOW_EVENT_ACTION
21916:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:71: warning: previous definition of SNOWPLOW_EVENT_ACTION was here
22016:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:72: warning: already initialized constant Integrations::Base::BaseIntegration::SNOWPLOW_EVENT_LABEL
22116:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_integration.rb:72: warning: previous definition of SNOWPLOW_EVENT_LABEL was here
22216:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:16: warning: already initialized constant Integrations::Base::BaseJira::PROJECTS_PER_PAGE
22316:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:16: warning: previous definition of PROJECTS_PER_PAGE was here
22416:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:17: warning: already initialized constant Integrations::Base::BaseJira::JIRA_CLOUD_HOST
22516:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:17: warning: previous definition of JIRA_CLOUD_HOST was here
22616:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:19: warning: already initialized constant Integrations::Base::BaseJira::ATLASSIAN_REFERRER_GITLAB_COM
22716:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:19: warning: previous definition of ATLASSIAN_REFERRER_GITLAB_COM was here
22816:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:22: warning: already initialized constant Integrations::Base::BaseJira::ATLASSIAN_REFERRER_SELF_MANAGED
22916:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:22: warning: previous definition of ATLASSIAN_REFERRER_SELF_MANAGED was here
23016:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:26: warning: already initialized constant Integrations::Base::BaseJira::API_ENDPOINTS
23116:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:26: warning: previous definition of API_ENDPOINTS was here
23216:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:35: warning: already initialized constant Integrations::Base::BaseJira::SECTION_TYPE_JIRA_TRIGGER
23316:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:35: warning: previous definition of SECTION_TYPE_JIRA_TRIGGER was here
23416:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:36: warning: already initialized constant Integrations::Base::BaseJira::SECTION_TYPE_JIRA_ISSUES
23516:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:36: warning: previous definition of SECTION_TYPE_JIRA_ISSUES was here
23616:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:37: warning: already initialized constant Integrations::Base::BaseJira::SECTION_TYPE_JIRA_ISSUE_CREATION
23716:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:37: warning: previous definition of SECTION_TYPE_JIRA_ISSUE_CREATION was here
23816:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:39: warning: already initialized constant Integrations::Base::BaseJira::AUTH_TYPE_BASIC
23916:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:39: warning: previous definition of AUTH_TYPE_BASIC was here
24016:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:40: warning: already initialized constant Integrations::Base::BaseJira::AUTH_TYPE_PAT
24116:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:40: warning: previous definition of AUTH_TYPE_PAT was here
24216:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:42: warning: already initialized constant Integrations::Base::BaseJira::SNOWPLOW_EVENT_CATEGORY
24316:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:42: warning: previous definition of SNOWPLOW_EVENT_CATEGORY was here
24416:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:44: warning: already initialized constant Integrations::Base::BaseJira::RE2_SYNTAX_DOC_URL
24516:57:32/builds/gitlab-org/gitlab/app/models/integrations/base/base_jira.rb:44: warning: previous definition of RE2_SYNTAX_DOC_URL was here
24616:57:51warning: parser/current is loading parser/ruby32, which recognizes 3.2.4-compliant syntax, but you are running 3.2.5.
24716:57:51Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
24816:57:55Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
24916:57:55# [RSpecRunTime] Starting RSpec timer...
25016:57:55[TEST PROF INFO] EventProf enabled (sql.active_record)
25116:57:55unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
25216:57:55unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
25316:58:02Test environment set up in 3.092844208 seconds
25416:58:02# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_integrations_enable_ssl_verification_spec.rb. Expected to take 2 minutes 23.64 seconds.
25516:58:02Gitlab::BackgroundMigration::BackfillIntegrationsEnableSslVerification
25616:59:52 enables SSL verification for known-good hostnames
25717:00:08 only updates records within the given ID range
25817:00:24 marks the job as succeeded
25917:00:26I, [2024-10-18T17:00:26.231542 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
26017:00:45I, [2024-10-18T17:00:45.799645 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
26117:00:47INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
26217:00:47INFO: analyzing "public.ci_pipeline_variables"
26317:00:47INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26417:00:47I, [2024-10-18T17:00:47.389393 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
26517:00:47I, [2024-10-18T17:00:47.390732 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26617:00:47I, [2024-10-18T17:00:47.391697 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26717:00:49INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
26817:00:49INFO: analyzing "public.ci_pipeline_variables"
26917:00:49INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
27017:00:53INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
27117:00:53INFO: analyzing "public.ci_job_artifacts"
27217:00:53INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
27317:00:53I, [2024-10-18T17:00:53.639693 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
27417:00:53I, [2024-10-18T17:00:53.640870 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27517:00:53I, [2024-10-18T17:00:53.641765 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27617:00:56INFO: analyzing "public.p_ci_stages" inheritance tree
27717:00:56INFO: analyzing "public.ci_stages"
27817:00:56INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
27917:00:56I, [2024-10-18T17:00:56.927336 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
28017:00:56I, [2024-10-18T17:00:56.928548 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28117:00:56I, [2024-10-18T17:00:56.929380 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28217:00:58I, [2024-10-18T17:00:58.099711 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
28317:01:04I, [2024-10-18T17:01:04.458992 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
28417:01:17I, [2024-10-18T17:01:17.929425 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
28517:01:17I, [2024-10-18T17:01:17.930672 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28617:01:17I, [2024-10-18T17:01:17.931533 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28717:01:29I, [2024-10-18T17:01:29.442060 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
28817:01:36I, [2024-10-18T17:01:36.162509 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
28917:01:43I, [2024-10-18T17:01:43.774419 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
29017:01:46I, [2024-10-18T17:01:46.304763 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
29117:01:49INFO: analyzing "public.p_ci_pipelines" inheritance tree
29217:01:49INFO: analyzing "public.ci_pipelines"
29317:01:49INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29417:01:49I, [2024-10-18T17:01:49.918178 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
29517:01:49I, [2024-10-18T17:01:49.919412 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29617:01:49I, [2024-10-18T17:01:49.920281 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29717:01:55I, [2024-10-18T17:01:55.442671 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
29817:02:15# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_integrations_enable_ssl_verification_spec.rb. It took 4 minutes 13.12 seconds. Expected to take 2 minutes 23.64 seconds.
29917:02:15# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb. Expected to take 2 minutes 1.47 seconds.
30017:02:15Gitlab::BackgroundMigration::BackfillTopicsTitle
30117:04:08 correctly backfills the title of the topics
30217:04:09I, [2024-10-18T17:04:09.190869 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
30317:04:29I, [2024-10-18T17:04:29.951868 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
30417:04:31INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
30517:04:31INFO: analyzing "public.ci_pipeline_variables"
30617:04:31INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
30717:04:31I, [2024-10-18T17:04:31.637052 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
30817:04:31I, [2024-10-18T17:04:31.638308 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30917:04:31I, [2024-10-18T17:04:31.639192 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
31017:04:33INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
31117:04:33INFO: analyzing "public.ci_pipeline_variables"
31217:04:33INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
31317:04:38INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
31417:04:38INFO: analyzing "public.ci_job_artifacts"
31517:04:38INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
31617:04:38I, [2024-10-18T17:04:38.022843 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
31717:04:38I, [2024-10-18T17:04:38.024201 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
31817:04:38I, [2024-10-18T17:04:38.025173 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
31917:04:41INFO: analyzing "public.p_ci_stages" inheritance tree
32017:04:41INFO: analyzing "public.ci_stages"
32117:04:41INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
32217:04:41I, [2024-10-18T17:04:41.454057 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
32317:04:41I, [2024-10-18T17:04:41.455246 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32417:04:41I, [2024-10-18T17:04:41.456056 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32517:04:42I, [2024-10-18T17:04:42.712419 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
32617:04:49I, [2024-10-18T17:04:49.263946 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
32717:05:03I, [2024-10-18T17:05:03.172053 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
32817:05:03I, [2024-10-18T17:05:03.173251 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32917:05:03I, [2024-10-18T17:05:03.174072 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33017:05:14I, [2024-10-18T17:05:14.856776 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
33117:05:22I, [2024-10-18T17:05:22.118284 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
33217:05:30I, [2024-10-18T17:05:30.107456 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
33317:05:32I, [2024-10-18T17:05:32.886762 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
33417:05:36INFO: analyzing "public.p_ci_pipelines" inheritance tree
33517:05:36INFO: analyzing "public.ci_pipelines"
33617:05:36INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
33717:05:36I, [2024-10-18T17:05:36.788911 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
33817:05:36I, [2024-10-18T17:05:36.790255 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33917:05:36I, [2024-10-18T17:05:36.791101 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34017:05:42I, [2024-10-18T17:05:42.873039 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
34117:06:03# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb. It took 3 minutes 47.32 seconds. Expected to take 2 minutes 1.47 seconds.
34217:06:03# [RSpecRunTime] RSpec elapsed time: 8 minutes 7.76 seconds. Current RSS: ~1232M. Threads: 1. load average: 1.04 1.09 1.18 1/276 388
34317:06:03.
34417:06:03# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/fix_allow_descendants_override_disabled_shared_runners_spec.rb. Expected to take 1 minute 50.37 seconds.
34517:06:03Gitlab::BackgroundMigration::FixAllowDescendantsOverrideDisabledSharedRunners
34617:07:57 fixes invalid allow_descendants_override_disabled_shared_runners and does not affect others
34717:07:58I, [2024-10-18T17:07:58.562951 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
34817:08:21I, [2024-10-18T17:08:21.165587 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
34917:08:22INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
35017:08:22INFO: analyzing "public.ci_pipeline_variables"
35117:08:22INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
35217:08:22I, [2024-10-18T17:08:22.997151 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
35317:08:22I, [2024-10-18T17:08:22.998418 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
35417:08:22I, [2024-10-18T17:08:22.999269 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
35517:08:25INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
35617:08:25INFO: analyzing "public.ci_pipeline_variables"
35717:08:25INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
35817:08:29INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
35917:08:29INFO: analyzing "public.ci_job_artifacts"
36017:08:29INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
36117:08:29I, [2024-10-18T17:08:29.750581 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
36217:08:29I, [2024-10-18T17:08:29.751795 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36317:08:29I, [2024-10-18T17:08:29.752695 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36417:08:33INFO: analyzing "public.p_ci_stages" inheritance tree
36517:08:33INFO: analyzing "public.ci_stages"
36617:08:33INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
36717:08:33I, [2024-10-18T17:08:33.420380 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
36817:08:33I, [2024-10-18T17:08:33.421814 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36917:08:33I, [2024-10-18T17:08:33.422827 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37017:08:34I, [2024-10-18T17:08:34.765793 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
37117:08:41I, [2024-10-18T17:08:41.932745 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
37217:08:56I, [2024-10-18T17:08:56.978145 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
37317:08:56I, [2024-10-18T17:08:56.979489 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37417:08:56I, [2024-10-18T17:08:56.980354 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37517:09:10I, [2024-10-18T17:09:10.788695 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
37617:09:19I, [2024-10-18T17:09:19.598440 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
37717:09:28I, [2024-10-18T17:09:28.427985 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
37817:09:31I, [2024-10-18T17:09:31.229258 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
37917:09:35INFO: analyzing "public.p_ci_pipelines" inheritance tree
38017:09:35INFO: analyzing "public.ci_pipelines"
38117:09:35INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
38217:09:35I, [2024-10-18T17:09:35.253262 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
38317:09:35I, [2024-10-18T17:09:35.254634 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
38417:09:35I, [2024-10-18T17:09:35.255549 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
38517:09:41I, [2024-10-18T17:09:41.466850 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
38617:10:02# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/fix_allow_descendants_override_disabled_shared_runners_spec.rb. It took 3 minutes 59.11 seconds. Expected to take 1 minute 50.37 seconds.
38717:10:02# [RSpecRunTime] RSpec elapsed time: 12 minutes 7.15 seconds. Current RSS: ~1229M. Threads: 1. load average: 1.05 1.04 1.14 1/277 390
38817:10:02.
38917:10:02# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb. Expected to take 1 minute 34.66 seconds.
39017:10:02Gitlab::BackgroundMigration::DeleteInvalidProtectedBranchPushAccessLevels
39117:11:33 when there are push access levels
39217:11:47 deletes push access levels with groups that do not have project_group_links to the project
39317:11:48# [RSpecRunTime] RSpec elapsed time: 13 minutes 52.63 seconds. Current RSS: ~1265M. Threads: 1. load average: 1.25 1.13 1.16 1/279 391
39417:11:48.
39517:11:50I, [2024-10-18T17:11:50.296361 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
39617:11:52INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
39717:11:52INFO: analyzing "public.ci_pipeline_variables"
39817:11:52INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
39917:11:52I, [2024-10-18T17:11:52.166750 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
40017:11:52I, [2024-10-18T17:11:52.167938 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40117:11:52I, [2024-10-18T17:11:52.168805 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40217:11:54INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
40317:11:54INFO: analyzing "public.ci_pipeline_variables"
40417:11:54INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
40517:11:59INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
40617:11:59INFO: analyzing "public.ci_job_artifacts"
40717:11:59INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
40817:11:59I, [2024-10-18T17:11:59.420948 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
40917:11:59I, [2024-10-18T17:11:59.422274 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41017:11:59I, [2024-10-18T17:11:59.423112 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41117:12:03INFO: analyzing "public.p_ci_stages" inheritance tree
41217:12:03INFO: analyzing "public.ci_stages"
41317:12:03INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
41417:12:03I, [2024-10-18T17:12:03.450577 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
41517:12:03I, [2024-10-18T17:12:03.452986 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41617:12:03I, [2024-10-18T17:12:03.454044 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41717:12:04I, [2024-10-18T17:12:04.956917 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
41817:12:12I, [2024-10-18T17:12:12.628823 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
41917:12:28I, [2024-10-18T17:12:28.488054 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
42017:12:28I, [2024-10-18T17:12:28.489379 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
42117:12:28I, [2024-10-18T17:12:28.490269 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
42217:12:42I, [2024-10-18T17:12:42.266490 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
42317:12:50I, [2024-10-18T17:12:50.644499 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
42417:13:00I, [2024-10-18T17:13:00.065499 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
42517:13:03I, [2024-10-18T17:13:03.266514 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
42617:13:07INFO: analyzing "public.p_ci_pipelines" inheritance tree
42717:13:07INFO: analyzing "public.ci_pipelines"
42817:13:07INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
42917:13:07I, [2024-10-18T17:13:07.545498 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
43017:13:07I, [2024-10-18T17:13:07.546868 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
43117:13:07I, [2024-10-18T17:13:07.547877 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43217:13:14I, [2024-10-18T17:13:14.455624 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
43317:13:35# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb. It took 3 minutes 32.81 seconds. Expected to take 1 minute 34.66 seconds.
43417:13:35# [RSpecRunTime] RSpec elapsed time: 15 minutes 40.01 seconds. Current RSS: ~1236M. Threads: 1. load average: 1.02 1.08 1.14 1/279 392
43517:13:35.
43617:13:35# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/drop_vulnerabilities_without_finding_id_spec.rb. Expected to take 1 minute 24.62 seconds.
43717:13:35Gitlab::BackgroundMigration::DropVulnerabilitiesWithoutFindingId
43817:14:53 #perform
43917:15:04 removes all Vulnerabilities without a finding_id
44017:15:04# [RSpecRunTime] RSpec elapsed time: 17 minutes 8.89 seconds. Current RSS: ~1270M. Threads: 1. load average: 1.16 1.10 1.14 1/279 393
44117:15:04.
44217:15:07INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
44317:15:07INFO: analyzing "public.ci_job_artifacts"
44417:15:07INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
44517:15:07I, [2024-10-18T17:15:07.425106 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
44617:15:07I, [2024-10-18T17:15:07.427103 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
44717:15:07I, [2024-10-18T17:15:07.428269 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
44817:15:11INFO: analyzing "public.p_ci_stages" inheritance tree
44917:15:11INFO: analyzing "public.ci_stages"
45017:15:11INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
45117:15:11I, [2024-10-18T17:15:11.093727 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
45217:15:11I, [2024-10-18T17:15:11.094985 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
45317:15:11I, [2024-10-18T17:15:11.095880 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
45417:15:12I, [2024-10-18T17:15:12.401800 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
45517:15:19I, [2024-10-18T17:15:19.370733 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
45617:15:34I, [2024-10-18T17:15:34.559882 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
45717:15:34I, [2024-10-18T17:15:34.561174 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
45817:15:34I, [2024-10-18T17:15:34.562080 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
45917:15:47I, [2024-10-18T17:15:47.393451 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
46017:15:54I, [2024-10-18T17:15:54.921583 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
46117:16:03I, [2024-10-18T17:16:03.553010 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
46217:16:06I, [2024-10-18T17:16:06.379672 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
46317:16:10INFO: analyzing "public.p_ci_pipelines" inheritance tree
46417:16:10INFO: analyzing "public.ci_pipelines"
46517:16:10INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
46617:16:10I, [2024-10-18T17:16:10.413288 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
46717:16:10I, [2024-10-18T17:16:10.414525 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
46817:16:10I, [2024-10-18T17:16:10.415424 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
46917:16:16I, [2024-10-18T17:16:16.119040 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
47017:16:36# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/drop_vulnerabilities_without_finding_id_spec.rb. It took 3 minutes 0.93 second. Expected to take 1 minute 24.62 seconds.
47117:16:36# [RSpecRunTime] RSpec elapsed time: 18 minutes 40.99 seconds. Current RSS: ~1235M. Threads: 1. load average: 1.15 1.09 1.12 1/282 394
47217:16:36.
47317:16:36# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb. Expected to take 1 minute 20.02 seconds.
47417:16:36Gitlab::BackgroundMigration::BackfillWorkItemParentLinksNamespaceId
47517:17:50 constructs a valid query
47617:17:58 performs without error
47717:17:59I, [2024-10-18T17:17:59.743584 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
47817:18:15I, [2024-10-18T17:18:15.352555 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
47917:18:15I, [2024-10-18T17:18:15.353951 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
48017:18:15I, [2024-10-18T17:18:15.354784 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
48117:18:27I, [2024-10-18T17:18:27.983792 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
48217:18:35I, [2024-10-18T17:18:35.260907 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
48317:18:43I, [2024-10-18T17:18:43.790689 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
48417:18:46I, [2024-10-18T17:18:46.460748 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
48517:18:50INFO: analyzing "public.p_ci_pipelines" inheritance tree
48617:18:50INFO: analyzing "public.ci_pipelines"
48717:18:50INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
48817:18:50I, [2024-10-18T17:18:50.482853 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
48917:18:50I, [2024-10-18T17:18:50.484154 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
49017:18:50I, [2024-10-18T17:18:50.485010 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
49117:18:56I, [2024-10-18T17:18:56.370594 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
49217:19:16# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb. It took 2 minutes 40.19 seconds. Expected to take 1 minute 20.02 seconds.
49317:19:17# [RSpecRunTime] RSpec elapsed time: 21 minutes 21.44 seconds. Current RSS: ~1234M. Threads: 1. load average: 1.21 1.16 1.15 1/282 395
49417:19:17.
49517:19:17# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_terraform_state_versions_project_id_spec.rb. Expected to take 1 minute 2.61 seconds.
49617:19:17Gitlab::BackgroundMigration::BackfillTerraformStateVersionsProjectId
49717:20:11 constructs a valid query
49817:20:18 performs without error
49917:20:30I, [2024-10-18T17:20:30.257746 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
50017:20:37I, [2024-10-18T17:20:37.517674 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
50117:20:45I, [2024-10-18T17:20:45.942908 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
50217:20:48I, [2024-10-18T17:20:48.710009 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
50317:20:52INFO: analyzing "public.p_ci_pipelines" inheritance tree
50417:20:52INFO: analyzing "public.ci_pipelines"
50517:20:52INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
50617:20:52I, [2024-10-18T17:20:52.832711 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
50717:20:52I, [2024-10-18T17:20:52.834265 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
50817:20:52I, [2024-10-18T17:20:52.835229 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
50917:20:58I, [2024-10-18T17:20:58.799927 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
51017:21:19# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_terraform_state_versions_project_id_spec.rb. It took 2 minutes 2.72 seconds. Expected to take 1 minute 2.61 seconds.
51117:21:19# [RSpecRunTime] RSpec elapsed time: 23 minutes 24.4 seconds. Current RSS: ~1234M. Threads: 1. load average: 1.21 1.16 1.14 1/280 396
51217:21:19.
51317:21:19# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_boards_epic_board_labels_group_id_spec.rb. Expected to take 1 minute 1.15 seconds.
51417:21:19Gitlab::BackgroundMigration::BackfillBoardsEpicBoardLabelsGroupId
51517:22:15 performs without error
51617:22:22 constructs a valid query
51717:22:23I, [2024-10-18T17:22:23.223310 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
51817:22:23I, [2024-10-18T17:22:23.224638 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
51917:22:23I, [2024-10-18T17:22:23.225513 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
52017:22:35I, [2024-10-18T17:22:35.281245 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
52117:22:42I, [2024-10-18T17:22:42.363109 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
52217:22:50I, [2024-10-18T17:22:50.304691 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
52317:22:52I, [2024-10-18T17:22:52.902581 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
52417:22:56INFO: analyzing "public.p_ci_pipelines" inheritance tree
52517:22:56INFO: analyzing "public.ci_pipelines"
52617:22:56INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
52717:22:56I, [2024-10-18T17:22:56.897368 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
52817:22:56I, [2024-10-18T17:22:56.898618 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
52917:22:56I, [2024-10-18T17:22:56.899454 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
53017:23:02I, [2024-10-18T17:23:02.731962 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
53117:23:22# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_boards_epic_board_labels_group_id_spec.rb. It took 2 minutes 2.77 seconds. Expected to take 1 minute 1.15 seconds.
53217:23:22# [RSpecRunTime] RSpec elapsed time: 25 minutes 27.22 seconds. Current RSS: ~1235M. Threads: 1. load average: 1.02 1.09 1.12 1/280 397
53317:23:22.
53417:23:22# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_draft_notes_project_id_spec.rb. Expected to take 1 minute 0.06 second.
53517:23:22Gitlab::BackgroundMigration::BackfillDraftNotesProjectId
53617:24:16 performs without error
53717:24:23 constructs a valid query
53817:24:35I, [2024-10-18T17:24:35.480159 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
53917:24:42I, [2024-10-18T17:24:42.516372 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
54017:24:50I, [2024-10-18T17:24:50.558700 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
54117:24:53I, [2024-10-18T17:24:53.243173 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
54217:24:57INFO: analyzing "public.p_ci_pipelines" inheritance tree
54317:24:57INFO: analyzing "public.ci_pipelines"
54417:24:57INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
54517:24:57I, [2024-10-18T17:24:57.312722 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
54617:24:57I, [2024-10-18T17:24:57.314190 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
54717:24:57I, [2024-10-18T17:24:57.315106 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
54817:25:03I, [2024-10-18T17:25:03.087498 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
54917:25:22# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_draft_notes_project_id_spec.rb. It took 2 minutes 0.1 second. Expected to take 1 minute 0.06 second.
55017:25:23# [RSpecRunTime] RSpec elapsed time: 27 minutes 27.65 seconds. Current RSS: ~1238M. Threads: 1. load average: 1.07 1.11 1.12 1/280 398
55117:25:23.
55217:25:23# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_archived_and_traversal_ids_to_vulnerability_reads_spec.rb. Expected to take 58.32 seconds.
55317:25:23Gitlab::BackgroundMigration::BackfillArchivedAndTraversalIdsToVulnerabilityReads
55417:26:19 backfills traversal_ids and archived
55517:26:21I, [2024-10-18T17:26:21.334414 #343] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
55617:26:21I, [2024-10-18T17:26:21.335639 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
55717:26:21I, [2024-10-18T17:26:21.336449 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
55817:26:33I, [2024-10-18T17:26:33.082837 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
55917:26:40I, [2024-10-18T17:26:40.044069 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
56017:26:48I, [2024-10-18T17:26:48.069979 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
56117:26:50I, [2024-10-18T17:26:50.566449 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
56217:26:54INFO: analyzing "public.p_ci_pipelines" inheritance tree
56317:26:54INFO: analyzing "public.ci_pipelines"
56417:26:54INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
56517:26:54I, [2024-10-18T17:26:54.615253 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
56617:26:54I, [2024-10-18T17:26:54.616470 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
56717:26:54I, [2024-10-18T17:26:54.617476 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
56817:27:00I, [2024-10-18T17:27:00.209418 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
56917:27:19# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_archived_and_traversal_ids_to_vulnerability_reads_spec.rb. It took 1 minute 56.52 seconds. Expected to take 58.32 seconds.
57017:27:19# [RSpecRunTime] RSpec elapsed time: 29 minutes 24.26 seconds. Current RSS: ~1238M. Threads: 1. load average: 1.00 1.12 1.12 1/284 399
57117:27:19.
57217:27:19# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ml_candidate_metadata_project_id_spec.rb. Expected to take 49.18 seconds.
57317:27:19Gitlab::BackgroundMigration::BackfillMlCandidateMetadataProjectId
57417:28:00 performs without error
57517:28:05 constructs a valid query
57617:28:06I, [2024-10-18T17:28:06.241940 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
57717:28:13I, [2024-10-18T17:28:13.063196 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
57817:28:20I, [2024-10-18T17:28:20.985481 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
57917:28:23I, [2024-10-18T17:28:23.617491 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
58017:28:27INFO: analyzing "public.p_ci_pipelines" inheritance tree
58117:28:27INFO: analyzing "public.ci_pipelines"
58217:28:27INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
58317:28:27I, [2024-10-18T17:28:27.525674 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
58417:28:27I, [2024-10-18T17:28:27.526925 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
58517:28:27I, [2024-10-18T17:28:27.527777 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
58617:28:33I, [2024-10-18T17:28:33.119257 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
58717:28:52# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ml_candidate_metadata_project_id_spec.rb. It took 1 minute 32.26 seconds. Expected to take 49.18 seconds.
58817:28:52# [RSpecRunTime] RSpec elapsed time: 30 minutes 56.75 seconds. Current RSS: ~1234M. Threads: 1. load average: 1.04 1.11 1.12 1/284 400
58917:28:52.
59017:28:52# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vulnerability_finding_signatures_project_id_spec.rb. Expected to take 48.21 seconds.
59117:28:52Gitlab::BackgroundMigration::BackfillVulnerabilityFindingSignaturesProjectId
59217:29:32 constructs a valid query
59317:29:38 performs without error
59417:29:39I, [2024-10-18T17:29:39.382917 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
59517:29:46I, [2024-10-18T17:29:46.107879 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
59617:29:54I, [2024-10-18T17:29:54.097874 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
59717:29:56I, [2024-10-18T17:29:56.665905 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
59817:30:00INFO: analyzing "public.p_ci_pipelines" inheritance tree
59917:30:00INFO: analyzing "public.ci_pipelines"
60017:30:00INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
60117:30:00I, [2024-10-18T17:30:00.675859 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
60217:30:00I, [2024-10-18T17:30:00.677266 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
60317:30:00I, [2024-10-18T17:30:00.678176 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
60417:30:06I, [2024-10-18T17:30:06.262824 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
60517:30:25# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vulnerability_finding_signatures_project_id_spec.rb. It took 1 minute 33.5 seconds. Expected to take 48.21 seconds.
60617:30:25# [RSpecRunTime] RSpec elapsed time: 32 minutes 30.3 seconds. Current RSS: ~1239M. Threads: 1. load average: 0.88 1.06 1.10 1/284 401
60717:30:25.
60817:30:25# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_approval_group_rules_users_group_id_spec.rb. Expected to take 46.82 seconds.
60917:30:25Gitlab::BackgroundMigration::BackfillApprovalGroupRulesUsersGroupId
61017:31:06 performs without error
61117:31:11 constructs a valid query
61217:31:18I, [2024-10-18T17:31:18.788059 #343] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
61317:31:28I, [2024-10-18T17:31:28.016657 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
61417:31:30I, [2024-10-18T17:31:30.637463 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
61517:31:34INFO: analyzing "public.p_ci_pipelines" inheritance tree
61617:31:34INFO: analyzing "public.ci_pipelines"
61717:31:34INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
61817:31:34I, [2024-10-18T17:31:34.691346 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
61917:31:34I, [2024-10-18T17:31:34.692827 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
62017:31:34I, [2024-10-18T17:31:34.693973 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
62117:31:40I, [2024-10-18T17:31:40.534348 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
62217:31:59# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_approval_group_rules_users_group_id_spec.rb. It took 1 minute 34.11 seconds. Expected to take 46.82 seconds.
62317:31:59# [RSpecRunTime] RSpec elapsed time: 34 minutes 4.45 seconds. Current RSS: ~1239M. Threads: 1. load average: 1.08 1.07 1.09 1/280 402
62417:31:59.
62517:31:59# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_merge_request_user_mentions_project_id_spec.rb. Expected to take 39.17 seconds.
62617:31:59Gitlab::BackgroundMigration::BackfillMergeRequestUserMentionsProjectId
62717:32:33 constructs a valid query
62817:32:38 performs without error
62917:32:38The application_settings (main) table has 1207 columns.
63017:32:38Recreating the database
63117:32:39Dropped database 'gitlabhq_test'
63217:32:40Dropped database 'gitlabhq_test_ci'
63317:32:40Created database 'gitlabhq_test'
63417:32:40Created database 'gitlabhq_test_ci'
63517:32:52main: == [advisory_lock_connection] object_id: 390556300, pg_backend_pid: 359
63617:32:53main: == [advisory_lock_connection] object_id: 390556300, pg_backend_pid: 359
63717:32:53ci: == [advisory_lock_connection] object_id: 390738380, pg_backend_pid: 361
63817:32:53ci: == [advisory_lock_connection] object_id: 390738380, pg_backend_pid: 361
63917:32:59Databases re-creation done in 20.823535628000172
64017:32:59# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_merge_request_user_mentions_project_id_spec.rb. It took 59.68 seconds. Expected to take 39.17 seconds.
64117:32:59# [RSpecRunTime] RSpec elapsed time: 35 minutes 4.18 seconds. Current RSS: ~1222M. Threads: 1. load average: 1.15 1.10 1.10 1/279 405
64217:32:59.
64317:32:59# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_error_tracking_error_events_project_id_spec.rb. Expected to take 36.62 seconds.
64417:32:59Gitlab::BackgroundMigration::BackfillErrorTrackingErrorEventsProjectId
64517:33:28 constructs a valid query
64617:33:32 performs without error
64717:33:39I, [2024-10-18T17:33:39.178980 #343] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
64817:33:41I, [2024-10-18T17:33:41.768712 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
64917:33:45INFO: analyzing "public.p_ci_pipelines" inheritance tree
65017:33:45INFO: analyzing "public.ci_pipelines"
65117:33:45INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
65217:33:45I, [2024-10-18T17:33:45.369630 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
65317:33:45I, [2024-10-18T17:33:45.370986 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
65417:33:45I, [2024-10-18T17:33:45.371905 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
65517:33:50I, [2024-10-18T17:33:50.244345 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
65617:34:08# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_error_tracking_error_events_project_id_spec.rb. It took 1 minute 9.3 seconds. Expected to take 36.62 seconds.
65717:34:09# [RSpecRunTime] RSpec elapsed time: 36 minutes 13.53 seconds. Current RSS: ~1238M. Threads: 1. load average: 0.96 1.06 1.08 1/279 406
65817:34:09.
65917:34:09# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ci_pipeline_schedule_variables_project_id_spec.rb. Expected to take 30.75 seconds.
66017:34:09Gitlab::BackgroundMigration::BackfillCiPipelineScheduleVariablesProjectId
66117:34:31 performs without error
66217:34:35 constructs a valid query
66317:35:03# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ci_pipeline_schedule_variables_project_id_spec.rb. It took 54.58 seconds. Expected to take 30.75 seconds.
66417:35:03# [RSpecRunTime] RSpec elapsed time: 37 minutes 8.38 seconds. Current RSS: ~1234M. Threads: 1. load average: 1.07 1.07 1.08 1/279 407
66517:35:03.
66617:35:03# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ml_candidate_metrics_project_id_spec.rb. Expected to take 26.32 seconds.
66717:35:03Gitlab::BackgroundMigration::BackfillMlCandidateMetricsProjectId
66817:35:24 constructs a valid query
66917:35:27 performs without error
67017:35:28I, [2024-10-18T17:35:28.572063 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
67117:35:32INFO: analyzing "public.p_ci_pipelines" inheritance tree
67217:35:32INFO: analyzing "public.ci_pipelines"
67317:35:32INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
67417:35:33I, [2024-10-18T17:35:33.157152 #343] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
67517:35:33I, [2024-10-18T17:35:33.158423 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
67617:35:33I, [2024-10-18T17:35:33.159271 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
67717:35:38I, [2024-10-18T17:35:38.185461 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
67817:35:56# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ml_candidate_metrics_project_id_spec.rb. It took 52.9 seconds. Expected to take 26.32 seconds.
67917:35:56# [RSpecRunTime] RSpec elapsed time: 38 minutes 1.33 seconds. Current RSS: ~1237M. Threads: 1. load average: 1.18 1.10 1.09 1/279 408
68017:35:56.
68117:35:56# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/remove_namespace_from_os_type_sbom_components_spec.rb. Expected to take 22.4 seconds.
68217:35:56Gitlab::BackgroundMigration::RemoveNamespaceFromOsTypeSbomComponents
68317:36:05 #perform
68417:36:08 successfully removes the os namespace prefix
68517:36:08 with existing record in regards to name, purl_type and component_type
68617:36:12 rescues valid ActiveRecord::RecordNotUnique errors
68717:36:12# [RSpecRunTime] RSpec elapsed time: 38 minutes 17.26 seconds. Current RSS: ~1234M. Threads: 1. load average: 1.06 1.08 1.09 1/279 409
68817:36:12.
68917:36:12 with unexpected ActiveRecord::RecordNotUnique error
69017:36:16 raises unknown ActiveRecord::RecordNotUnique errors
69117:36:16# [RSpecRunTime] RSpec elapsed time: 38 minutes 21.06 seconds. Current RSS: ~1227M. Threads: 1. load average: 0.97 1.06 1.08 1/279 410
69217:36:16.
69317:36:17I, [2024-10-18T17:36:17.529371 #343] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
69417:36:35# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/remove_namespace_from_os_type_sbom_components_spec.rb. It took 39.09 seconds. Expected to take 22.4 seconds.
69517:36:35# [RSpecRunTime] RSpec elapsed time: 38 minutes 40.57 seconds. Current RSS: ~1234M. Threads: 1. load average: 0.98 1.05 1.08 1/279 411
69617:36:35.
69717:36:35# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_rpm_metadata_project_id_spec.rb. Expected to take 15.96 seconds.
69817:36:35Gitlab::BackgroundMigration::BackfillPackagesRpmMetadataProjectId
69917:36:46 constructs a valid query
70017:36:49 performs without error
70117:37:06# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_rpm_metadata_project_id_spec.rb. It took 30.83 seconds. Expected to take 15.96 seconds.
70217:37:06# [RSpecRunTime] RSpec elapsed time: 39 minutes 11.49 seconds. Current RSS: ~1235M. Threads: 1. load average: 0.93 1.03 1.07 1/279 412
70317:37:06.
70417:37:06# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_pypi_metadata_project_id_spec.rb. Expected to take 14.59 seconds.
70517:37:06Gitlab::BackgroundMigration::BackfillPackagesPypiMetadataProjectId
70617:37:16 constructs a valid query
70717:37:20 performs without error
70817:37:37# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_pypi_metadata_project_id_spec.rb. It took 30.12 seconds. Expected to take 14.59 seconds.
70917:37:37# [RSpecRunTime] RSpec elapsed time: 39 minutes 41.79 seconds. Current RSS: ~1240M. Threads: 1. load average: 1.07 1.06 1.08 1/279 413
71017:37:37.
71117:37:37# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_new_audit_event_tables_spec.rb. Expected to take 9.13 seconds.
71217:37:37Gitlab::BackgroundMigration::BackfillNewAuditEventTables
71317:37:41 correctly migrates audit events into new tables
71417:37:41 when audit events are already present
71517:37:45 does not duplicate records
71617:37:45# [RSpecRunTime] RSpec elapsed time: 39 minutes 49.78 seconds. Current RSS: ~1246M. Threads: 1. load average: 1.06 1.06 1.08 1/279 414
71717:37:45.
71817:37:53# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_new_audit_event_tables_spec.rb. It took 15.67 seconds. Expected to take 9.13 seconds.
71917:37:53# [RSpecRunTime] RSpec elapsed time: 39 minutes 57.77 seconds. Current RSS: ~1238M. Threads: 1. load average: 1.06 1.06 1.08 1/279 415
72017:37:53.
72117:37:53# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/deduplicate_lfs_objects_projects_spec.rb. Expected to take 9.07 seconds.
72217:37:53Gitlab::BackgroundMigration::DeduplicateLfsObjectsProjects
72317:37:53 #perform
72417:37:53 with duplicates
72517:37:57 deduplicates lfs_objects_projects by lfs_object_id, repository_type and project_id
72617:37:57# [RSpecRunTime] RSpec elapsed time: 40 minutes 2.45 seconds. Current RSS: ~1240M. Threads: 1. load average: 1.05 1.05 1.08 1/279 416
72717:37:57.
72817:37:57 without duplicates
72917:38:01 does not delete any records
73017:38:01# [RSpecRunTime] RSpec elapsed time: 40 minutes 6.31 seconds. Current RSS: ~1241M. Threads: 1. load average: 1.05 1.05 1.08 1/279 417
73117:38:01.
73217:38:09# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/deduplicate_lfs_objects_projects_spec.rb. It took 16.41 seconds. Expected to take 9.07 seconds.
73317:38:09# [RSpecRunTime] RSpec elapsed time: 40 minutes 14.27 seconds. Current RSS: ~1236M. Threads: 1. load average: 0.96 1.03 1.07 1/279 418
73417:38:09.
73517:38:09# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb. Expected to take 5.8 seconds.
73617:38:09Gitlab::BackgroundMigration::RerunEpicDatesToWorkItemDatesSourcesSync
73717:38:10 when backfilling all epics
73817:38:14 backfills data correctly
73917:38:14# [RSpecRunTime] RSpec elapsed time: 40 minutes 19.01 seconds. Current RSS: ~1254M. Threads: 1. load average: 0.96 1.03 1.07 1/279 419
74017:38:14.
74117:38:22# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb. It took 12.59 seconds. Expected to take 5.8 seconds.
74217:38:22# [RSpecRunTime] RSpec elapsed time: 40 minutes 26.99 seconds. Current RSS: ~1240M. Threads: 1. load average: 0.97 1.03 1.07 1/279 420
74317:38:22.
74417:38:22# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/fix_namespace_id_for_work_item_parent_links_spec.rb. Expected to take 4.88 seconds.
74517:38:22Gitlab::BackgroundMigration::FixNamespaceIdForWorkItemParentLinks
74617:38:22 #perform
74717:38:26 fixes the namespace_id for work item parent links
74817:38:26# [RSpecRunTime] RSpec elapsed time: 40 minutes 31.3 seconds. Current RSS: ~1249M. Threads: 1. load average: 0.97 1.03 1.07 1/279 421
74917:38:26.
75017:38:34# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/fix_namespace_id_for_work_item_parent_links_spec.rb. It took 12.21 seconds. Expected to take 4.88 seconds.
75117:38:34# [RSpecRunTime] RSpec elapsed time: 40 minutes 39.3 seconds. Current RSS: ~1245M. Threads: 1. load average: 1.05 1.05 1.07 1/279 422
75217:38:34.
75317:38:34# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_issues_dates_with_work_item_dates_sources_spec.rb. Expected to take 4.61 seconds.
75417:38:34Gitlab::BackgroundMigration::BackfillIssuesDatesWithWorkItemDatesSources
75517:38:38 backfills data correctly
75617:38:46# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_issues_dates_with_work_item_dates_sources_spec.rb. It took 12.04 seconds. Expected to take 4.61 seconds.
75717:38:46# [RSpecRunTime] RSpec elapsed time: 40 minutes 51.45 seconds. Current RSS: ~1244M. Threads: 1. load average: 1.05 1.05 1.07 1/279 423
75817:38:46.
75917:38:46# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/base_job_spec.rb. Expected to take 4.23 seconds.
76017:38:46Gitlab::BackgroundMigration::BaseJob#perform
76117:38:47 #perform
76217:38:50 raises an error if not overridden by a subclass
76317:38:50# [RSpecRunTime] RSpec elapsed time: 40 minutes 55.44 seconds. Current RSS: ~1251M. Threads: 1. load average: 1.05 1.04 1.07 1/279 424
76417:38:50.
76517:38:58# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/base_job_spec.rb. It took 11.89 seconds. Expected to take 4.23 seconds.
76617:38:58# [RSpecRunTime] RSpec elapsed time: 41 minutes 3.46 seconds. Current RSS: ~1245M. Threads: 1. load average: 1.04 1.04 1.07 1/279 425
76717:38:58.
76817:39:44auto_explain log contains 1131 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-7-10.343.main.ndjson.gz
76917:39:44took 45.753812244
77017:40:28auto_explain log contains 1131 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-7-10.343.ci.ndjson.gz
77117:40:28took 43.336809874
77217:40:28[TEST PROF INFO] EventProf results for sql.active_record
77317:40:28Total time: 26:33.617 of 40:56.140 (64.88%)
77417:40:28Total events: 1139010
77517:40:28Top 5 slowest suites (by time):
77617:40:28Gitlab::Backg...dSharedRunners (./spec/lib/gitlab/background_migration/fix_allow_descendants_override_disabled_shared_runners_spec.rb:5) – 02:44.412 (109406 / 1) of 03:59.163 (68.74%)
77717:40:28Gitlab::Backg...illTopicsTitle (./spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb:5) – 02:35.424 (109394 / 1) of 03:47.591 (68.29%)
77817:40:28Gitlab::Backg...slVerification (./spec/lib/gitlab/background_migration/backfill_integrations_enable_ssl_verification_spec.rb:5) – 02:34.409 (114358 / 3) of 04:13.083 (61.01%)
77917:40:28Gitlab::Backg...shAccessLevels (./spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb:5) – 02:24.730 (91537 / 1) of 03:32.864 (67.99%)
78017:40:28Gitlab::Backg...thoutFindingId (./spec/lib/gitlab/background_migration/drop_vulnerabilities_without_finding_id_spec.rb:5) – 02:04.892 (85672 / 1) of 03:01.181 (68.93%)
78117:40:28Knapsack report was generated. Preview:
78217:40:28{
78317:40:28 "spec/lib/gitlab/background_migration/backfill_integrations_enable_ssl_verification_spec.rb": 142.2156806740004,
78417:40:28 "spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb": 112.468182953,
78517:40:28 "spec/lib/gitlab/background_migration/fix_allow_descendants_override_disabled_shared_runners_spec.rb": 114.28908843200043,
78617:40:28 "spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb": 105.51182293500096,
78717:40:28 "spec/lib/gitlab/background_migration/drop_vulnerabilities_without_finding_id_spec.rb": 88.89772448299846,
78817:40:28 "spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb": 82.18410327899983,
78917:40:28 "spec/lib/gitlab/background_migration/backfill_terraform_state_versions_project_id_spec.rb": 61.00786380499994,
79017:40:28 "spec/lib/gitlab/background_migration/backfill_boards_epic_board_labels_group_id_spec.rb": 62.304251396000836,
79117:40:28 "spec/lib/gitlab/background_migration/backfill_draft_notes_project_id_spec.rb": 60.70702917100061,
79217:40:28 "spec/lib/gitlab/background_migration/backfill_archived_and_traversal_ids_to_vulnerability_reads_spec.rb": 56.187748160999035,
79317:40:28 "spec/lib/gitlab/background_migration/backfill_ml_candidate_metadata_project_id_spec.rb": 45.73399859299934,
79417:40:28 "spec/lib/gitlab/background_migration/backfill_vulnerability_finding_signatures_project_id_spec.rb": 46.22521078200043,
79517:40:28 "spec/lib/gitlab/background_migration/backfill_approval_group_rules_users_group_id_spec.rb": 45.935031310998966,
79617:40:28 "spec/lib/gitlab/background_migration/backfill_merge_request_user_mentions_project_id_spec.rb": 38.76317264200043,
79717:40:28 "spec/lib/gitlab/background_migration/backfill_error_tracking_error_events_project_id_spec.rb": 32.86895935299981,
79817:40:28 "spec/lib/gitlab/background_migration/backfill_ci_pipeline_schedule_variables_project_id_spec.rb": 26.377864905000024,
79917:40:28 "spec/lib/gitlab/background_migration/backfill_ml_candidate_metrics_project_id_spec.rb": 24.17128535700067,
80017:40:28 "spec/lib/gitlab/background_migration/remove_namespace_from_os_type_sbom_components_spec.rb": 19.655503008001688,
80117:40:28 "spec/lib/gitlab/background_migration/backfill_packages_rpm_metadata_project_id_spec.rb": 13.604131330999735,
80217:40:28 "spec/lib/gitlab/background_migration/backfill_packages_pypi_metadata_project_id_spec.rb": 13.181091988000844,
80317:40:28 "spec/lib/gitlab/background_migration/backfill_new_audit_event_tables_spec.rb": 7.7417646999983845,
80417:40:28 "spec/lib/gitlab/background_migration/deduplicate_lfs_objects_projects_spec.rb": 8.517717101998642,
80517:40:28 "spec/lib/gitlab/background_migration/rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb": 4.672745245999977,
80617:40:28 "spec/lib/gitlab/background_migration/fix_namespace_id_for_work_item_parent_links_spec.rb": 4.270864769998298,
80717:40:28 "spec/lib/gitlab/background_migration/backfill_issues_dates_with_work_item_dates_sources_spec.rb": 4.24858303200017,
80817:40:28 "spec/lib/gitlab/background_migration/base_job_spec.rb": 3.941465279000113
80917:40:28}
81017:40:28Knapsack global time execution for tests: 20m 25s
81117:40:28Finished in 42 minutes 33 seconds (files took 53.63 seconds to load)
81217:40:2845 examples, 0 failures
81317:40:28Randomized with seed 52598
81417:40:38[TEST PROF INFO] Time spent in factories: 00:00.447 (0.02% of total time)
81517:40:39RSpec exited with 0.
81617:40:39No examples to retry, congrats!
817 17:40:39 Running after_script 00:23
81817:40:40Running after script...
81917:40:40$ source scripts/utils.sh
82017:40:40$ log_disk_usage
82117:40:40*******************************************************
82217:40:40This runner currently has 71G free disk space.
82317:40:40*******************************************************
824 17:40:40 Disk usage detail 00:13
88017:40:53$ bundle exec gem list gitlab_quality-test_tooling
88117:40:57gitlab_quality-test_tooling (2.0.0)
88217:40:57$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
883 17:40:57 Report test failures 00:00
88517:40:57$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
886 17:40:57 Report test flakiness 00:00
88817:40:57$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
889 17:40:57 Report test slowness 00:00
89117:40:57$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
892 17:40:57 Report test slowness in MR note 00:02
89517:40:59$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
896 17:40:59 Report test files close to timing out 00:02
89917:41:01$ tooling/bin/push_job_metrics || true
90017:41:02[job-metrics] Pushing job metrics file for the CI/CD job.
90117:41:02[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
902 17:41:02 Saving cache for successful job 00:01
90317:41:03Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19 due to policy
904 17:41:03 Uploading artifacts for successful job 00:08
90517:41:06Uploading artifacts...
90617:41:06auto_explain/: found 3 matching artifact files and directories
90717:41:06coverage/: found 5 matching artifact files and directories
90817:41:06crystalball/: found 2 matching artifact files and directories
90917:41:06WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
91017:41:06knapsack/: found 4 matching artifact files and directories
91117:41:06rspec/: found 16 matching artifact files and directories
91217:41:06WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
91317:41:06log/*.log: found 13 matching artifact files and directories
91417:41:06WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8126558229/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
91517:41:06WARNING: Retrying... context=artifacts-uploader error=request redirected
91617:41:09Uploading artifacts as "archive" to coordinator... 201 Created id=8126558229 responseStatus=201 Created token=glcbt-66
91717:41:09Uploading artifacts...
91817:41:09rspec/rspec-*.xml: found 1 matching artifact files and directories
91917:41:09WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8126558229/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
92017:41:09WARNING: Retrying... context=artifacts-uploader error=request redirected
92117:41:11Uploading artifacts as "junit" to coordinator... 201 Created id=8126558229 responseStatus=201 Created token=glcbt-66
922 17:41:11 Cleaning up project directory and file based variables 00:00
92317:41:20Job succeeded