Skip to content
Snippets Groups Projects

rspec migration pg14 no_gitaly_transactions 14/26

Passed Started by
🤖 GitLab Bot 🤖
@gitlab-bot
1Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
2 on green-1.shared-gitlab-org.runners-manager.gitlab.com/default Mf8beF5G, system ID: s_673deea48c2a
3 feature flags: FF_NETWORK_PER_BUILD:true
4Resolving secrets
5 Preparing the "docker+machine" executor 00:10
6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.5-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 ...
7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
8Authenticating with credentials from job payload (GitLab Registry)
9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
10Using 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 ...
11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
14Authenticating with credentials from job payload (GitLab Registry)
15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
16Using 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 ...
17Starting service redis:6.2-alpine ...
18Pulling docker image redis:6.2-alpine ...
19Using docker image sha256:36c1d3cfe18f4b12d4640576f7048068acbfc3625027b6bbb46bba0e31b11831 for redis:6.2-alpine with digest redis@sha256:e3b17ba9479deec4b7d1eeec1548a253acc5374d68d3b27937fcfe4df8d18c7e ...
20Waiting for services to be up and running (timeout 30 seconds)...
21Authenticating with credentials from job payload (GitLab Registry)
22Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.5-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 ...
23Using docker image sha256:b26253b493531cada33f45ef568f38c50a310442d05c39f517656e0f7d305dc7 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.5-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.1.5-golang-1.22-rust-1.73-node-20.12-postgresql-14@sha256:543339542a02edc250c2c0424c5077b673f1fca2877024fdc5fb670eff44f8cf ...
24 Preparing environment 00:01
25Running on runner-mf8bef5g-project-278964-concurrent-0 via runner-mf8bef5g-shared-gitlab-org-1722417662-5aa8f88a...
26 Getting source from Git repository 00:00
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
30 Restoring cache 00:21
31Checking cache for ruby-gems-debian-bookworm-ruby-3.1.5-17...
32Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.1.5-17
33Successfully extracted cache
34 Downloading artifacts 00:42
35Downloading artifacts for clone-gitlab-repo (7471344368)...
36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7471344368 responseStatus=200 OK token=glcbt-66
37WARNING: Part of .git directory is on the list of files to extract
38WARNING: This may introduce unexpected problems
39Downloading artifacts for compile-test-assets (7471344747)...
40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7471344747 responseStatus=200 OK token=glcbt-66
41Downloading artifacts for retrieve-tests-metadata (7471344898)...
42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7471344898 responseStatus=200 OK token=glcbt-66
43Downloading artifacts for setup-test-env (7471344810)...
44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7471344810 responseStatus=200 OK token=glcbt-66
45 Executing "step_script" stage of the job script
46Using docker image sha256:b26253b493531cada33f45ef568f38c50a310442d05c39f517656e0f7d305dc7 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.5-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.1.5-golang-1.22-rust-1.73-node-20.12-postgresql-14@sha256:543339542a02edc250c2c0424c5077b673f1fca2877024fdc5fb670eff44f8cf ...
47$ echo $FOSS_ONLY
48$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
49$ export GOPATH=$CI_PROJECT_DIR/.go
50$ mkdir -p $GOPATH
51$ source scripts/utils.sh
52$ source scripts/prepare_build.sh
53 Installing gems 00:23
82Using decomposed database config (config/database.yml.decomposed-postgresql)
83Geo DB won't be set up.
84Embedding DB won't be set up.
85 Setting up DBs 01:31
96$ source ./scripts/rspec_helpers.sh
97$ run_timed_command "gem install knapsack --no-document"
98$ gem install knapsack --no-document
99Successfully installed knapsack-4.0.0
1001 gem installed
101$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
102==> 'gem install knapsack --no-document' succeeded in 2 seconds.
103 Spawning Gitaly 00:05
107$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
108$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
109$ tooling/bin/create_job_metrics_file || true
110[job-metrics] Creating the job metrics file for the CI/CD job.
111$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
112 RSpec 27:29
113[10:15:09] Starting rspec_parallelized_job
114RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
115KNAPSACK_GENERATE_REPORT: true
116FLAKY_RSPEC_GENERATE_REPORT: true
117KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
118KNAPSACK_LOG_LEVEL: debug
119KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_no_gitaly_transactions_14_26_278964_report.json
120FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
121FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_no_gitaly_transactions_14_26_278964_report.json
122NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_no_gitaly_transactions_14_26_278964_report.json
123RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7471346399.txt
124CRYSTALBALL: true
125RSPEC_TESTS_MAPPING_ENABLED:
126RSPEC_TESTS_FILTER_FILE:
127Shell set options (set -o) enabled:
128braceexpand on
129hashall on
130interactive-comments on
131pipefail on
132Knapsack report generator started!
133warning: parser/current is loading parser/ruby31, which recognizes 3.1.6-compliant syntax, but you are running 3.1.5.
134Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
135Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
136# [RSpecRunTime] Starting RSpec timer...
137[TEST PROF INFO] EventProf enabled (sql.active_record)
138unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
139unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
140Test environment set up in 3.256986283 seconds
141# [RSpecRunTime] Starting example group spec/migrations/20230811103941_swap_vulnerability_user_mentions_note_id_to_bigint_for_self_hosts_spec.rb. Expected to take 2 minutes 31.86 seconds.
142SwapVulnerabilityUserMentionsNoteIdToBigintForSelfHosts
143 #up
144 when GitLab.com, dev, or test
145 behaves like column `note_id_convert_to_bigint` is already dropped
146 does not swaps the columns
147 when self-managed instance with the `note_id_convert_to_bigint` column already dropped
148 behaves like column `note_id_convert_to_bigint` is already dropped
149 does not swaps the columns
150# [RSpecRunTime] RSpec elapsed time: 2 minutes 8.11 seconds. Current RSS: ~1435M. Threads: 2. load average: 1.25 1.35 1.32 1/265 406
151.
152 when self-managed instance columns already swapped
153 does not swaps the columns
154# [RSpecRunTime] RSpec elapsed time: 2 minutes 19.66 seconds. Current RSS: ~1447M. Threads: 2. load average: 1.21 1.34 1.31 1/265 407
155.
156 when self-managed instance
157 swaps the columns
158# [RSpecRunTime] RSpec elapsed time: 2 minutes 39.62 seconds. Current RSS: ~1415M. Threads: 2. load average: 1.23 1.33 1.31 1/265 408
159.
160INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
161INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
162INFO: "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
163INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
164INFO: "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
165INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
166INFO: "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
167INFO: analyzing "public.p_ci_job_annotations" inheritance tree
168INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
169INFO: "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
170INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
171INFO: "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
172INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
173INFO: "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
174INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
175INFO: analyzing "public.ci_builds_metadata"
176INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
177INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
178INFO: "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
179INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
180INFO: "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
181INFO: analyzing "public.p_ci_builds" inheritance tree
182INFO: analyzing "public.ci_builds"
183INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
184INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
185INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
186INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
187INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
188I, [2024-07-31T10:20:24.433351 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
189I, [2024-07-31T10:20:45.923624 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
190INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
191INFO: analyzing "public.ci_pipeline_variables"
192INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
193I, [2024-07-31T10:20:47.572586 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
194I, [2024-07-31T10:20:47.573898 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
195I, [2024-07-31T10:20:47.574896 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
196INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
197INFO: analyzing "public.ci_pipeline_variables"
198INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
199INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
200INFO: analyzing "public.ci_job_artifacts"
201INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
202I, [2024-07-31T10:20:54.173917 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
203I, [2024-07-31T10:20:54.175158 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
204I, [2024-07-31T10:20:54.176021 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
205INFO: analyzing "public.p_ci_stages" inheritance tree
206INFO: analyzing "public.ci_stages"
207INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
208I, [2024-07-31T10:20:57.662756 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
209I, [2024-07-31T10:20:57.664666 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
210I, [2024-07-31T10:20:57.665620 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
211I, [2024-07-31T10:20:58.928796 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
212I, [2024-07-31T10:21:06.025670 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
213I, [2024-07-31T10:21:21.078101 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
214I, [2024-07-31T10:21:21.080054 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
215I, [2024-07-31T10:21:21.080984 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
216I, [2024-07-31T10:21:34.113946 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
217I, [2024-07-31T10:21:41.940927 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
218# [RSpecRunTime] Finishing example group spec/migrations/20230811103941_swap_vulnerability_user_mentions_note_id_to_bigint_for_self_hosts_spec.rb. It took 4 minutes 7.75 seconds. Expected to take 2 minutes 31.86 seconds.
219# [RSpecRunTime] RSpec elapsed time: 4 minutes 14.87 seconds. Current RSS: ~1361M. Threads: 2. load average: 1.09 1.25 1.28 1/267 409
220.
221# [RSpecRunTime] Starting example group spec/migrations/20230724071541_queue_backfill_default_branch_protection_namespace_setting_spec.rb. Expected to take 1 minute 48.44 seconds.
222QueueBackfillDefaultBranchProtectionNamespaceSetting
223main: == [advisory_lock_connection] object_id: 62326240, pg_backend_pid: 185
224main: == 20230724071541 QueueBackfillDefaultBranchProtectionNamespaceSetting: migrating
225main: == 20230724071541 QueueBackfillDefaultBranchProtectionNamespaceSetting: migrated (0.0724s)
226main: == [advisory_lock_connection] object_id: 62326240, pg_backend_pid: 185
227 schedules a new batched migration
228INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
229INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
230INFO: "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
231INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
232INFO: "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
233INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
234INFO: "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
235INFO: analyzing "public.p_ci_job_annotations" inheritance tree
236INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
237INFO: "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
238INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
239INFO: "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
240INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
241INFO: "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
242INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
243INFO: analyzing "public.ci_builds_metadata"
244INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
245INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
246INFO: "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
247INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
248INFO: "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
249INFO: analyzing "public.p_ci_builds" inheritance tree
250INFO: analyzing "public.ci_builds"
251INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
252INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
253INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
254INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
255INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
256I, [2024-07-31T10:23:59.922038 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
257I, [2024-07-31T10:24:21.023806 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
258INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
259INFO: analyzing "public.ci_pipeline_variables"
260INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
261I, [2024-07-31T10:24:22.684837 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
262I, [2024-07-31T10:24:22.686063 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
263I, [2024-07-31T10:24:22.686925 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
264INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
265INFO: analyzing "public.ci_pipeline_variables"
266INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
267INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
268INFO: analyzing "public.ci_job_artifacts"
269INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
270I, [2024-07-31T10:24:29.069630 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
271I, [2024-07-31T10:24:29.070901 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
272I, [2024-07-31T10:24:29.071822 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
273INFO: analyzing "public.p_ci_stages" inheritance tree
274INFO: analyzing "public.ci_stages"
275INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
276I, [2024-07-31T10:24:32.635828 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
277I, [2024-07-31T10:24:32.637093 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
278I, [2024-07-31T10:24:32.638122 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
279I, [2024-07-31T10:24:33.935095 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
280I, [2024-07-31T10:24:40.938809 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
281I, [2024-07-31T10:24:55.517622 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
282I, [2024-07-31T10:24:55.519020 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
283I, [2024-07-31T10:24:55.519971 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
284I, [2024-07-31T10:25:08.346964 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
285I, [2024-07-31T10:25:16.074841 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
286# [RSpecRunTime] Finishing example group spec/migrations/20230724071541_queue_backfill_default_branch_protection_namespace_setting_spec.rb. It took 3 minutes 34.16 seconds. Expected to take 1 minute 48.44 seconds.
287# [RSpecRunTime] RSpec elapsed time: 7 minutes 49.09 seconds. Current RSS: ~1358M. Threads: 2. load average: 0.91 1.08 1.20 1/267 411
288.
289# [RSpecRunTime] Starting example group spec/migrations/20230823140934_add_linked_items_widget_to_ticket_work_item_type_spec.rb. Expected to take 1 minute 37.77 seconds.
290AddLinkedItemsWidgetToTicketWorkItemType
291 behaves like migration that adds a widget to a work item type
292 #up
293main: == [advisory_lock_connection] object_id: 103954320, pg_backend_pid: 205
294main: == 20230823140934 AddLinkedItemsWidgetToTicketWorkItemType: migrating =========
295main: == 20230823140934 AddLinkedItemsWidgetToTicketWorkItemType: migrated (0.0485s)
296main: == [advisory_lock_connection] object_id: 103954320, pg_backend_pid: 205
297 adds widget to work item type
298 when type does not exist
299main: == [advisory_lock_connection] object_id: 104528900, pg_backend_pid: 208
300main: == 20230823140934 AddLinkedItemsWidgetToTicketWorkItemType: migrating =========
301main: -- Ticket work item type does not exist, skipping widget creation
302main: == 20230823140934 AddLinkedItemsWidgetToTicketWorkItemType: migrated (0.0139s)
303main: == [advisory_lock_connection] object_id: 104528900, pg_backend_pid: 208
304 skips creating the new widget definition
305# [RSpecRunTime] RSpec elapsed time: 9 minutes 24.16 seconds. Current RSS: ~1388M. Threads: 2. load average: 0.98 1.05 1.18 1/266 412
306.
307 #down
308main: == [advisory_lock_connection] object_id: 104983540, pg_backend_pid: 210
309main: == 20230823140934 AddLinkedItemsWidgetToTicketWorkItemType: migrating =========
310main: == 20230823140934 AddLinkedItemsWidgetToTicketWorkItemType: migrated (0.0528s)
311main: == [advisory_lock_connection] object_id: 104983540, pg_backend_pid: 210
312 removes widget from work item type
313# [RSpecRunTime] RSpec elapsed time: 9 minutes 39.02 seconds. Current RSS: ~1339M. Threads: 2. load average: 0.98 1.05 1.18 1/266 413
314.
315INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
316INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
317INFO: "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
318INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
319INFO: "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
320INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
321INFO: "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
322INFO: analyzing "public.p_ci_job_annotations" inheritance tree
323INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
324INFO: "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
325INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
326INFO: "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
327INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
328INFO: "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
329INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
330INFO: analyzing "public.ci_builds_metadata"
331INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
332INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
333INFO: "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
334INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
335INFO: "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
336INFO: analyzing "public.p_ci_builds" inheritance tree
337INFO: analyzing "public.ci_builds"
338INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
339INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
340INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
341INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
342INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
343I, [2024-07-31T10:27:21.393407 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
344I, [2024-07-31T10:27:43.381902 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
345INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
346INFO: analyzing "public.ci_pipeline_variables"
347INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
348I, [2024-07-31T10:27:45.082072 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
349I, [2024-07-31T10:27:45.083514 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
350I, [2024-07-31T10:27:45.084535 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
351INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
352INFO: analyzing "public.ci_pipeline_variables"
353INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
354INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
355INFO: analyzing "public.ci_job_artifacts"
356INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
357I, [2024-07-31T10:27:51.697320 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
358I, [2024-07-31T10:27:51.699096 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
359I, [2024-07-31T10:27:51.700033 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
360INFO: analyzing "public.p_ci_stages" inheritance tree
361INFO: analyzing "public.ci_stages"
362INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
363I, [2024-07-31T10:27:55.377893 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
364I, [2024-07-31T10:27:55.379611 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
365I, [2024-07-31T10:27:55.380553 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
366I, [2024-07-31T10:27:56.803461 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
367I, [2024-07-31T10:28:03.795100 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
368I, [2024-07-31T10:28:18.769196 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
369I, [2024-07-31T10:28:18.771076 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
370I, [2024-07-31T10:28:18.771898 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
371I, [2024-07-31T10:28:31.857637 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
372I, [2024-07-31T10:28:39.392092 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
373# [RSpecRunTime] Finishing example group spec/migrations/20230823140934_add_linked_items_widget_to_ticket_work_item_type_spec.rb. It took 3 minutes 23.33 seconds. Expected to take 1 minute 37.77 seconds.
374# [RSpecRunTime] RSpec elapsed time: 11 minutes 12.48 seconds. Current RSS: ~1366M. Threads: 2. load average: 0.88 1.00 1.15 1/266 414
375.
376# [RSpecRunTime] Starting example group spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb. Expected to take 1 minute 28.46 seconds.
377QueueBackfillProjectStatisticsStorageSizeWithRecentSize
378main: == [advisory_lock_connection] object_id: 143759600, pg_backend_pid: 228
379main: == 20230823090001 QueueBackfillProjectStatisticsStorageSizeWithRecentSize: migrating
380main: == 20230823090001 QueueBackfillProjectStatisticsStorageSizeWithRecentSize: migrated (0.0671s)
381main: == [advisory_lock_connection] object_id: 143759600, pg_backend_pid: 228
382 schedules a new batched migration
383INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
384INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
385INFO: "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
386INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
387INFO: "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
388INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
389INFO: "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
390INFO: analyzing "public.p_ci_job_annotations" inheritance tree
391INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
392INFO: "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
393INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
394INFO: "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
395INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
396INFO: "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
397INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
398INFO: analyzing "public.ci_builds_metadata"
399INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
400INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
401INFO: "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
402INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
403INFO: "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
404INFO: analyzing "public.p_ci_builds" inheritance tree
405INFO: analyzing "public.ci_builds"
406INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
407INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
408INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
409INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
410INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
411I, [2024-07-31T10:30:33.881396 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
412I, [2024-07-31T10:30:55.863574 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
413INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
414INFO: analyzing "public.ci_pipeline_variables"
415INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
416I, [2024-07-31T10:30:57.622664 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
417I, [2024-07-31T10:30:57.624068 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
418I, [2024-07-31T10:30:57.625029 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
419INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
420INFO: analyzing "public.ci_pipeline_variables"
421INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
422INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
423INFO: analyzing "public.ci_job_artifacts"
424INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
425I, [2024-07-31T10:31:04.362887 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
426I, [2024-07-31T10:31:04.364042 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
427I, [2024-07-31T10:31:04.364915 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
428INFO: analyzing "public.p_ci_stages" inheritance tree
429INFO: analyzing "public.ci_stages"
430INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
431I, [2024-07-31T10:31:08.115152 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
432I, [2024-07-31T10:31:08.117226 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
433I, [2024-07-31T10:31:08.118333 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
434I, [2024-07-31T10:31:09.399428 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
435I, [2024-07-31T10:31:16.347241 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
436I, [2024-07-31T10:31:31.258642 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
437I, [2024-07-31T10:31:31.260165 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
438I, [2024-07-31T10:31:31.260978 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
439I, [2024-07-31T10:31:44.159852 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
440I, [2024-07-31T10:31:51.677289 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
441# [RSpecRunTime] Finishing example group spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb. It took 3 minutes 12.41 seconds. Expected to take 1 minute 28.46 seconds.
442# [RSpecRunTime] RSpec elapsed time: 14 minutes 24.95 seconds. Current RSS: ~1354M. Threads: 2. load average: 1.02 1.03 1.13 1/266 415
443.
444# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240214040351_swap_columns_for_auto_canceled_by_id_between_ci_builds_and_ci_pipelines_spec.rb. Expected to take 55.54 seconds.
445SwapColumnsForAutoCanceledByIdBetweenCiBuildsAndCiPipelines
446 behaves like swap conversion columns
447main: == [advisory_lock_connection] object_id: 178468980, pg_backend_pid: 246
448main: == 20240219040351 SwapColumnsForAutoCanceledByIdBetweenCiBuildsAndCiPipelines: migrating
449main: -- indexes(:p_ci_builds)
450main: -> 0.0562s
451main: -- quote_table_name(:ci_pipelines)
452main: -> 0.0000s
453main: -- quote_table_name(:p_ci_builds)
454main: -> 0.0000s
455main: -- execute("LOCK TABLE \"ci_pipelines\", \"p_ci_builds\" IN ACCESS EXCLUSIVE MODE")
456main: -> 0.0015s
457main: -- clear_cache!(nil)
458main: -> 0.0000s
459main: -- quote_table_name(:p_ci_builds)
460main: -> 0.0000s
461main: -- quote_column_name(:auto_canceled_by_id_convert_to_bigint)
462main: -> 0.0000s
463main: -- quote_column_name(:temp_name_for_renaming)
464main: -> 0.0000s
465main: -- execute("ALTER TABLE \"p_ci_builds\"\n RENAME COLUMN \"auto_canceled_by_id_convert_to_bigint\" TO \"temp_name_for_renaming\"\n")
466main: -> 0.0017s
467main: -- clear_cache!(nil)
468main: -> 0.0000s
469main: -- quote_table_name(:p_ci_builds)
470main: -> 0.0000s
471main: -- quote_column_name(:auto_canceled_by_id)
472main: -> 0.0000s
473main: -- quote_column_name(:auto_canceled_by_id_convert_to_bigint)
474main: -> 0.0000s
475main: -- execute("ALTER TABLE \"p_ci_builds\"\n RENAME COLUMN \"auto_canceled_by_id\" TO \"auto_canceled_by_id_convert_to_bigint\"\n")
476main: -> 0.0015s
477main: -- clear_cache!(nil)
478main: -> 0.0000s
479main: -- quote_table_name(:p_ci_builds)
480main: -> 0.0000s
481main: -- quote_column_name(:temp_name_for_renaming)
482main: -> 0.0000s
483main: -- quote_column_name(:auto_canceled_by_id)
484main: -> 0.0000s
485main: -- execute("ALTER TABLE \"p_ci_builds\"\n RENAME COLUMN \"temp_name_for_renaming\" TO \"auto_canceled_by_id\"\n")
486main: -> 0.0014s
487main: -- quote_table_name(:trigger_10ee1357e825)
488main: -> 0.0000s
489main: -- execute("ALTER FUNCTION \"trigger_10ee1357e825\" RESET ALL")
490main: -> 0.0011s
491main: -- execute("LOCK TABLE ci_pipelines, p_ci_builds IN ACCESS EXCLUSIVE MODE")
492main: -> 0.0026s
493main: -- remove_foreign_key(:p_ci_builds, :ci_pipelines, {:name=>:fk_a2141b1522})
494main: -> 0.0102s
495main: -- quote_table_name("public.ci_builds")
496main: -> 0.0001s
497main: -- quote_column_name(:fk_dd3c83bdee)
498main: -> 0.0000s
499main: -- quote_column_name(:fk_a2141b1522)
500main: -> 0.0000s
501main: -- execute("ALTER TABLE \"public\".\"ci_builds\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")
502main: -> 0.0026s
503main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_101")
504main: -> 0.0001s
505main: -- quote_column_name(:fk_dd3c83bdee)
506main: -> 0.0000s
507main: -- quote_column_name(:fk_a2141b1522)
508main: -> 0.0001s
509main: -- execute("ALTER TABLE \"gitlab_partitions_dynamic\".\"ci_builds_101\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")
510main: -> 0.0015s
511main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_102")
512main: -> 0.0000s
513main: -- quote_column_name(:fk_dd3c83bdee)
514main: -> 0.0001s
515main: -- quote_column_name(:fk_a2141b1522)
516main: -> 0.0000s
517main: -- execute("ALTER TABLE \"gitlab_partitions_dynamic\".\"ci_builds_102\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")
518main: -> 0.0014s
519main: -- quote_table_name(:p_ci_builds)
520main: -> 0.0000s
521main: -- quote_column_name(:fk_dd3c83bdee)
522main: -> 0.0000s
523main: -- quote_column_name(:fk_a2141b1522)
524main: -> 0.0000s
525main: -- execute("ALTER TABLE \"p_ci_builds\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")
526main: -> 0.0017s
527main: -- indexes(:p_ci_builds)
528main: -> 0.0533s
529main: -- indexes(:p_ci_builds)
530main: -> 0.0526s
531main: -- indexes("public.ci_builds")
532main: -> 0.0542s
533main: -- indexes("public.ci_builds")
534main: -> 0.0549s
535main: -- quote_table_name("public.index_ffe1233676")
536main: -> 0.0000s
537main: -- quote_table_name("temp_b1a20ba0f9")
538main: -> 0.0000s
539main: -- execute("ALTER INDEX \"public\".\"index_ffe1233676\" RENAME TO \"temp_b1a20ba0f9\"")
540main: -> 0.0013s
541main: -- quote_table_name("public.index_ci_builds_on_auto_canceled_by_id")
542main: -> 0.0000s
543main: -- quote_table_name("index_ffe1233676")
544main: -> 0.0000s
545main: -- execute("ALTER INDEX \"public\".\"index_ci_builds_on_auto_canceled_by_id\" RENAME TO \"index_ffe1233676\"")
546main: -> 0.0011s
547main: -- quote_table_name("public.temp_b1a20ba0f9")
548main: -> 0.0000s
549main: -- quote_table_name("index_ci_builds_on_auto_canceled_by_id")
550main: -> 0.0000s
551main: -- execute("ALTER INDEX \"public\".\"temp_b1a20ba0f9\" RENAME TO \"index_ci_builds_on_auto_canceled_by_id\"")
552main: -> 0.0011s
553main: -- indexes("gitlab_partitions_dynamic.ci_builds_101")
554main: -> 0.0527s
555main: -- indexes("gitlab_partitions_dynamic.ci_builds_101")
556main: -> 0.0525s
557main: -- quote_table_name("gitlab_partitions_dynamic.index_be05aa07e1")
558main: -> 0.0000s
559main: -- quote_table_name("temp_64259f0ae9")
560main: -> 0.0000s
561main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"index_be05aa07e1\" RENAME TO \"temp_64259f0ae9\"")
562main: -> 0.0013s
563main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_101_auto_canceled_by_id_idx")
564main: -> 0.0000s
565main: -- quote_table_name("index_be05aa07e1")
566main: -> 0.0000s
567main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"ci_builds_101_auto_canceled_by_id_idx\" RENAME TO \"index_be05aa07e1\"")
568main: -> 0.0011s
569main: -- quote_table_name("gitlab_partitions_dynamic.temp_64259f0ae9")
570main: -> 0.0000s
571main: -- quote_table_name("ci_builds_101_auto_canceled_by_id_idx")
572main: -> 0.0000s
573main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"temp_64259f0ae9\" RENAME TO \"ci_builds_101_auto_canceled_by_id_idx\"")
574main: -> 0.0013s
575main: -- indexes("gitlab_partitions_dynamic.ci_builds_102")
576main: -> 0.0526s
577main: -- indexes("gitlab_partitions_dynamic.ci_builds_102")
578main: -> 0.0549s
579main: -- quote_table_name("gitlab_partitions_dynamic.index_8d72ef5f95")
580main: -> 0.0000s
581main: -- quote_table_name("temp_a0f25a97bc")
582main: -> 0.0000s
583main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"index_8d72ef5f95\" RENAME TO \"temp_a0f25a97bc\"")
584main: -> 0.0016s
585main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_102_auto_canceled_by_id_idx")
586main: -> 0.0000s
587main: -- quote_table_name("index_8d72ef5f95")
588main: -> 0.0000s
589main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"ci_builds_102_auto_canceled_by_id_idx\" RENAME TO \"index_8d72ef5f95\"")
590main: -> 0.0013s
591main: -- quote_table_name("gitlab_partitions_dynamic.temp_a0f25a97bc")
592main: -> 0.0000s
593main: -- quote_table_name("ci_builds_102_auto_canceled_by_id_idx")
594main: -> 0.0000s
595main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"temp_a0f25a97bc\" RENAME TO \"ci_builds_102_auto_canceled_by_id_idx\"")
596main: -> 0.0013s
597main: -- quote_table_name("p_ci_builds_auto_canceled_by_id_bigint_idx")
598main: -> 0.0000s
599main: -- quote_table_name("temp_3f76d2212d")
600main: -> 0.0000s
601main: -- execute("ALTER INDEX \"p_ci_builds_auto_canceled_by_id_bigint_idx\" RENAME TO \"temp_3f76d2212d\"")
602main: -> 0.0016s
603main: -- quote_table_name("p_ci_builds_auto_canceled_by_id_idx")
604main: -> 0.0000s
605main: -- quote_table_name(:p_ci_builds_auto_canceled_by_id_bigint_idx)
606main: -> 0.0000s
607main: -- execute("ALTER INDEX \"p_ci_builds_auto_canceled_by_id_idx\" RENAME TO \"p_ci_builds_auto_canceled_by_id_bigint_idx\"")
608main: -> 0.0011s
609main: -- quote_table_name("temp_3f76d2212d")
610main: -> 0.0000s
611main: -- quote_table_name(:p_ci_builds_auto_canceled_by_id_idx)
612main: -> 0.0000s
613main: -- execute("ALTER INDEX \"temp_3f76d2212d\" RENAME TO \"p_ci_builds_auto_canceled_by_id_idx\"")
614main: -> 0.0011s
615main: -- remove_index(:p_ci_builds, {:name=>:p_ci_builds_auto_canceled_by_id_bigint_idx})
616main: -> 0.0015s
617main: == 20240219040351 SwapColumnsForAutoCanceledByIdBetweenCiBuildsAndCiPipelines: migrated (0.6331s)
618main: == [advisory_lock_connection] object_id: 178468980, pg_backend_pid: 246
619 correctly swaps conversion columns
620# [RSpecRunTime] RSpec elapsed time: 15 minutes 23.9 seconds. Current RSS: ~1405M. Threads: 2. load average: 1.41 1.12 1.15 1/266 416
621.
622INFO: analyzing "public.p_ci_stages" inheritance tree
623INFO: analyzing "public.ci_stages"
624INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
625I, [2024-07-31T10:33:04.036373 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
626I, [2024-07-31T10:33:04.037870 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
627I, [2024-07-31T10:33:04.038815 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
628I, [2024-07-31T10:33:05.392779 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
629I, [2024-07-31T10:33:12.526816 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
630I, [2024-07-31T10:33:27.730170 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
631I, [2024-07-31T10:33:27.731383 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
632I, [2024-07-31T10:33:27.732151 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
633I, [2024-07-31T10:33:40.657498 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
634I, [2024-07-31T10:33:48.110865 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
635# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240214040351_swap_columns_for_auto_canceled_by_id_between_ci_builds_and_ci_pipelines_spec.rb. It took 1 minute 56.47 seconds. Expected to take 55.54 seconds.
636# [RSpecRunTime] RSpec elapsed time: 16 minutes 21.48 seconds. Current RSS: ~1349M. Threads: 2. load average: 1.16 1.10 1.14 1/267 417
637.
638# [RSpecRunTime] Starting example group spec/migrations/20240209153920_queue_purge_security_scans_with_empty_finding_data_spec.rb. Expected to take 53.67 seconds.
639QueuePurgeSecurityScansWithEmptyFindingData
640main: == [advisory_lock_connection] object_id: 206650360, pg_backend_pid: 262
641main: == 20240209153920 QueuePurgeSecurityScansWithEmptyFindingData: migrating ======
642main: == 20240209153920 QueuePurgeSecurityScansWithEmptyFindingData: migrated (0.0169s)
643main: == [advisory_lock_connection] object_id: 206650360, pg_backend_pid: 262
644 does not schedule a new batched migration for CE
645INFO: analyzing "public.p_ci_stages" inheritance tree
646INFO: analyzing "public.ci_stages"
647INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
648I, [2024-07-31T10:35:03.820792 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
649I, [2024-07-31T10:35:03.822255 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
650I, [2024-07-31T10:35:03.823265 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
651I, [2024-07-31T10:35:05.227076 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
652I, [2024-07-31T10:35:12.347158 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
653I, [2024-07-31T10:35:27.772219 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
654I, [2024-07-31T10:35:27.773435 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
655I, [2024-07-31T10:35:27.774286 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
656I, [2024-07-31T10:35:40.851145 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
657I, [2024-07-31T10:35:48.337929 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
658# [RSpecRunTime] Finishing example group spec/migrations/20240209153920_queue_purge_security_scans_with_empty_finding_data_spec.rb. It took 2 minutes 0.04 second. Expected to take 53.67 seconds.
659# [RSpecRunTime] RSpec elapsed time: 18 minutes 21.59 seconds. Current RSS: ~1327M. Threads: 2. load average: 1.05 1.11 1.14 1/267 418
660.
661# [RSpecRunTime] Starting example group spec/migrations/20240515102108_persist_usage_data_keys_that_should_not_expire_spec.rb. Expected to take 43.23 seconds.
662PersistUsageDataKeysThatShouldNotExpire
663 #up
664 behaves like a Redis key does not expire
665main: == [advisory_lock_connection] object_id: 225206600, pg_backend_pid: 277
666main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrating ==========
667main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrated (0.0399s) =
668main: == [advisory_lock_connection] object_id: 225206600, pg_backend_pid: 277
669 removes the expiry date from the key
670# [RSpecRunTime] RSpec elapsed time: 18 minutes 47.72 seconds. Current RSS: ~1368M. Threads: 2. load average: 1.03 1.10 1.14 1/266 419
671.
672 behaves like a Redis key does not expire
673main: == [advisory_lock_connection] object_id: 225837680, pg_backend_pid: 279
674main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrating ==========
675main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrated (0.0449s) =
676main: == [advisory_lock_connection] object_id: 225837680, pg_backend_pid: 279
677 removes the expiry date from the key
678# [RSpecRunTime] RSpec elapsed time: 18 minutes 53.86 seconds. Current RSS: ~1390M. Threads: 2. load average: 1.03 1.09 1.13 1/266 420
679.
680 behaves like a Redis key does not expire
681main: == [advisory_lock_connection] object_id: 226144840, pg_backend_pid: 281
682main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrating ==========
683main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrated (0.0396s) =
684main: == [advisory_lock_connection] object_id: 226144840, pg_backend_pid: 281
685 removes the expiry date from the key
686# [RSpecRunTime] RSpec elapsed time: 18 minutes 58.97 seconds. Current RSS: ~1445M. Threads: 2. load average: 0.94 1.08 1.13 1/266 421
687.
688 behaves like a Redis key that expire
689main: == [advisory_lock_connection] object_id: 226757560, pg_backend_pid: 283
690main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrating ==========
691main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrated (0.0406s) =
692main: == [advisory_lock_connection] object_id: 226757560, pg_backend_pid: 283
693 does not remove the expiry date from the key
694# [RSpecRunTime] RSpec elapsed time: 19 minutes 4.02 seconds. Current RSS: ~1478M. Threads: 2. load average: 0.95 1.07 1.13 1/266 422
695.
696 behaves like a Redis key that expire
697main: == [advisory_lock_connection] object_id: 227361440, pg_backend_pid: 286
698main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrating ==========
699main: == 20240515102108 PersistUsageDataKeysThatShouldNotExpire: migrated (0.0390s) =
700main: == [advisory_lock_connection] object_id: 227361440, pg_backend_pid: 286
701 does not remove the expiry date from the key
702# [RSpecRunTime] RSpec elapsed time: 19 minutes 9.07 seconds. Current RSS: ~1482M. Threads: 2. load average: 0.87 1.06 1.12 1/266 423
703.
704I, [2024-07-31T10:36:58.749038 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
705I, [2024-07-31T10:37:06.118437 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
706# [RSpecRunTime] Finishing example group spec/migrations/20240515102108_persist_usage_data_keys_that_should_not_expire_spec.rb. It took 1 minute 17.47 seconds. Expected to take 43.23 seconds.
707# [RSpecRunTime] RSpec elapsed time: 19 minutes 39.12 seconds. Current RSS: ~1343M. Threads: 2. load average: 0.99 1.07 1.12 1/266 424
708.
709# [RSpecRunTime] Starting example group spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb. Expected to take 19.91 seconds.
710QueueBackfillDastSiteProfileSecretVariablesProjectId
711main: == [advisory_lock_connection] object_id: 234054820, pg_backend_pid: 297
712main: == 20240605192711 QueueBackfillDastSiteProfileSecretVariablesProjectId: migrating
713main: == 20240605192711 QueueBackfillDastSiteProfileSecretVariablesProjectId: migrated (0.0722s)
714main: == [advisory_lock_connection] object_id: 234054820, pg_backend_pid: 297
715 schedules a new batched migration
716I, [2024-07-31T10:37:44.478416 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
717I, [2024-07-31T10:37:51.838622 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
718# [RSpecRunTime] Finishing example group spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb. It took 45.77 seconds. Expected to take 19.91 seconds.
719# [RSpecRunTime] RSpec elapsed time: 20 minutes 24.94 seconds. Current RSS: ~1339M. Threads: 2. load average: 1.04 1.07 1.12 1/266 425
720.
721# [RSpecRunTime] Starting example group spec/migrations/20240717093514_requeue_backfill_partition_id_ci_daily_build_group_report_result_spec.rb. Expected to take 17.7 seconds.
722RequeueBackfillPartitionIdCiDailyBuildGroupReportResult
723 #down
724ci: == [advisory_lock_connection] object_id: 238610660, pg_backend_pid: 343
725ci: == 20240717093514 RequeueBackfillPartitionIdCiDailyBuildGroupReportResult: migrating
726ci: == 20240717093514 RequeueBackfillPartitionIdCiDailyBuildGroupReportResult: migrated (0.0939s)
727ci: == [advisory_lock_connection] object_id: 238610660, pg_backend_pid: 343
728 deletes all batched migration records
729# [RSpecRunTime] RSpec elapsed time: 20 minutes 34.26 seconds. Current RSS: ~1415M. Threads: 2. load average: 1.03 1.07 1.12 1/266 426
730.
731 #up
732 with migration present
733 when migration finished successfully
734ci: == [advisory_lock_connection] object_id: 239811600, pg_backend_pid: 350
735ci: == 20240717093514 RequeueBackfillPartitionIdCiDailyBuildGroupReportResult: migrating
736ci: == 20240717093514 RequeueBackfillPartitionIdCiDailyBuildGroupReportResult: migrated (0.0880s)
737ci: == [advisory_lock_connection] object_id: 239811600, pg_backend_pid: 350
738 does not raise exception
739ci: == [advisory_lock_connection] object_id: 240444260, pg_backend_pid: 356
740ci: == 20240717093514 RequeueBackfillPartitionIdCiDailyBuildGroupReportResult: migrating
741ci: == 20240717093514 RequeueBackfillPartitionIdCiDailyBuildGroupReportResult: migrated (0.0860s)
742ci: == [advisory_lock_connection] object_id: 240444260, pg_backend_pid: 356
743 schedules background jobs for each batch of ci_daily_build_group_report_results
744# [RSpecRunTime] RSpec elapsed time: 20 minutes 43.19 seconds. Current RSS: ~1473M. Threads: 2. load average: 1.17 1.10 1.13 1/267 427
745.
746# [RSpecRunTime] Finishing example group spec/migrations/20240717093514_requeue_backfill_partition_id_ci_daily_build_group_report_result_spec.rb. It took 30.68 seconds. Expected to take 17.7 seconds.
747# [RSpecRunTime] RSpec elapsed time: 20 minutes 55.68 seconds. Current RSS: ~1378M. Threads: 2. load average: 1.07 1.08 1.12 1/267 428
748.
749# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240617114649_remove_subscription_history_with_null_namespace_id_spec.rb. Expected to take 12.9 seconds.
750RemoveSubscriptionHistoryWithNullNamespaceId
751 #up
752main: == [advisory_lock_connection] object_id: 242859560, pg_backend_pid: 399
753main: == 20240617114649 RemoveSubscriptionHistoryWithNullNamespaceId: migrating =====
754main: == 20240617114649 RemoveSubscriptionHistoryWithNullNamespaceId: migrated (0.0273s)
755main: == [advisory_lock_connection] object_id: 242859560, pg_backend_pid: 399
756 removes gitlab_subscription_histories records with null namespace_id
757# [RSpecRunTime] RSpec elapsed time: 21 minutes 10.04 seconds. Current RSS: ~1388M. Threads: 2. load average: 1.14 1.09 1.12 3/267 429
758.
759I, [2024-07-31T10:38:56.085107 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
760# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240617114649_remove_subscription_history_with_null_namespace_id_spec.rb. It took 33.38 seconds. Expected to take 12.9 seconds.
761# [RSpecRunTime] RSpec elapsed time: 21 minutes 29.13 seconds. Current RSS: ~1346M. Threads: 2. load average: 1.10 1.08 1.12 1/267 430
762.
763# [RSpecRunTime] Starting example group spec/migrations/20240716133952_queue_backfill_evidences_project_id_spec.rb. Expected to take 6.8 seconds.
764QueueBackfillEvidencesProjectId
765main: == [advisory_lock_connection] object_id: 246582220, pg_backend_pid: 409
766main: == 20240716133952 QueueBackfillEvidencesProjectId: migrating ==================
767main: == 20240716133952 QueueBackfillEvidencesProjectId: migrated (0.0657s) =========
768main: == [advisory_lock_connection] object_id: 246582220, pg_backend_pid: 409
769 schedules a new batched migration
770# [RSpecRunTime] Finishing example group spec/migrations/20240716133952_queue_backfill_evidences_project_id_spec.rb. It took 16.87 seconds. Expected to take 6.8 seconds.
771# [RSpecRunTime] RSpec elapsed time: 21 minutes 46.06 seconds. Current RSS: ~1366M. Threads: 2. load average: 1.08 1.08 1.11 1/267 431
772.
773auto_explain log contains 967 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-no-gitaly-transactions-14-26.328.main.ndjson.gz
774took 31.795261683
775auto_explain log contains 968 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-no-gitaly-transactions-14-26.328.ci.ndjson.gz
776took 28.317165411
777[TEST PROF INFO] EventProf results for sql.active_record
778Total time: 13:51.445 of 21:38.999 (64.01%)
779Total events: 575288
780Top 5 slowest suites (by time):
781QueueBackfill...mespaceSetting (./spec/migrations/20230724071541_queue_backfill_default_branch_protection_namespace_setting_spec.rb:6) – 02:28.587 (93276 / 1) of 03:34.218 (69.36%)
782SwapVulnerabi...ntForSelfHosts (./spec/migrations/20230811103941_swap_vulnerability_user_mentions_note_id_to_bigint_for_self_hosts_spec.rb:6) – 02:20.488 (96057 / 4) of 04:07.812 (56.69%)
783QueueBackfill...WithRecentSize (./spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb:6) – 02:11.402 (87914 / 1) of 03:12.468 (68.27%)
784AddLinkedItem...etWorkItemType (./spec/migrations/20230823140934_add_linked_items_widget_to_ticket_work_item_type_spec.rb:6) – 02:08.569 (85876 / 3) of 03:23.384 (63.21%)
785QueuePurgeSec...ptyFindingData (./spec/migrations/20240209153920_queue_purge_security_scans_with_empty_finding_data_spec.rb:6) – 01:22.988 (59845 / 1) of 02:00.100 (69.1%)
786Knapsack report was generated. Preview:
787{
788 "spec/migrations/20230811103941_swap_vulnerability_user_mentions_note_id_to_bigint_for_self_hosts_spec.rb": 153.7240108169999,
789 "spec/migrations/20230724071541_queue_backfill_default_branch_protection_namespace_setting_spec.rb": 108.80657402499992,
790 "spec/migrations/20230823140934_add_linked_items_widget_to_ticket_work_item_type_spec.rb": 110.07099118700035,
791 "spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb": 99.01980567100054,
792 "spec/migrations/db/post_migrate/20240214040351_swap_columns_for_auto_canceled_by_id_between_ci_builds_and_ci_pipelines_spec.rb": 59.10144970100009,
793 "spec/migrations/20240209153920_queue_purge_security_scans_with_empty_finding_data_spec.rb": 59.486393678999775,
794 "spec/migrations/20240515102108_persist_usage_data_keys_that_should_not_expire_spec.rb": 47.645873883000604,
795 "spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb": 20.354102252999837,
796 "spec/migrations/20240717093514_requeue_backfill_partition_id_ci_daily_build_group_report_result_spec.rb": 18.408494124000754,
797 "spec/migrations/db/post_migrate/20240617114649_remove_subscription_history_with_null_namespace_id_spec.rb": 14.51786098499997,
798 "spec/migrations/20240716133952_queue_backfill_evidences_project_id_spec.rb": 7.306416739999804
799}
800Knapsack global time execution for tests: 11m 38s
801Finished in 22 minutes 47 seconds (files took 2 minutes 25.3 seconds to load)
80222 examples, 0 failures
803Randomized with seed 60498
804[TEST PROF INFO] Time spent in factories: 00:00.207 (0.01% of total time)
805RSpec exited with 0.
806No examples to retry, congrats!
807 Running after_script 00:12
808Running after script...
809$ source scripts/utils.sh
810$ bundle exec gem list gitlab_quality-test_tooling
811gitlab_quality-test_tooling (1.33.0)
812$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
813 Report test failures 00:03
816$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
817 Report test flakiness 00:00
819$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
820 Report test slowness 00:02
823$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
824 Report test slowness in MR note 00:00
826$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
827 Report test files close to timing out 00:02
830$ tooling/bin/push_job_metrics || true
831[job-metrics] Pushing job metrics file for the CI/CD job.
832[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
833 Saving cache for successful job 00:00
834Not uploading cache ruby-gems-debian-bookworm-ruby-3.1.5-17 due to policy
835 Uploading artifacts for successful job 00:06
836Uploading artifacts...
837auto_explain/: found 3 matching artifact files and directories
838coverage/: found 5 matching artifact files and directories
839crystalball/: found 5 matching artifact files and directories
840WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
841knapsack/: found 4 matching artifact files and directories
842rspec/: found 12 matching artifact files and directories
843WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
844log/*.log: found 13 matching artifact files and directories
845WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7471346399/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
846WARNING: Retrying... context=artifacts-uploader error=request redirected
847Uploading artifacts as "archive" to coordinator... 201 Created id=7471346399 responseStatus=201 Created token=glcbt-66
848Uploading artifacts...
849rspec/rspec-*.xml: found 1 matching artifact files and directories
850WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7471346399/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
851WARNING: Retrying... context=artifacts-uploader error=request redirected
852Uploading artifacts as "junit" to coordinator... 201 Created id=7471346399 responseStatus=201 Created token=glcbt-66
853 Cleaning up project directory and file based variables 00:00
854Job succeeded