Skip to content
Snippets Groups Projects

rspec background_migration pg14 10/10

Passed Started by
🤖 GitLab Bot 🤖
@gitlab-bot
Log timestamps in UTC.
122:01:27Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
222:01:27 on green-2.private.runners-manager.gitlab.com/gitlab.com/gitlab-org GaSD-S1F, system ID: s_5651e5b5643b
322:01:27 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:01:27Resolving secrets
5 22:01:27 Preparing the "docker+machine" executor 01:30
622:01:27Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.4-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
722:01:31Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
822:01:31Authenticating with credentials from job payload (GitLab Registry)
922:01:31Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:01:39Using 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:01:43WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:01:43WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:01:43Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1422:01:43Authenticating with credentials from job payload (GitLab Registry)
1522:01:43Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:01:46Using 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:01:48Starting service redis:6.2-alpine ...
1822:01:48Pulling docker image redis:6.2-alpine ...
1922:01:50Using docker image sha256:36c1d3cfe18f4b12d4640576f7048068acbfc3625027b6bbb46bba0e31b11831 for redis:6.2-alpine with digest redis@sha256:e3b17ba9479deec4b7d1eeec1548a253acc5374d68d3b27937fcfe4df8d18c7e ...
2022:01:51Waiting for services to be up and running (timeout 30 seconds)...
2122:01:52Authenticating with credentials from job payload (GitLab Registry)
2222:01:52Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.4-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
2322:02:57Using docker image sha256:6a195ce35d9229dab25717dc9993ad0219581614348979c7e06c0f599f4bbe9f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.4-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-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.4-golang-1.22-rust-1.73-node-20.12-postgresql-14@sha256:2299c13e2bfd9f337be825f8493cdd17ae90305d748f5299f1e4a1eeffcec325 ...
24 22:02:57 Preparing environment 00:00
2522:02:57Running on runner-gasd-s1f-project-278964-concurrent-0 via runner-gasd-s1f-private-1721944824-c458b2ac...
26 22:02:57 Getting source from Git repository 00:01
2722:02:58Skipping Git repository setup
2822:02:58Skipping Git checkout
2922:02:58Skipping Git submodules setup
30 22:02:58 Restoring cache 00:16
3122:02:59Checking cache for ruby-gems-debian-bookworm-ruby-3.2.4-17...
3222:02:59Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.4-17
3322:03:10Successfully extracted cache
34 22:03:14 Downloading artifacts 00:41
3522:03:15Downloading artifacts for retrieve-tests-metadata (7432319060)...
3622:03:16Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319060 responseStatus=200 OK token=glcbt-66
3722:03:16Downloading artifacts for clone-gitlab-repo (7432319031)...
3822:03:20Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319031 responseStatus=200 OK token=glcbt-66
3922:03:20WARNING: Part of .git directory is on the list of files to extract
4022:03:20WARNING: This may introduce unexpected problems
4122:03:32Downloading artifacts for compile-test-assets (7432319052)...
4222:03:35Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319052 responseStatus=200 OK token=glcbt-66
4322:03:41Downloading artifacts for setup-test-env (7432319054)...
4422:03:44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319054 responseStatus=200 OK token=glcbt-66
4522:03:49Downloading artifacts for detect-tests (7432319057)...
4622:03:49Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319057 responseStatus=200 OK token=glcbt-66
47 22:03:55 Executing "step_script" stage of the job script
4822:03:55Using docker image sha256:6a195ce35d9229dab25717dc9993ad0219581614348979c7e06c0f599f4bbe9f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.4-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-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.4-golang-1.22-rust-1.73-node-20.12-postgresql-14@sha256:2299c13e2bfd9f337be825f8493cdd17ae90305d748f5299f1e4a1eeffcec325 ...
4922:03:56$ echo $FOSS_ONLY
5022:03:56$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5122:03:56$ export GOPATH=$CI_PROJECT_DIR/.go
5222:03:56$ mkdir -p $GOPATH
5322:03:56$ source scripts/utils.sh
5422:03:56$ source scripts/prepare_build.sh
55 22:03:56 Installing gems 00:23
8422:04:19Using decomposed database config (config/database.yml.decomposed-postgresql)
8522:04:19Geo DB won't be set up.
8622:04:19Embedding DB won't be set up.
87 22:04:19 Setting up DBs 01:27
9922:05:46$ source ./scripts/rspec_helpers.sh
10022:05:46$ run_timed_command "gem install knapsack --no-document"
10122:05:46$ gem install knapsack --no-document
10222:05:47Successfully installed knapsack-4.0.0
10322:05:471 gem installed
10422:05:46==> 'gem install knapsack --no-document' succeeded in 1 seconds.
10522:05:47$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
106 22:05:47 Spawning Gitaly 00:04
11022:05:51$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
11122:05:51$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
11222:05:51$ tooling/bin/create_job_metrics_file || true
11322:05:54[job-metrics] Creating the job metrics file for the CI/CD job.
11422:05:54$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
115 22:05:54 RSpec 32:24
11622:05:54[22:05:54] Starting rspec_parallelized_job
11722:05:47RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
11822:05:54KNAPSACK_GENERATE_REPORT: true
11922:05:54FLAKY_RSPEC_GENERATE_REPORT: true
12022:05:54KNAPSACK_TEST_FILE_PATTERN: spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
12122:05:54KNAPSACK_LOG_LEVEL: debug
12222:05:54KNAPSACK_REPORT_PATH: knapsack/rspec_background_migration_pg14_10_10_278964_report.json
12322:05:54FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
12422:05:54FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_background_migration_pg14_10_10_278964_report.json
12522:05:54NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_background_migration_pg14_10_10_278964_report.json
12622:05:54RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7432319169.txt
12722:05:54CRYSTALBALL:
12822:05:54RSPEC_TESTS_MAPPING_ENABLED:
12922:05:54RSPEC_TESTS_FILTER_FILE:
13022:05:54Shell set options (set -o) enabled:
13122:05:54braceexpand on
13222:05:55hashall on
13322:05:55interactive-comments on
13422:05:55pipefail on
13522:06:01Knapsack report generator started!
13622:05:55/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!
13722:07:25/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!
13822:08:05Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
13922:08:05# [RSpecRunTime] Starting RSpec timer...
14022:08:05[TEST PROF INFO] EventProf enabled (sql.active_record)
14122:08:05unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
14222:08:05unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
14322:08:11Test environment set up in 2.57115294 seconds
14422:08:11# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_missing_ci_cd_settings_spec.rb. Expected to take 2 minutes 5.33 seconds.
14522:08:11Gitlab::BackgroundMigration::BackfillMissingCiCdSettings
14622:09:46 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
14722:10:01 creates ci_cd_settings for projects without ci_cd_settings
14822:10:14 creates ci_cd_settings with default values
14922:10:33INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
15022:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
15122:10:33INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
15222:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
15322:10:33INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
15422:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
15522:10:33INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
15622:10:33INFO: analyzing "public.p_ci_job_annotations" inheritance tree
15722:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
15822:10:33INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
15922:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
16022:10:33INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
16122:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
16222:10:33INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
16322:10:33INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
16422:10:33INFO: analyzing "public.ci_builds_metadata"
16522:10:33INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
16622:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
16722:10:33INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
16822:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
16922:10:33INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
17022:10:33INFO: analyzing "public.p_ci_builds" inheritance tree
17122:10:33INFO: analyzing "public.ci_builds"
17222:10:33INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
17322:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
17422:10:33INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
17522:10:33INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
17622:10:33INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
17722:10:34I, [2024-07-25T22:10:34.671105 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
17822:10:57I, [2024-07-25T22:10:57.250213 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
17922:10:58INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
18022:10:58INFO: analyzing "public.ci_pipeline_variables"
18122:10:58INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
18222:10:58I, [2024-07-25T22:10:58.936555 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
18322:10:58I, [2024-07-25T22:10:58.937980 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
18422:10:58I, [2024-07-25T22:10:58.939967 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
18522:11:01INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
18622:11:01INFO: analyzing "public.ci_pipeline_variables"
18722:11: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
18822:11:05INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
18922:11:05INFO: analyzing "public.ci_job_artifacts"
19022:11: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
19122:11:05I, [2024-07-25T22:11:05.738613 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
19222:11:05I, [2024-07-25T22:11:05.740101 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
19322:11:05I, [2024-07-25T22:11:05.741104 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
19422:11:09INFO: analyzing "public.p_ci_stages" inheritance tree
19522:11:09INFO: analyzing "public.ci_stages"
19622:11:09INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
19722:11:09I, [2024-07-25T22:11:09.614231 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
19822:11:09I, [2024-07-25T22:11:09.615621 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
19922:11:09I, [2024-07-25T22:11:09.616600 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
20022:11:11I, [2024-07-25T22:11:11.048523 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
20122:11:18I, [2024-07-25T22:11:18.287867 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
20222:11:34I, [2024-07-25T22:11:34.453417 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
20322:11:34I, [2024-07-25T22:11:34.454779 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
20422:11:34I, [2024-07-25T22:11:34.455617 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
20522:11:47I, [2024-07-25T22:11:47.768986 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
20622:11:55I, [2024-07-25T22:11:55.624513 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
20722:12:07# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_missing_ci_cd_settings_spec.rb. It took 3 minutes 55.36 seconds. Expected to take 2 minutes 5.33 seconds.
20822:12:07# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb. Expected to take 1 minute 58.97 seconds.
20922:12:07Gitlab::BackgroundMigration::BackfillVulnerabilityReadsClusterAgent
21022:13:41 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
21122:13:41 #filter_batch
21222:13:54 pick only vulnerability reads where report_type = 7
21322:13:55# [RSpecRunTime] RSpec elapsed time: 5 minutes 49.28 seconds. Current RSS: ~1228M. Threads: 2. load average: 1.03 1.10 0.83 1/261 428
21422:13:55.
21522:13:55 #perform
21622:14:09 backfills `casted_cluster_agent_id` for the selected records
21722:14:22 tracks timings of queries
21822:14:23# [RSpecRunTime] RSpec elapsed time: 6 minutes 17.4 seconds. Current RSS: ~1196M. Threads: 2. load average: 1.02 1.09 0.84 1/262 429
21922:14:23.
22022:14:41INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
22122:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
22222:14:41INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
22322:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
22422:14:41INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
22522:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
22622:14:41INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
22722:14:41INFO: analyzing "public.p_ci_job_annotations" inheritance tree
22822:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
22922:14:41INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
23022:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
23122:14:41INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
23222:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
23322:14:41INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
23422:14:41INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
23522:14:41INFO: analyzing "public.ci_builds_metadata"
23622:14:41INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
23722:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
23822:14:41INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
23922:14:41INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
24022:14:41INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
24122:14:42INFO: analyzing "public.p_ci_builds" inheritance tree
24222:14:42INFO: analyzing "public.ci_builds"
24322:14:42INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
24422:14:42INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
24522:14:42INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
24622:14:42INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
24722:14:42INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
24822:14:42I, [2024-07-25T22:14:42.755892 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
24922:15:05I, [2024-07-25T22:15:05.796909 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
25022:15:07INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
25122:15:07INFO: analyzing "public.ci_pipeline_variables"
25222:15:07INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
25322:15:07I, [2024-07-25T22:15:07.881829 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
25422:15:07I, [2024-07-25T22:15:07.883763 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25522:15:07I, [2024-07-25T22:15:07.884912 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25622:15:10INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
25722:15:10INFO: analyzing "public.ci_pipeline_variables"
25822:15:10INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
25922:15:15INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
26022:15:15INFO: analyzing "public.ci_job_artifacts"
26122:15:15INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26222:15:15I, [2024-07-25T22:15:15.094166 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
26322:15:15I, [2024-07-25T22:15:15.096619 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26422:15:15I, [2024-07-25T22:15:15.097808 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26522:15:18INFO: analyzing "public.p_ci_stages" inheritance tree
26622:15:18INFO: analyzing "public.ci_stages"
26722:15:18INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26822:15:18I, [2024-07-25T22:15:18.947696 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
26922:15:18I, [2024-07-25T22:15:18.949125 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27022:15:18I, [2024-07-25T22:15:18.950110 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27122:15:20I, [2024-07-25T22:15:20.389875 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
27222:15:27I, [2024-07-25T22:15:27.821188 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
27322:15:43I, [2024-07-25T22:15:43.678591 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
27422:15:43I, [2024-07-25T22:15:43.679864 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27522:15:43I, [2024-07-25T22:15:43.680686 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27622:15:57I, [2024-07-25T22:15:57.647244 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
27722:16:05I, [2024-07-25T22:16:05.850163 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
27822:16:17# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb. It took 4 minutes 10.45 seconds. Expected to take 1 minute 58.97 seconds.
27922:16:17# [RSpecRunTime] RSpec elapsed time: 8 minutes 11.88 seconds. Current RSS: ~1201M. Threads: 2. load average: 1.01 1.07 0.86 1/262 430
28022:16:17.
28122:16:17# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb. Expected to take 1 minute 40.41 seconds.
28222:16:17Gitlab::BackgroundMigration::SetLegacyOpenSourceLicenseAvailableForNonPublicProjects
28322:17:54 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
28422:18:08 sets `legacy_open_source_license_available` attribute to false for non-public projects
28522:18:28INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
28622:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
28722:18:28INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
28822:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
28922:18:28INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29022:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
29122:18:28INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29222:18:28INFO: analyzing "public.p_ci_job_annotations" inheritance tree
29322:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
29422:18:28INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29522:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
29622:18:28INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29722:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
29822:18:28INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29922:18:28INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
30022:18:28INFO: analyzing "public.ci_builds_metadata"
30122:18:28INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
30222:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
30322:18:28INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
30422:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
30522:18:28INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
30622:18:28INFO: analyzing "public.p_ci_builds" inheritance tree
30722:18:28INFO: analyzing "public.ci_builds"
30822:18:28INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
30922:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
31022:18:28INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
31122:18:28INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
31222:18:28INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
31322:18:29I, [2024-07-25T22:18:29.313810 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
31422:18:53I, [2024-07-25T22:18:53.191329 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
31522:18:55INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
31622:18:55INFO: analyzing "public.ci_pipeline_variables"
31722:18:55INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
31822:18:55I, [2024-07-25T22:18:55.070100 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
31922:18:55I, [2024-07-25T22:18:55.071384 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32022:18:55I, [2024-07-25T22:18:55.072222 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32122:18:57INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
32222:18:57INFO: analyzing "public.ci_pipeline_variables"
32322:18: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
32422:19:02INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
32522:19:02INFO: analyzing "public.ci_job_artifacts"
32622:19:02INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
32722:19:02I, [2024-07-25T22:19:02.522490 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
32822:19:02I, [2024-07-25T22:19:02.524111 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32922:19:02I, [2024-07-25T22:19:02.525441 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33022:19:06INFO: analyzing "public.p_ci_stages" inheritance tree
33122:19:06INFO: analyzing "public.ci_stages"
33222:19:06INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
33322:19:06I, [2024-07-25T22:19:06.505082 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
33422:19:06I, [2024-07-25T22:19:06.506626 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33522:19:06I, [2024-07-25T22:19:06.507669 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33622:19:07I, [2024-07-25T22:19:07.951913 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
33722:19:15I, [2024-07-25T22:19:15.471352 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
33822:19:31I, [2024-07-25T22:19:31.651942 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
33922:19:31I, [2024-07-25T22:19:31.653150 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34022:19:31I, [2024-07-25T22:19:31.654006 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34122:19:45I, [2024-07-25T22:19:45.754846 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
34222:19:54I, [2024-07-25T22:19:54.186064 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
34322:20:05# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb. It took 3 minutes 48.38 seconds. Expected to take 1 minute 40.41 seconds.
34422:20:05# [RSpecRunTime] RSpec elapsed time: 12 minutes 0.31 second. Current RSS: ~1206M. Threads: 2. load average: 1.03 1.07 0.92 1/264 431
34522:20:05.
34622:20:05# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/delete_invalid_protected_branch_merge_access_levels_spec.rb. Expected to take 1 minute 10.58 seconds.
34722:20:05Gitlab::BackgroundMigration::DeleteInvalidProtectedBranchMergeAccessLevels
34822:21:09 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
34922:21:09 when there are merge access levels
35022:21:17 deletes merge access levels with groups that do not have project_group_links to the project
35122:21:17# [RSpecRunTime] RSpec elapsed time: 13 minutes 12.08 seconds. Current RSS: ~1245M. Threads: 2. load average: 1.06 1.07 0.93 1/264 432
35222:21:17.
35322:21:20I, [2024-07-25T22:21:20.027936 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
35422:21:21INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
35522:21:21INFO: analyzing "public.ci_pipeline_variables"
35622:21:21INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
35722:21:21I, [2024-07-25T22:21:21.943068 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
35822:21:21I, [2024-07-25T22:21:21.944410 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
35922:21:21I, [2024-07-25T22:21:21.945329 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36022:21:24INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
36122:21:24INFO: analyzing "public.ci_pipeline_variables"
36222:21:24INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
36322:21:29INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
36422:21:29INFO: analyzing "public.ci_job_artifacts"
36522:21: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
36622:21:29I, [2024-07-25T22:21:29.128572 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
36722:21:29I, [2024-07-25T22:21:29.130005 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36822:21:29I, [2024-07-25T22:21:29.131032 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36922:21:32INFO: analyzing "public.p_ci_stages" inheritance tree
37022:21:32INFO: analyzing "public.ci_stages"
37122:21:32INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
37222:21:32I, [2024-07-25T22:21:32.965696 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
37322:21:32I, [2024-07-25T22:21:32.967073 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37422:21:32I, [2024-07-25T22:21:32.968020 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37522:21:34I, [2024-07-25T22:21:34.342562 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
37622:21:41I, [2024-07-25T22:21:41.689979 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
37722:21:57I, [2024-07-25T22:21:57.024613 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
37822:21:57I, [2024-07-25T22:21:57.025889 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37922:21:57I, [2024-07-25T22:21:57.026696 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
38022:22:10I, [2024-07-25T22:22:10.730605 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
38122:22:18I, [2024-07-25T22:22:18.831863 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
38222:22:30# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/delete_invalid_protected_branch_merge_access_levels_spec.rb. It took 2 minutes 24.27 seconds. Expected to take 1 minute 10.58 seconds.
38322:22:30# [RSpecRunTime] RSpec elapsed time: 14 minutes 24.62 seconds. Current RSS: ~1212M. Threads: 2. load average: 1.21 1.10 0.95 1/263 433
38422:22:30.
38522:22:30# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb. Expected to take 1 minute 3.26 seconds.
38622:22:30Gitlab::BackgroundMigration::DeleteInvalidProtectedBranchPushAccessLevels
38722:23:31 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
38822:23:31 when there are push access levels
38922:23:39 deletes push access levels with groups that do not have project_group_links to the project
39022:23:39# [RSpecRunTime] RSpec elapsed time: 15 minutes 33.79 seconds. Current RSS: ~1239M. Threads: 2. load average: 1.34 1.15 0.99 1/263 434
39122:23:39.
39222:23:41I, [2024-07-25T22:23:41.774976 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
39322:23:43INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
39422:23:43INFO: analyzing "public.ci_pipeline_variables"
39522:23:43INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
39622:23:43I, [2024-07-25T22:23:43.660622 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
39722:23:43I, [2024-07-25T22:23:43.662135 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
39822:23:43I, [2024-07-25T22:23:43.663565 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
39922:23:45INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
40022:23:45INFO: analyzing "public.ci_pipeline_variables"
40122:23:45INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
40222:23:50INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
40322:23:50INFO: analyzing "public.ci_job_artifacts"
40422:23:50INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
40522:23:50I, [2024-07-25T22:23:50.914609 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
40622:23:50I, [2024-07-25T22:23:50.916017 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40722:23:50I, [2024-07-25T22:23:50.917146 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40822:23:54INFO: analyzing "public.p_ci_stages" inheritance tree
40922:23:54INFO: analyzing "public.ci_stages"
41022:23:54INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
41122:23:54I, [2024-07-25T22:23:54.851823 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
41222:23:54I, [2024-07-25T22:23:54.853154 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41322:23:54I, [2024-07-25T22:23:54.854067 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41422:23:56I, [2024-07-25T22:23:56.369952 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
41522:24:03I, [2024-07-25T22:24:03.878170 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
41622:24:19I, [2024-07-25T22:24:19.578607 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
41722:24:19I, [2024-07-25T22:24:19.579878 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41822:24:19I, [2024-07-25T22:24:19.580703 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41922:24:33I, [2024-07-25T22:24:33.567933 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
42022:24:41I, [2024-07-25T22:24:41.550185 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
42122:24:52# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb. It took 2 minutes 22.59 seconds. Expected to take 1 minute 3.26 seconds.
42222:24:52# [RSpecRunTime] RSpec elapsed time: 16 minutes 47.26 seconds. Current RSS: ~1217M. Threads: 2. load average: 1.09 1.13 0.99 1/263 435
42322:24:52.
42422:24:52# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb. Expected to take 49.08 seconds.
42522:24:52Gitlab::BackgroundMigration::BackfillWorkItemParentLinksNamespaceId
42622:25:39 constructs a valid query
42722:25:39 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
42822:25:45 performs without error
42922:25:46I, [2024-07-25T22:25:46.364589 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
43022:26:02I, [2024-07-25T22:26:02.263837 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
43122:26:02I, [2024-07-25T22:26:02.265196 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
43222:26:02I, [2024-07-25T22:26:02.266176 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43322:26:16I, [2024-07-25T22:26:16.237789 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
43422:26:24I, [2024-07-25T22:26:24.153487 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
43522:26:35# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb. It took 1 minute 42.74 seconds. Expected to take 49.08 seconds.
43622:26:35# [RSpecRunTime] RSpec elapsed time: 18 minutes 30.05 seconds. Current RSS: ~1204M. Threads: 2. load average: 1.13 1.14 1.01 1/262 436
43722:26:35.
43822:26:35# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_headers_group_id_spec.rb. Expected to take 38.22 seconds.
43922:26:35Gitlab::BackgroundMigration::BackfillAuditEventsStreamingHeadersGroupId
44022:27:04 performs without error
44122:27:09 constructs a valid query
44222:27:09 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
44322:27:11I, [2024-07-25T22:27:11.159372 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
44422:27:11I, [2024-07-25T22:27:11.161626 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
44522:27:11I, [2024-07-25T22:27:11.162773 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
44622:27:24I, [2024-07-25T22:27:24.615281 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
44722:27:32I, [2024-07-25T22:27:32.908434 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
44822:27:44# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_headers_group_id_spec.rb. It took 1 minute 8.6 seconds. Expected to take 38.22 seconds.
44922:27:44# [RSpecRunTime] RSpec elapsed time: 19 minutes 38.7 seconds. Current RSS: ~1203M. Threads: 2. load average: 1.03 1.11 1.00 1/263 437
45022:27:44.
45122:27:44# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vulnerability_issue_links_project_id_spec.rb. Expected to take 33.14 seconds.
45222:27:44Gitlab::BackgroundMigration::BackfillVulnerabilityIssueLinksProjectId
45322:28:07 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
45422:28:12 constructs a valid query
45522:28:17 performs without error
45622:28:30I, [2024-07-25T22:28:30.672492 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
45722:28:38I, [2024-07-25T22:28:38.806115 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
45822:28:50# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vulnerability_issue_links_project_id_spec.rb. It took 1 minute 5.72 seconds. Expected to take 33.14 seconds.
45922:28:50# [RSpecRunTime] RSpec elapsed time: 20 minutes 44.47 seconds. Current RSS: ~1206M. Threads: 2. load average: 0.90 1.07 0.99 1/263 438
46022:28:50.
46122:28:50# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_debian_publications_project_id_spec.rb. Expected to take 31.58 seconds.
46222:28:50Gitlab::BackgroundMigration::BackfillPackagesDebianPublicationsProjectId
46322:29:18 performs without error
46422:29:18 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
46522:29:22 constructs a valid query
46622:29:36I, [2024-07-25T22:29:36.474572 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
46722:29:44I, [2024-07-25T22:29:44.600486 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
46822:29:56# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_debian_publications_project_id_spec.rb. It took 1 minute 6.07 seconds. Expected to take 31.58 seconds.
46922:29:56# [RSpecRunTime] RSpec elapsed time: 21 minutes 50.58 seconds. Current RSS: ~1208M. Threads: 2. load average: 0.83 1.02 0.99 4/263 439
47022:29:56.
47122:29:56# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/legacy_uploads_migrator_spec.rb. Expected to take 30.67 seconds.
47222:29:56Gitlab::BackgroundMigration::LegacyUploadsMigrator
47322:29:56 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
47422:30:06 removes all legacy files
47522:30:13 removes all Note AttachmentUploader records
47622:30:21 creates new uploads for successfully migrated records
47722:30:28 does not remove appearance uploads
47822:30:36# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/legacy_uploads_migrator_spec.rb. It took 40.49 seconds. Expected to take 30.67 seconds.
47922:30:36# [RSpecRunTime] RSpec elapsed time: 22 minutes 31.13 seconds. Current RSS: ~1218M. Threads: 4. load average: 1.05 1.05 1.00 1/274 451
48022:30:36.
48122:30:36# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_debian_group_distribution_keys_group_id_spec.rb. Expected to take 29.6 seconds.
48222:30:36Gitlab::BackgroundMigration::BackfillPackagesDebianGroupDistributionKeysGroupId
48322:31:05 constructs a valid query
48422:31:10 performs without error
48522:31:10 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
48622:31:23I, [2024-07-25T22:31:23.870976 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
48722:31:32I, [2024-07-25T22:31:32.274328 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
48822:31:43# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_debian_group_distribution_keys_group_id_spec.rb. It took 1 minute 6.83 seconds. Expected to take 29.6 seconds.
48922:31:43# [RSpecRunTime] RSpec elapsed time: 23 minutes 38.01 seconds. Current RSS: ~1227M. Threads: 4. load average: 1.18 1.09 1.02 1/274 452
49022:31:43.
49122:31:43# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backup_and_remove_notes_with_null_noteable_type_spec.rb. Expected to take 22.54 seconds.
49222:31:43Gitlab::BackgroundMigration::BackupAndRemoveNotesWithNullNoteableType
49322:32:01 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
49422:32:02WARNING: there is no transaction in progress
49522:32:06 backs up and removes orphaned notes records
49622:32:16I, [2024-07-25T22:32:16.562099 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
49722:32:24I, [2024-07-25T22:32:24.412750 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
49822:32:35# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backup_and_remove_notes_with_null_noteable_type_spec.rb. It took 51.99 seconds. Expected to take 22.54 seconds.
49922:32:35# [RSpecRunTime] RSpec elapsed time: 24 minutes 30.05 seconds. Current RSS: ~1213M. Threads: 4. load average: 1.09 1.08 1.01 1/274 453
50022:32:35.
50122:32:35# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_merge_request_reviewers_project_id_spec.rb. Expected to take 18.54 seconds.
50222:32:35Gitlab::BackgroundMigration::BackfillMergeRequestReviewersProjectId
50322:32:47 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
50422:32:51 constructs a valid query
50522:32:55 performs without error
50622:32:56I, [2024-07-25T22:32:56.887046 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
50722:33:05I, [2024-07-25T22:33:05.083100 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
50822:33:16# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_merge_request_reviewers_project_id_spec.rb. It took 41.27 seconds. Expected to take 18.54 seconds.
50922:33:17# [RSpecRunTime] RSpec elapsed time: 25 minutes 11.37 seconds. Current RSS: ~1213M. Threads: 4. load average: 1.15 1.10 1.02 1/274 454
51022:33:17.
51122:33:17# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vulnerability_finding_evidences_project_id_spec.rb. Expected to take 17.49 seconds.
51222:33:17Gitlab::BackgroundMigration::BackfillVulnerabilityFindingEvidencesProjectId
51322:33:28 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
51422:33:32 performs without error
51522:33:36 constructs a valid query
51622:33:37I, [2024-07-25T22:33:37.400082 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
51722:33:45I, [2024-07-25T22:33:45.510494 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
51822:33:56# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vulnerability_finding_evidences_project_id_spec.rb. It took 39.98 seconds. Expected to take 17.49 seconds.
51922:33:57# [RSpecRunTime] RSpec elapsed time: 25 minutes 51.4 seconds. Current RSS: ~1215M. Threads: 4. load average: 1.14 1.11 1.03 1/274 455
52022:33:57.
52122:33:57# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_users_color_mode_id_spec.rb. Expected to take 12.4 seconds.
52222:33:57Gitlab::BackgroundMigration::BackfillUsersColorModeId
52322:34:05 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
52422:34:10 backfills with the default values
52522:34:16I, [2024-07-25T22:34:16.563597 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
52622:34:27# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_users_color_mode_id_spec.rb. It took 30.89 seconds. Expected to take 12.4 seconds.
52722:34:27# [RSpecRunTime] RSpec elapsed time: 26 minutes 22.34 seconds. Current RSS: ~1218M. Threads: 4. load average: 1.00 1.08 1.02 1/275 456
52822:34:27.
52922:34:27# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_evidences_project_id_spec.rb. Expected to take 11.21 seconds.
53022:34:27Gitlab::BackgroundMigration::BackfillEvidencesProjectId
53122:34:36 performs without error
53222:34:39 constructs a valid query
53322:34:39 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
53422:34:51# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_evidences_project_id_spec.rb. It took 23.15 seconds. Expected to take 11.21 seconds.
53522:34:51# [RSpecRunTime] RSpec elapsed time: 26 minutes 45.54 seconds. Current RSS: ~1213M. Threads: 4. load average: 1.00 1.08 1.02 1/275 457
53622:34:51.
53722:34:51# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/batching_strategies/backfill_project_statistics_with_container_registry_size_batching_strategy_spec.rb. Expected to take 5.21 seconds.
53822:34:51Gitlab::BackgroundMigration::BatchingStrategies::BackfillProjectStatisticsWithContainerRegistrySizeBatchingStrategy
53922:34:51 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
54022:34:55 is expected to be < Gitlab::BackgroundMigration::BatchingStrategies::PrimaryKeyBatchingStrategy
54122:35:03# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/batching_strategies/backfill_project_statistics_with_container_registry_size_batching_strategy_spec.rb. It took 12.39 seconds. Expected to take 5.21 seconds.
54222:35:03# [RSpecRunTime] RSpec elapsed time: 26 minutes 57.98 seconds. Current RSS: ~1212M. Threads: 4. load average: 0.92 1.06 1.01 1/275 458
54322:35:03.
54422:35:03# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/batching_strategies/dismissed_vulnerabilities_strategy_spec.rb. Expected to take 4.47 seconds.
54522:35:03Gitlab::BackgroundMigration::BatchingStrategies::DismissedVulnerabilitiesStrategy#next_batch
54622:35:04 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
54722:35:08 is expected to be < Gitlab::BackgroundMigration::BatchingStrategies::PrimaryKeyBatchingStrategy
54822:35:16# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/batching_strategies/dismissed_vulnerabilities_strategy_spec.rb. It took 12.43 seconds. Expected to take 4.47 seconds.
54922:35:16# [RSpecRunTime] RSpec elapsed time: 27 minutes 10.46 seconds. Current RSS: ~1213M. Threads: 4. load average: 0.86 1.04 1.00 1/275 459
55022:35:16.
55122:35:44auto_explain log contains 1045 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-10-10.356.main.ndjson.gz
55222:35:44took 28.056046822
55322:36:11auto_explain log contains 1046 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-10-10.356.ci.ndjson.gz
55422:36:11took 27.328727484
55522:36:11[TEST PROF INFO] EventProf results for sql.active_record
55622:36:11Total time: 17:59.266 of 27:04.430 (66.44%)
55722:36:11Total events: 698863
55822:36:11Top 5 slowest suites (by time):
55922:36:11Gitlab::Backg...PublicProjects (./spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb:5) – 02:43.620 (93589 / 2) of 03:48.422 (71.63%)
56022:36:11Gitlab::Backg...dsClusterAgent (./spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb:5) – 02:42.971 (98026 / 4) of 04:10.500 (65.06%)
56122:36:11Gitlab::Backg...ngCiCdSettings (./spec/lib/gitlab/background_migration/backfill_missing_ci_cd_settings_spec.rb:5) – 02:37.380 (96131 / 3) of 03:55.361 (66.87%)
56222:36:11Gitlab::Backg...geAccessLevels (./spec/lib/gitlab/background_migration/delete_invalid_protected_branch_merge_access_levels_spec.rb:5) – 01:42.253 (66870 / 2) of 02:24.312 (70.86%)
56322:36:11Gitlab::Backg...shAccessLevels (./spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb:5) – 01:41.136 (66916 / 2) of 02:22.637 (70.9%)
56422:36:11Knapsack report was generated. Preview:
56522:36:11{
56622:36:11 "spec/lib/gitlab/background_migration/backfill_missing_ci_cd_settings_spec.rb": 123.94490827799996,
56722:36:11 "spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb": 136.170483129,
56822:36:11 "spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb": 111.11525886699997,
56922:36:11 "spec/lib/gitlab/background_migration/delete_invalid_protected_branch_merge_access_levels_spec.rb": 71.9306758780001,
57022:36:11 "spec/lib/gitlab/background_migration/delete_invalid_protected_branch_push_access_levels_spec.rb": 69.31630736900001,
57122:36:11 "spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb": 52.49004955999999,
57222:36:11 "spec/lib/gitlab/background_migration/backfill_audit_events_streaming_headers_group_id_spec.rb": 33.95877711899993,
57322:36:11 "spec/lib/gitlab/background_migration/backfill_vulnerability_issue_links_project_id_spec.rb": 32.93315124800006,
57422:36:11 "spec/lib/gitlab/background_migration/backfill_packages_debian_publications_project_id_spec.rb": 32.975096054000005,
57522:36:11 "spec/lib/gitlab/background_migration/legacy_uploads_migrator_spec.rb": 32.66667357000006,
57622:36:11 "spec/lib/gitlab/background_migration/backfill_packages_debian_group_distribution_keys_group_id_spec.rb": 33.49016613399999,
57722:36:11 "spec/lib/gitlab/background_migration/backup_and_remove_notes_with_null_noteable_type_spec.rb": 23.158641232000036,
57822:36:11 "spec/lib/gitlab/background_migration/backfill_merge_request_reviewers_project_id_spec.rb": 20.185265630999993,
57922:36:11 "spec/lib/gitlab/background_migration/backfill_vulnerability_finding_evidences_project_id_spec.rb": 19.381496424000034,
58022:36:11 "spec/lib/gitlab/background_migration/backfill_users_color_mode_id_spec.rb": 13.351473368999905,
58122:36:11 "spec/lib/gitlab/background_migration/backfill_evidences_project_id_spec.rb": 12.013324998999906,
58222:36:11 "spec/lib/gitlab/background_migration/batching_strategies/backfill_project_statistics_with_container_registry_size_batching_strategy_spec.rb": 4.640232263999906,
58322:36:11 "spec/lib/gitlab/background_migration/batching_strategies/dismissed_vulnerabilities_strategy_spec.rb": 4.7150396819997695
58422:36:11}
58522:36:11Knapsack global time execution for tests: 13m 48s
58622:36:11Pending: (Failures listed here are expected and do not affect your suite's status)
58722:36:11 1) Gitlab::BackgroundMigration::BackfillMissingCiCdSettings
58822:36:11 # Not yet implemented
58922:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
59022:36:11 2) Gitlab::BackgroundMigration::BackfillVulnerabilityReadsClusterAgent
59122:36:11 # Not yet implemented
59222:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
59322:36:11 3) Gitlab::BackgroundMigration::SetLegacyOpenSourceLicenseAvailableForNonPublicProjects
59422:36:11 # Not yet implemented
59522:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
59622:36:11 4) Gitlab::BackgroundMigration::DeleteInvalidProtectedBranchMergeAccessLevels
59722:36:11 # Not yet implemented
59822:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
59922:36:11 5) Gitlab::BackgroundMigration::DeleteInvalidProtectedBranchPushAccessLevels
60022:36:11 # Not yet implemented
60122:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
60222:36:11 6) Gitlab::BackgroundMigration::BackfillWorkItemParentLinksNamespaceId
60322:36:11 # Not yet implemented
60422:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
60522:36:11 7) Gitlab::BackgroundMigration::BackfillAuditEventsStreamingHeadersGroupId
60622:36:11 # Not yet implemented
60722:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
60822:36:11 8) Gitlab::BackgroundMigration::BackfillVulnerabilityIssueLinksProjectId
60922:36:11 # Not yet implemented
61022:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
61122:36:11 9) Gitlab::BackgroundMigration::BackfillPackagesDebianPublicationsProjectId
61222:36:11 # Not yet implemented
61322:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
61422:36:11 10) Gitlab::BackgroundMigration::LegacyUploadsMigrator
61522:36:11 # Not yet implemented
61622:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
61722:36:11 11) Gitlab::BackgroundMigration::BackfillPackagesDebianGroupDistributionKeysGroupId
61822:36:11 # Not yet implemented
61922:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
62022:36:11 12) Gitlab::BackgroundMigration::BackupAndRemoveNotesWithNullNoteableType
62122:36:11 # Not yet implemented
62222:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
62322:36:11 13) Gitlab::BackgroundMigration::BackfillMergeRequestReviewersProjectId
62422:36:11 # Not yet implemented
62522:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
62622:36:11 14) Gitlab::BackgroundMigration::BackfillVulnerabilityFindingEvidencesProjectId
62722:36:11 # Not yet implemented
62822:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
62922:36:11 15) Gitlab::BackgroundMigration::BackfillUsersColorModeId
63022:36:11 # Not yet implemented
63122:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
63222:36:11 16) Gitlab::BackgroundMigration::BackfillEvidencesProjectId
63322:36:11 # Not yet implemented
63422:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
63522:36:11 17) Gitlab::BackgroundMigration::BatchingStrategies::BackfillProjectStatisticsWithContainerRegistrySizeBatchingStrategy
63622:36:11 # Not yet implemented
63722:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
63822:36:11 18) Gitlab::BackgroundMigration::BatchingStrategies::DismissedVulnerabilitiesStrategy#next_batch
63922:36:11 # Not yet implemented
64022:36:11 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
64122:36:11Finished in 28 minutes 6 seconds (files took 2 minutes 7.3 seconds to load)
64222:36:1150 examples, 0 failures, 18 pending
64322:36:11Randomized with seed 65189
64422:36:18[TEST PROF INFO] Time spent in factories: 00:13.177 (0.76% of total time)
64522:36:18RSpec exited with 0.
64622:36:18No examples to retry, congrats!
647 22:36:19 Running after_script 00:07
64822:36:20Running after script...
64922:36:20$ source scripts/utils.sh
65022:36:20$ bundle exec gem list gitlab_quality-test_tooling
65122:36:21gitlab_quality-test_tooling (1.32.1)
65222:36:21$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
653 22:36:21 Report test failures 00:00
65522:36:21$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
656 22:36:21 Report test flakiness 00:00
65822:36:21$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
659 22:36:21 Report test slowness 00:00
66122:36:21$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
662 22:36:21 Report test slowness in MR note 00:02
66522:36:23$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
666 22:36:23 Report test files close to timing out 00:02
66922:36:25$ tooling/bin/push_job_metrics || true
67022:36:26[job-metrics] Pushing job metrics file for the CI/CD job.
67122:36:26[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
672 22:36:26 Saving cache for successful job 00:01
67322:36:27Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.4-17 due to policy
674 22:36:27 Uploading artifacts for successful job 00:05
67522:36:28Uploading artifacts...
67622:36:28auto_explain/: found 3 matching artifact files and directories
67722:36:28coverage/: found 5 matching artifact files and directories
67822:36:28crystalball/: found 2 matching artifact files and directories
67922:36:28WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
68022:36:28knapsack/: found 4 matching artifact files and directories
68122:36:28rspec/: found 16 matching artifact files and directories
68222:36:28WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
68322:36:28log/*.log: found 13 matching artifact files and directories
68422:36:28WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7432319169/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
68522:36:28WARNING: Retrying... context=artifacts-uploader error=request redirected
68622:36:30Uploading artifacts as "archive" to coordinator... 201 Created id=7432319169 responseStatus=201 Created token=glcbt-66
68722:36:30Uploading artifacts...
68822:36:30rspec/rspec-*.xml: found 1 matching artifact files and directories
68922:36:30WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7432319169/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
69022:36:30WARNING: Retrying... context=artifacts-uploader error=request redirected
69122:36:32Uploading artifacts as "junit" to coordinator... 201 Created id=7432319169 responseStatus=201 Created token=glcbt-66
692 22:36:32 Cleaning up project directory and file based variables 00:00
69322:36:40Job succeeded