Skip to content
Snippets Groups Projects

rspec-ee background_migration pg14 3/3

Passed Started by
🤖 GitLab Bot 🤖
@gitlab-bot
Log timestamps in UTC.
122:02:06Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
222:02:06 on green-4.shared-gitlab-org.runners-manager.gitlab.com/default x5QiHUKw, system ID: s_b9637080a79e
322:02:06 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:02:06Resolving secrets
5 22:02:06 Preparing the "docker+machine" executor 00:38
622:02:06Using 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:02:08Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
822:02:08Authenticating with credentials from job payload (GitLab Registry)
922:02:08Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:02:09Using 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:02:09WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:02:09WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:02:09Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1422:02:09Authenticating with credentials from job payload (GitLab Registry)
1522:02:09Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:02:09Using 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:02:10Starting service redis:6.2-alpine ...
1822:02:10Pulling docker image redis:6.2-alpine ...
1922:02:10Using docker image sha256:36c1d3cfe18f4b12d4640576f7048068acbfc3625027b6bbb46bba0e31b11831 for redis:6.2-alpine with digest redis@sha256:e3b17ba9479deec4b7d1eeec1548a253acc5374d68d3b27937fcfe4df8d18c7e ...
2022:02:11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 is already created. Ignoring.
2122:02:11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 is already created. Ignoring.
2222:02:11Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 ...
2322:02:11Authenticating with credentials from job payload (GitLab Registry)
2422:02:11Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 ...
2522:02:11Using docker image sha256:df819140e3df737cdc7b8d6fb73ce804b05058c790e025b5f52840956feb1734 for registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:7bdf349fc3dd9da64bc6581d44c0e906bffd38977ddbd2668ba34adb5dedfe38 ...
2622:02:11Starting service elasticsearch:7.17.6 ...
2722:02:11Pulling docker image elasticsearch:7.17.6 ...
2822:02:12Using docker image sha256:5fad10241ffd65d817ed0ddfaf6e87eee1f7dc2a7db33db1047835560ea71fda for elasticsearch:7.17.6 with digest elasticsearch@sha256:6c128de5d01c0c130a806022d6bd99b3e4c27a9af5bfc33b6b81861ae117d028 ...
2922:02:12Starting service registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.12.0 ...
3022:02:12Authenticating with credentials from job payload (GitLab Registry)
3122:02:12Pulling docker image registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.12.0 ...
3222:02:13Using docker image sha256:9e2a46104bf429c88386efd9133e58584ef5a27c27cc4435f97778e05847e0e2 for registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.12.0 with digest registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway@sha256:d8f985b0841bd08b3505c9e6ed003f095af36f91b05849f6262ccec4278b9b53 ...
3322:02:13Waiting for services to be up and running (timeout 30 seconds)...
3422:02:44Authenticating with credentials from job payload (GitLab Registry)
3522:02:44Pulling 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 ...
3622:02:44Using 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 ...
37 22:02:44 Preparing environment 00:01
3822:02:44Running on runner-x5qihukw-project-278964-concurrent-0 via runner-x5qihukw-shared-gitlab-org-1721938661-10a2075b...
39 22:02:45 Getting source from Git repository 00:01
4022:02:45Skipping Git repository setup
4122:02:45Skipping Git checkout
4222:02:45Skipping Git submodules setup
43 22:02:46 Restoring cache 00:22
4422:02:47Checking cache for ruby-gems-debian-bookworm-ruby-3.2.4-17...
4522:02:47Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.4-17
4622:03:04Successfully extracted cache
47 22:03:08 Downloading artifacts 00:44
4822:03:08Downloading artifacts for retrieve-tests-metadata (7432319060)...
4922:03:09Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319060 responseStatus=200 OK token=glcbt-66
5022:03:09Downloading artifacts for clone-gitlab-repo (7432319031)...
5122:03:12Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319031 responseStatus=200 OK token=glcbt-66
5222:03:12WARNING: Part of .git directory is on the list of files to extract
5322:03:12WARNING: This may introduce unexpected problems
5422:03:27Downloading artifacts for compile-test-assets (7432319052)...
5522:03:30Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319052 responseStatus=200 OK token=glcbt-66
5622:03:37Downloading artifacts for setup-test-env (7432319054)...
5722:03:41Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319054 responseStatus=200 OK token=glcbt-66
5822:03:46Downloading artifacts for detect-tests (7432319057)...
5922:03:47Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7432319057 responseStatus=200 OK token=glcbt-66
60 22:03:52 Executing "step_script" stage of the job script
6122:03:52Using 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 ...
6222:03:53$ echo $FOSS_ONLY
6322:03:53$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
6422:03:53$ export GOPATH=$CI_PROJECT_DIR/.go
6522:03:53$ mkdir -p $GOPATH
6622:03:53$ source scripts/utils.sh
6722:03:53$ source scripts/prepare_build.sh
68 22:03:53 Installing gems 00:25
9722:04:19Using decomposed database config (config/database.yml.decomposed-postgresql)
9822:04:18Geo DB will be set up.
9922:04:19Embedding DB will be set up.
100 22:04:19 Setting up DBs 01:42
11622:06:01$ source ./scripts/rspec_helpers.sh
11722:06:01$ run_timed_command "gem install knapsack --no-document"
11822:06:00$ gem install knapsack --no-document
11922:06:02Successfully installed knapsack-4.0.0
12022:06:021 gem installed
12122:06:01==> 'gem install knapsack --no-document' succeeded in 1 seconds.
12222:06:02$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
123 22:06:02 Spawning Gitaly 00:05
12722:06:07$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
12822:06:07$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
12922:06:07$ tooling/bin/create_job_metrics_file || true
13022:06:10[job-metrics] Creating the job metrics file for the CI/CD job.
13122:06:10$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
132 22:06:10 RSpec 19:52
13322:06:10[22:06:10] Starting rspec_parallelized_job
13422:06:02RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
13522:06:11KNAPSACK_GENERATE_REPORT: true
13622:06:11FLAKY_RSPEC_GENERATE_REPORT: true
13722:06:11KNAPSACK_TEST_FILE_PATTERN: {ee/}spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
13822:06:11KNAPSACK_LOG_LEVEL: debug
13922:06:11KNAPSACK_REPORT_PATH: knapsack/rspec-ee_background_migration_pg14_3_3_278964_report.json
14022:06:11FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
14122:06:11FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec-ee_background_migration_pg14_3_3_278964_report.json
14222:06:11NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec-ee_background_migration_pg14_3_3_278964_report.json
14322:06:11RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7432319295.txt
14422:06:11CRYSTALBALL:
14522:06:11RSPEC_TESTS_MAPPING_ENABLED:
14622:06:11RSPEC_TESTS_FILTER_FILE:
14722:06:11Shell set options (set -o) enabled:
14822:06:11braceexpand on
14922:06:12hashall on
15022:06:12interactive-comments on
15122:06:12pipefail on
15222:06:20Knapsack report generator started!
15322:06:12/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!
15422:07:50/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!
15522:08:37Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
15622:08:37# [RSpecRunTime] Starting RSpec timer...
15722:08:37[TEST PROF INFO] EventProf enabled (sql.active_record)
15822:08:37unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
15922:08:37unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
16022:08:44Test environment set up in 3.441159739 seconds
16122:08:44# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb. Expected to take 2 minutes 2.59 seconds.
16222:08:44Gitlab::BackgroundMigration::MigrateApproverToApprovalRules
16322:08:46 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
16422:08:46 sync approval rule and its members
16522:08:46 merge request
16622:08:46 behaves like sync approval member
16722:08:46 when member in old schema but not in new schema
16822:08:54 creates in new schema
16922:08:54 when rule is not created yet
17022:09:00 creates rule in new schema
17122:09:00 when member not in old schema but in new schema
17222:09:06 removes in new schema
17322:09:07# [RSpecRunTime] RSpec elapsed time: 28.88 seconds. Current RSS: ~1223M. Threads: 4. load average: 1.15 1.48 1.56 1/408 443
17422:09:07.
17522:09:07 when approver has user_id which no longer exists
17622:09:14 ignores broken reference when assigning
17722:09:14# [RSpecRunTime] RSpec elapsed time: 37.02 seconds. Current RSS: ~1207M. Threads: 4. load average: 1.21 1.48 1.56 3/409 444
17822:09:14.
17922:09:14 when project rule is present
18022:09:20 sets MR rule's source to project rule without duplication
18122:09:20# [RSpecRunTime] RSpec elapsed time: 43.15 seconds. Current RSS: ~1204M. Threads: 4. load average: 1.19 1.47 1.55 1/408 445
18222:09:20.
18322:09:20 when project rule is absent
18422:09:26 has MR rule's source as nil
18522:09:26# [RSpecRunTime] RSpec elapsed time: 49.0 seconds. Current RSS: ~1208M. Threads: 4. load average: 1.18 1.47 1.55 1/409 446
18622:09:26.
18722:09:26 when approvals_before_merge is nil
18822:09:32 updates with project's approvals_required
18922:09:32# [RSpecRunTime] RSpec elapsed time: 54.98 seconds. Current RSS: ~1195M. Threads: 4. load average: 1.22 1.47 1.55 1/408 447
19022:09:32.
19122:09:32 when approvals_before_merge is too big
19222:09:38 caps at allowed maximum
19322:09:38# [RSpecRunTime] RSpec elapsed time: 1 minute 1.0 second. Current RSS: ~1214M. Threads: 4. load average: 1.21 1.46 1.55 1/408 448
19422:09:38.
19522:09:38 when approver is no longer overwritten
19622:09:44 removes rule
19722:09:44# [RSpecRunTime] RSpec elapsed time: 1 minute 6.93 seconds. Current RSS: ~1206M. Threads: 4. load average: 1.19 1.45 1.54 1/408 449
19822:09:44.
19922:09:44 #sync_code_owners_with_approvers
20022:09:44 when merge request is merged
20122:09:51 does nothing
20222:09:51# [RSpecRunTime] RSpec elapsed time: 1 minute 13.56 seconds. Current RSS: ~1220M. Threads: 4. load average: 1.09 1.43 1.53 1/411 479
20322:09:51.
20422:09:51 when code owner rule does not exist
20522:09:56 creates rule
20622:09:56# [RSpecRunTime] RSpec elapsed time: 1 minute 19.33 seconds. Current RSS: ~1214M. Threads: 4. load average: 1.09 1.42 1.53 1/411 480
20722:09:56.
20822:09:56 when code owner rule exists
20922:10:02 reuses and updates existing rule
21022:10:02 when there is no code owner
21122:10:08 removes rule
21222:10:08# [RSpecRunTime] RSpec elapsed time: 1 minute 30.74 seconds. Current RSS: ~1216M. Threads: 4. load average: 1.06 1.40 1.52 1/411 481
21322:10:08.
21422:10:08 project
21522:10:08 behaves like sync approval member
21622:10:08 when member in old schema but not in new schema
21722:10:14 creates in new schema
21822:10:14 when rule is not created yet
21922:10:19 creates rule in new schema
22022:10:19# [RSpecRunTime] RSpec elapsed time: 1 minute 42.3 seconds. Current RSS: ~1222M. Threads: 4. load average: 1.35 1.45 1.54 1/411 482
22122:10:19.
22222:10:19 when member not in old schema but in new schema
22322:10:26 removes in new schema
22422:10:26# [RSpecRunTime] RSpec elapsed time: 1 minute 48.47 seconds. Current RSS: ~1210M. Threads: 4. load average: 1.32 1.44 1.54 2/412 483
22522:10:26.
22622:10:26 when approver has user_id which no longer exists
22722:10:32 ignores broken reference when assigning
22822:10:32# [RSpecRunTime] RSpec elapsed time: 1 minute 54.72 seconds. Current RSS: ~1232M. Threads: 4. load average: 1.29 1.44 1.53 1/411 484
22922:10:32.
23022:10:32 when target is deleted
23122:10:38 does not err
23222:10:38# [RSpecRunTime] RSpec elapsed time: 2 minutes 0.45 second. Current RSS: ~1218M. Threads: 4. load average: 1.25 1.42 1.53 1/411 485
23322:10:38.
23422:10:38 when project has no repository
23522:10:43 does not err
23622:10:43# [RSpecRunTime] RSpec elapsed time: 2 minutes 6.1 seconds. Current RSS: ~1220M. Threads: 4. load average: 1.31 1.43 1.53 1/411 486
23722:10:43.
23822:10:55# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb. It took 2 minutes 10.1 seconds. Expected to take 2 minutes 2.59 seconds.
23922:10:55# [RSpecRunTime] RSpec elapsed time: 2 minutes 17.41 seconds. Current RSS: ~1192M. Threads: 4. load average: 1.26 1.42 1.52 1/410 487
24022:10:55.
24122:10:55# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/delete_orphaned_transferred_project_approval_rules_spec.rb. Expected to take 1 minute 58.98 seconds.
24222:10:55Gitlab::BackgroundMigration::DeleteOrphanedTransferredProjectApprovalRules
24322:12:24 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
24422:12:24 #perform
24522:12:35 delete only outdated approval project rules where report_type equals to 4 and 2
24622:12:46 enqueues a worker to sync the affected project
24722:12:46# [RSpecRunTime] RSpec elapsed time: 4 minutes 8.4 seconds. Current RSS: ~1226M. Threads: 4. load average: 1.15 1.33 1.48 1/411 488
24822:12:46.
24922:13:02INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
25022:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
25122:13:02INFO: "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
25222:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
25322:13:02INFO: "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
25422:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
25522:13:02INFO: "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
25622:13:02INFO: analyzing "public.p_ci_job_annotations" inheritance tree
25722:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
25822:13:02INFO: "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
25922:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
26022:13:02INFO: "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
26122:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
26222:13:02INFO: "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
26322:13:02INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
26422:13:02INFO: analyzing "public.ci_builds_metadata"
26522:13:02INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
26622:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
26722:13:02INFO: "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
26822:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
26922:13:02INFO: "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
27022:13:02INFO: analyzing "public.p_ci_builds" inheritance tree
27122:13:02INFO: analyzing "public.ci_builds"
27222:13:02INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
27322:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
27422:13:02INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
27522:13:02INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
27622:13:02INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
27722:13:03I, [2024-07-25T22:13:03.664201 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
27822:13:25I, [2024-07-25T22:13:25.241757 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
27922:13:27INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
28022:13:27INFO: analyzing "public.ci_pipeline_variables"
28122:13:27INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
28222:13:27I, [2024-07-25T22:13:27.130654 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
28322:13:27I, [2024-07-25T22:13:27.131911 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28422:13:27I, [2024-07-25T22:13:27.132726 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28522:13:29INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
28622:13:29INFO: analyzing "public.ci_pipeline_variables"
28722:13:29INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
28822:13:33INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
28922:13:33INFO: analyzing "public.ci_job_artifacts"
29022:13:33INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29122:13:33I, [2024-07-25T22:13:33.823394 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
29222:13:33I, [2024-07-25T22:13:33.824691 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29322:13:33I, [2024-07-25T22:13:33.825631 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29422:13:37INFO: analyzing "public.p_ci_stages" inheritance tree
29522:13:37INFO: analyzing "public.ci_stages"
29622:13:37INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29722:13:37I, [2024-07-25T22:13:37.448941 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
29822:13:37I, [2024-07-25T22:13:37.450230 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29922:13:37I, [2024-07-25T22:13:37.451176 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30022:13:38I, [2024-07-25T22:13:38.807957 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
30122:13:45I, [2024-07-25T22:13:45.789572 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
30222:14:00I, [2024-07-25T22:14:00.605248 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
30322:14:00I, [2024-07-25T22:14:00.606667 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30422:14:00I, [2024-07-25T22:14:00.607582 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30522:14:13I, [2024-07-25T22:14:13.642408 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
30622:14:21I, [2024-07-25T22:14:21.159042 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
30722:14:35# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/delete_orphaned_transferred_project_approval_rules_spec.rb. It took 3 minutes 40.09 seconds. Expected to take 1 minute 58.98 seconds.
30822:14:35# [RSpecRunTime] RSpec elapsed time: 5 minutes 57.55 seconds. Current RSS: ~1218M. Threads: 4. load average: 1.01 1.23 1.43 1/409 489
30922:14:35.
31022:14:35# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_project_statistics_storage_size_with_recent_size_spec.rb. Expected to take 1 minute 37.09 seconds.
31122:14:35Gitlab::BackgroundMigration::BackfillProjectStatisticsStorageSizeWithRecentSize
31222:15:54 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
31322:15:54 #perform
31422:15:54 when project_statistics backfill runs
31522:15:54 when on should_check_namespace_plan? returns true
31622:16:05 does not include uploads_size in the storage_size
31722:16:05# [RSpecRunTime] RSpec elapsed time: 7 minutes 27.98 seconds. Current RSS: ~1245M. Threads: 4. load average: 0.96 1.18 1.39 1/410 490
31822:16:05.
31922:16:05 when on should_check_namespace_plan? returns false
32022:16:15 includes uploads_size in the storage_size
32122:16:15# [RSpecRunTime] RSpec elapsed time: 7 minutes 38.0 seconds. Current RSS: ~1228M. Threads: 4. load average: 0.97 1.17 1.38 1/410 491
32222:16:15.
32322:16:18INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
32422:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
32522:16:18INFO: "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
32622:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
32722:16:18INFO: "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
32822:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
32922:16:18INFO: "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
33022:16:18INFO: analyzing "public.p_ci_job_annotations" inheritance tree
33122:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
33222:16:18INFO: "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
33322:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
33422:16:18INFO: "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
33522:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
33622:16:18INFO: "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
33722:16:18INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
33822:16:18INFO: analyzing "public.ci_builds_metadata"
33922:16:18INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
34022:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
34122:16:18INFO: "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
34222:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
34322:16:18INFO: "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
34422:16:18INFO: analyzing "public.p_ci_builds" inheritance tree
34522:16:18INFO: analyzing "public.ci_builds"
34622:16:18INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
34722:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
34822:16:18INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
34922:16:18INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
35022:16:18INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
35122:16:19I, [2024-07-25T22:16:19.239101 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
35222:16:41I, [2024-07-25T22:16:41.144231 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
35322:16:42INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
35422:16:42INFO: analyzing "public.ci_pipeline_variables"
35522:16:42INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
35622:16:42I, [2024-07-25T22:16:42.902958 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
35722:16:42I, [2024-07-25T22:16:42.904225 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
35822:16:42I, [2024-07-25T22:16:42.905043 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
35922:16:44INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
36022:16:44INFO: analyzing "public.ci_pipeline_variables"
36122:16:44INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
36222:16:49INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
36322:16:49INFO: analyzing "public.ci_job_artifacts"
36422:16:49INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
36522:16:49I, [2024-07-25T22:16:49.629731 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
36622:16:49I, [2024-07-25T22:16:49.631444 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
36722:16:49I, [2024-07-25T22:16:49.632769 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
36822:16:53INFO: analyzing "public.p_ci_stages" inheritance tree
36922:16:53INFO: analyzing "public.ci_stages"
37022:16:53INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
37122:16:53I, [2024-07-25T22:16:53.305159 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
37222:16:53I, [2024-07-25T22:16:53.306616 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37322:16:53I, [2024-07-25T22:16:53.307662 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37422:16:54I, [2024-07-25T22:16:54.689856 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
37522:17:01I, [2024-07-25T22:17:01.733549 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
37622:17:17I, [2024-07-25T22:17:17.292925 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
37722:17:17I, [2024-07-25T22:17:17.295074 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
37822:17:17I, [2024-07-25T22:17:17.295965 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
37922:17:30I, [2024-07-25T22:17:30.876886 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
38022:17:38I, [2024-07-25T22:17:38.693462 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
38122:17:53# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_project_statistics_storage_size_with_recent_size_spec.rb. It took 3 minutes 18.52 seconds. Expected to take 1 minute 37.09 seconds.
38222:17:53# [RSpecRunTime] RSpec elapsed time: 9 minutes 16.12 seconds. Current RSS: ~1207M. Threads: 4. load average: 1.13 1.15 1.35 1/412 492
38322:17:53.
38422:17:53# [RSpecRunTime] Starting example group ee/spec/lib/gitlab/background_migration/update_workspaces_config_version_spec.rb. Expected to take 1 minute 15.45 seconds.
38522:17:53Gitlab::BackgroundMigration::UpdateWorkspacesConfigVersion
38622:18:54 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
38722:18:54 #perform
38822:19:01 updates config_version and force_include_all_resources for existing non-terminated workspaces
38922:19:08 does not update workspaces with different config_version or actual_state
39022:19:08# [RSpecRunTime] RSpec elapsed time: 10 minutes 30.65 seconds. Current RSS: ~1234M. Threads: 4. load average: 1.25 1.19 1.35 1/413 493
39122:19:08.
39222:19:12I, [2024-07-25T22:19:12.537166 #356] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
39322:19:14INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
39422:19:14INFO: analyzing "public.ci_pipeline_variables"
39522:19:14INFO: "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:19:14I, [2024-07-25T22:19:14.420673 #356] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
39722:19:14I, [2024-07-25T22:19:14.421945 #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:19:14I, [2024-07-25T22:19:14.422913 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
39922:19:16INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
40022:19:16INFO: analyzing "public.ci_pipeline_variables"
40122:19:16INFO: "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:19:21INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
40322:19:21INFO: analyzing "public.ci_job_artifacts"
40422:19:21INFO: "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:19:21I, [2024-07-25T22:19:21.410866 #356] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
40622:19:21I, [2024-07-25T22:19:21.412167 #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:19:21I, [2024-07-25T22:19:21.412990 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40822:19:25INFO: analyzing "public.p_ci_stages" inheritance tree
40922:19:25INFO: analyzing "public.ci_stages"
41022:19:25INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
41122:19:25I, [2024-07-25T22:19:25.260067 #356] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
41222:19:25I, [2024-07-25T22:19:25.261418 #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:19:25I, [2024-07-25T22:19:25.262383 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41422:19:26I, [2024-07-25T22:19:26.776511 #356] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
41522:19:33I, [2024-07-25T22:19:33.862590 #356] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
41622:19:49I, [2024-07-25T22:19:49.006456 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
41722:19:49I, [2024-07-25T22:19:49.007692 #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:19:49I, [2024-07-25T22:19:49.008589 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41922:20:01I, [2024-07-25T22:20:01.901153 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
42022:20:09I, [2024-07-25T22:20:09.652498 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
42122:20:24# [RSpecRunTime] Finishing example group ee/spec/lib/gitlab/background_migration/update_workspaces_config_version_spec.rb. It took 2 minutes 30.47 seconds. Expected to take 1 minute 15.45 seconds.
42222:20:24# [RSpecRunTime] RSpec elapsed time: 11 minutes 46.65 seconds. Current RSS: ~1212M. Threads: 4. load average: 1.12 1.15 1.32 1/413 494
42322:20:24.
42422:20:24# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb. Expected to take 40.23 seconds.
42522:20:24Gitlab::BackgroundMigration::DeleteInvalidEpicIssues
42622:20:48 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
42722:20:48 #perform
42822:20:53 removes invalid epic issues
42922:20:59 logs deleted records
43022:21:04 prevents N+1 queries
43122:21:04# [RSpecRunTime] RSpec elapsed time: 12 minutes 26.88 seconds. Current RSS: ~1222M. Threads: 4. load average: 1.08 1.13 1.31 1/413 495
43222:21:04.
43322:21:07I, [2024-07-25T22:21:07.083337 #356] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
43422:21:07I, [2024-07-25T22:21:07.084807 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
43522:21:07I, [2024-07-25T22:21:07.085813 #356] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43622:21:19I, [2024-07-25T22:21:19.970673 #356] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
43722:21:28I, [2024-07-25T22:21:28.076075 #356] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
43822:21:42# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb. It took 1 minute 18.48 seconds. Expected to take 40.23 seconds.
43922:21:42# [RSpecRunTime] RSpec elapsed time: 13 minutes 5.19 seconds. Current RSS: ~1221M. Threads: 4. load average: 0.95 1.08 1.28 1/414 496
44022:21:42.
44122:21:42# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_in_batch_spec.rb. Expected to take 10.16 seconds.
44222:21:42Gitlab::BackgroundMigration::MigrateApproverToApprovalRulesInBatch
44322:21:43 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
44422:21:43 when there is no more MigrateApproverToApprovalRules jobs
44522:21:52 migrates individual target
44622:21:52# [RSpecRunTime] RSpec elapsed time: 13 minutes 15.07 seconds. Current RSS: ~1301M. Threads: 4. load average: 0.88 1.06 1.27 1/414 599
44722:21:52.
44822:22:03# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_in_batch_spec.rb. It took 21.0 seconds. Expected to take 10.16 seconds.
44922:22:04# [RSpecRunTime] RSpec elapsed time: 13 minutes 26.34 seconds. Current RSS: ~1227M. Threads: 4. load average: 0.89 1.06 1.27 1/414 600
45022:22:04.
45122:22:04# [RSpecRunTime] Starting example group ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb. Expected to take 9.56 seconds.
45222:22:04Gitlab::BackgroundMigration::UpdateWorkspacesConfigVersion3
45322:22:04 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
45422:22:04 #perform
45522:22:09 updates config_version and force_include_all_resources for existing non-terminated workspaces
45622:22:13 does not update workspaces with different config_version or actual_state
45722:22:13# [RSpecRunTime] RSpec elapsed time: 13 minutes 36.02 seconds. Current RSS: ~1230M. Threads: 4. load average: 0.99 1.07 1.27 1/414 601
45822:22:13.
45922:22:25# [RSpecRunTime] Finishing example group ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb. It took 21.12 seconds. Expected to take 9.56 seconds.
46022:22:25# [RSpecRunTime] RSpec elapsed time: 13 minutes 47.52 seconds. Current RSS: ~1224M. Threads: 4. load average: 0.91 1.05 1.26 1/414 602
46122:22:25.
46222:22:25# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/disable_allow_runner_registration_on_namespace_settings_for_gitlab_com_spec.rb. Expected to take 9.02 seconds.
46322:22:25Gitlab::BackgroundMigration::DisableAllowRunnerRegistrationOnNamespaceSettingsForGitlabCom
46422:22:25 example at ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24 (PENDING: Not yet implemented)
46522:22:25 #perform
46622:22:31 disables the namespace setting for namespaces with settings
46722:22:31 with end_id set to start_id
46822:22:35 disables the namespace setting for first namespace with settings
46922:22:35# [RSpecRunTime] RSpec elapsed time: 13 minutes 58.21 seconds. Current RSS: ~1221M. Threads: 4. load average: 1.16 1.10 1.28 1/414 603
47022:22:35.
47122:22:46# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/disable_allow_runner_registration_on_namespace_settings_for_gitlab_com_spec.rb. It took 21.81 seconds. Expected to take 9.02 seconds.
47222:22:47# [RSpecRunTime] RSpec elapsed time: 14 minutes 9.38 seconds. Current RSS: ~1203M. Threads: 4. load average: 1.13 1.10 1.27 1/414 604
47322:22:47.
47422:23:12auto_explain log contains 1057 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-background-migration-pg14-3-3.356.main.ndjson.gz
47522:23:12took 25.760326084
47622:23:35auto_explain log contains 1058 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-background-migration-pg14-3-3.356.ci.ndjson.gz
47722:23:35took 22.608228442
47822:23:35[TEST PROF INFO] EventProf results for sql.active_record
47922:23:35Total time: 08:49.622 of 14:02.091 (62.89%)
48022:23:35Total events: 383578
48122:23:35Top 5 slowest suites (by time):
48222:23:35Gitlab::Backg...tApprovalRules (./ee/spec/lib/ee/gitlab/background_migration/delete_orphaned_transferred_project_approval_rules_spec.rb:5) – 02:33.477 (96683 / 3) of 03:40.146 (69.72%)
48322:23:35Gitlab::Backg...WithRecentSize (./ee/spec/lib/ee/gitlab/background_migration/backfill_project_statistics_storage_size_with_recent_size_spec.rb:5) – 02:16.633 (91408 / 3) of 03:18.573 (68.81%)
48422:23:35Gitlab::Backg...sConfigVersion (./ee/spec/lib/gitlab/background_migration/update_workspaces_config_version_spec.rb:5) – 01:44.205 (73577 / 3) of 02:30.523 (69.23%)
48522:23:35Gitlab::Backg...oApprovalRules (./ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb:6) – 00:54.136 (54895 / 20) of 02:10.122 (41.6%)
48622:23:35Gitlab::Backg...alidEpicIssues (./ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb:6) – 00:49.439 (34653 / 4) of 01:18.640 (62.87%)
48722:23:35Knapsack report was generated. Preview:
48822:23:35{
48922:23:35 "ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb": 118.98566015400047,
49022:23:35 "ee/spec/lib/ee/gitlab/background_migration/delete_orphaned_transferred_project_approval_rules_spec.rb": 111.16073870599939,
49122:23:35 "ee/spec/lib/ee/gitlab/background_migration/backfill_project_statistics_storage_size_with_recent_size_spec.rb": 100.61546454099971,
49222:23:35 "ee/spec/lib/gitlab/background_migration/update_workspaces_config_version_spec.rb": 74.7106621110006,
49322:23:35 "ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb": 40.3708312789995,
49422:23:35 "ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_in_batch_spec.rb": 9.918176316999961,
49522:23:35 "ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb": 9.868208161999974,
49622:23:35 "ee/spec/lib/ee/gitlab/background_migration/disable_allow_runner_registration_on_namespace_settings_for_gitlab_com_spec.rb": 10.842855347000295
49722:23:35}
49822:23:35Knapsack global time execution for tests: 07m 56s
49922:23:35Pending: (Failures listed here are expected and do not affect your suite's status)
50022:23:35 1) Gitlab::BackgroundMigration::MigrateApproverToApprovalRules
50122:23:35 # Not yet implemented
50222:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
50322:23:35 2) Gitlab::BackgroundMigration::DeleteOrphanedTransferredProjectApprovalRules
50422:23:35 # Not yet implemented
50522:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
50622:23:35 3) Gitlab::BackgroundMigration::BackfillProjectStatisticsStorageSizeWithRecentSize
50722:23:35 # Not yet implemented
50822:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
50922:23:35 4) Gitlab::BackgroundMigration::UpdateWorkspacesConfigVersion
51022:23:35 # Not yet implemented
51122:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
51222:23:35 5) Gitlab::BackgroundMigration::DeleteInvalidEpicIssues
51322:23:35 # Not yet implemented
51422:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
51522:23:35 6) Gitlab::BackgroundMigration::MigrateApproverToApprovalRulesInBatch
51622:23:35 # Not yet implemented
51722:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
51822:23:35 7) Gitlab::BackgroundMigration::UpdateWorkspacesConfigVersion3
51922:23:35 # Not yet implemented
52022:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
52122:23:35 8) Gitlab::BackgroundMigration::DisableAllowRunnerRegistrationOnNamespaceSettingsForGitlabCom
52222:23:35 # Not yet implemented
52322:23:35 # ./spec/support/helpers/disable_namespace_organization_validation_helper.rb:24
52422:23:35Finished in 14 minutes 58 seconds (files took 2 minutes 22.9 seconds to load)
52522:23:3541 examples, 0 failures, 8 pending
52622:23:35Randomized with seed 32924
52722:23:43[TEST PROF INFO] Time spent in factories: 00:20.250 (2.11% of total time)
52822:23:44RSpec exited with 0.
52922:23:44No examples to retry, congrats!
530 22:23:44 Running after_script 00:11
53122:23:45Running after script...
53222:23:45$ source scripts/utils.sh
53322:23:45$ bundle exec gem list gitlab_quality-test_tooling
53422:23:49gitlab_quality-test_tooling (1.32.1)
53522:23:49$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
536 22:23:49 Report test failures 00:00
53822:23:49$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
539 22:23:49 Report test flakiness 00:00
54122:23:49$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
542 22:23:49 Report test slowness 00:00
54422:23:49$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
545 22:23:49 Report test slowness in MR note 00:02
54822:23:51$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
549 22:23:51 Report test files close to timing out 00:02
55222:23:53$ tooling/bin/push_job_metrics || true
55322:23:55[job-metrics] Pushing job metrics file for the CI/CD job.
55422:23:55[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
555 22:23:55 Saving cache for successful job 00:01
55622:23:56Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.4-17 due to policy
557 22:23:56 Uploading artifacts for successful job 00:06
55822:23:57Uploading artifacts...
55922:23:57auto_explain/: found 3 matching artifact files and directories
56022:23:57coverage/: found 5 matching artifact files and directories
56122:23:57crystalball/: found 2 matching artifact files and directories
56222:23:57WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
56322:23:57knapsack/: found 4 matching artifact files and directories
56422:23:57rspec/: found 16 matching artifact files and directories
56522:23:57WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
56622:23:57log/*.log: found 13 matching artifact files and directories
56722:23:58WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7432319295/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
56822:23:58WARNING: Retrying... context=artifacts-uploader error=request redirected
56922:24:00Uploading artifacts as "archive" to coordinator... 201 Created id=7432319295 responseStatus=201 Created token=glcbt-66
57022:24:00Uploading artifacts...
57122:24:01rspec/rspec-*.xml: found 1 matching artifact files and directories
57222:24:01WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7432319295/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
57322:24:01WARNING: Retrying... context=artifacts-uploader error=request redirected
57422:24:02Uploading artifacts as "junit" to coordinator... 201 Created id=7432319295 responseStatus=201 Created token=glcbt-66
575 22:24:02 Cleaning up project directory and file based variables 00:01
57622:24:22Job succeeded