Skip to content
Snippets Groups Projects

rspec migration pg14 9/26

Passed Started by
🤖 GitLab Bot 🤖
@gitlab-bot
1Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
2 on green-2.shared-gitlab-org.runners-manager.gitlab.com/default 8zCxmpPt, system ID: s_130a96ce9e65
3 feature flags: FF_NETWORK_PER_BUILD:true
4Resolving secrets
5 Preparing the "docker+machine" executor 00:09
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:00
25Running on runner-8zcxmppt-project-278964-concurrent-0 via runner-8zcxmppt-shared-gitlab-org-1722417925-7b230dfe...
26 Getting source from Git repository 00:01
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
30 Restoring cache 00:20
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:23
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==> 'gem install knapsack --no-document' succeeded in 2 seconds.
102$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
103 Spawning Gitaly 00:04
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:12
113[10:14:44] 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_9_26_278964_report.json
120FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
121FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_9_26_278964_report.json
122NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_9_26_278964_report.json
123RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7471345417.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 1.95541984 seconds
141# [RSpecRunTime] Starting example group spec/migrations/20230726144458_swap_notes_id_to_bigint_for_self_managed_spec.rb. Expected to take 2 minutes 45.29 seconds.
142SwapNotesIdToBigintForSelfManaged
143 #up
144 when GitLab.com, dev, or test
145 behaves like column `id_convert_to_bigint` is already dropped
146 does not swaps the columns
147 when self-managed instance with the `id_convert_to_bigint` column already dropped
148 behaves like column `id_convert_to_bigint` is already dropped
149 does not swaps the columns
150# [RSpecRunTime] RSpec elapsed time: 2 minutes 2.46 seconds. Current RSS: ~1422M. Threads: 2. load average: 1.29 1.16 1.27 1/266 407
151.
152 when self-managed instance columns already swapped
153 does not swaps the columns
154# [RSpecRunTime] RSpec elapsed time: 2 minutes 12.71 seconds. Current RSS: ~1490M. Threads: 2. load average: 1.24 1.15 1.27 1/267 408
155.
156 when self-managed instance
157 swaps the columns
158 when having different naming for fk_d83a918cb1 foreign key
159 swaps the columns
160# [RSpecRunTime] RSpec elapsed time: 2 minutes 53.21 seconds. Current RSS: ~1427M. Threads: 2. load average: 1.12 1.13 1.26 1/267 409
161.
162INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
163INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
164INFO: "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
165INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
166INFO: "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
167INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
168INFO: "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
169INFO: analyzing "public.p_ci_job_annotations" inheritance tree
170INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
171INFO: "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
172INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
173INFO: "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
174INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
175INFO: "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
176INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
177INFO: analyzing "public.ci_builds_metadata"
178INFO: "ci_builds_metadata": 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_101"
180INFO: "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
181INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
182INFO: "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
183INFO: analyzing "public.p_ci_builds" inheritance tree
184INFO: analyzing "public.ci_builds"
185INFO: "ci_builds": 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_101"
187INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
188INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
189INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
190I, [2024-07-31T10:19:58.687684 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
191I, [2024-07-31T10:20:19.532069 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
192INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
193INFO: analyzing "public.ci_pipeline_variables"
194INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
195I, [2024-07-31T10:20:21.216509 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
196I, [2024-07-31T10:20:21.217669 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
197I, [2024-07-31T10:20:21.218618 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
198INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
199INFO: analyzing "public.ci_pipeline_variables"
200INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
201INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
202INFO: analyzing "public.ci_job_artifacts"
203INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
204I, [2024-07-31T10:20:27.654162 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
205I, [2024-07-31T10:20:27.655401 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
206I, [2024-07-31T10:20:27.656255 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
207INFO: analyzing "public.p_ci_stages" inheritance tree
208INFO: analyzing "public.ci_stages"
209INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
210I, [2024-07-31T10:20:31.102962 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
211I, [2024-07-31T10:20:31.104210 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
212I, [2024-07-31T10:20:31.105165 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
213I, [2024-07-31T10:20:32.425357 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
214I, [2024-07-31T10:20:39.170568 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
215I, [2024-07-31T10:20:53.714523 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
216I, [2024-07-31T10:20:53.715888 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
217I, [2024-07-31T10:20:53.716664 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
218I, [2024-07-31T10:21:06.094076 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
219I, [2024-07-31T10:21:13.206440 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
220# [RSpecRunTime] Finishing example group spec/migrations/20230726144458_swap_notes_id_to_bigint_for_self_managed_spec.rb. It took 4 minutes 17.98 seconds. Expected to take 2 minutes 45.29 seconds.
221# [RSpecRunTime] RSpec elapsed time: 4 minutes 23.63 seconds. Current RSS: ~1366M. Threads: 2. load average: 0.95 1.08 1.23 1/267 410
222.
223# [RSpecRunTime] Starting example group spec/migrations/add_expiry_notified_at_to_member_spec.rb. Expected to take 1 minute 43.68 seconds.
224AddExpiryNotifiedAtToMember
225main: == [advisory_lock_connection] object_id: 66621840, pg_backend_pid: 189
226main: == 20230707003301 AddExpiryNotifiedAtToMember: migrating ======================
227main: -- add_column("members", "expiry_notified_at", :datetime_with_timezone)
228main: -> 0.0015s
229main: == 20230707003301 AddExpiryNotifiedAtToMember: migrated (0.0169s) =============
230main: == [advisory_lock_connection] object_id: 66621840, pg_backend_pid: 189
231 correctly migrates up and down
232INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
233INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
234INFO: "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
235INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
236INFO: "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
237INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
238INFO: "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
239INFO: analyzing "public.p_ci_job_annotations" inheritance tree
240INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
241INFO: "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
242INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
243INFO: "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
244INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
245INFO: "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
246INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
247INFO: analyzing "public.ci_builds_metadata"
248INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
249INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
250INFO: "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
251INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
252INFO: "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
253INFO: analyzing "public.p_ci_builds" inheritance tree
254INFO: analyzing "public.ci_builds"
255INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
256INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
257INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
258INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
259INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
260I, [2024-07-31T10:23:33.633512 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
261I, [2024-07-31T10:23:54.873031 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
262INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
263INFO: analyzing "public.ci_pipeline_variables"
264INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
265I, [2024-07-31T10:23:56.577878 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
266I, [2024-07-31T10:23:56.579135 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
267I, [2024-07-31T10:23:56.579918 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
268INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
269INFO: analyzing "public.ci_pipeline_variables"
270INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
271INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
272INFO: analyzing "public.ci_job_artifacts"
273INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
274I, [2024-07-31T10:24:03.178079 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
275I, [2024-07-31T10:24:03.179487 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
276I, [2024-07-31T10:24:03.180418 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
277INFO: analyzing "public.p_ci_stages" inheritance tree
278INFO: analyzing "public.ci_stages"
279INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
280I, [2024-07-31T10:24:06.835821 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
281I, [2024-07-31T10:24:06.837137 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
282I, [2024-07-31T10:24:06.838109 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
283I, [2024-07-31T10:24:08.210527 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
284I, [2024-07-31T10:24:15.240869 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
285I, [2024-07-31T10:24:30.226618 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
286I, [2024-07-31T10:24:30.227906 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
287I, [2024-07-31T10:24:30.228717 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
288I, [2024-07-31T10:24:43.234185 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
289I, [2024-07-31T10:24:51.019527 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
290# [RSpecRunTime] Finishing example group spec/migrations/add_expiry_notified_at_to_member_spec.rb. It took 3 minutes 38.27 seconds. Expected to take 1 minute 43.68 seconds.
291# [RSpecRunTime] RSpec elapsed time: 8 minutes 1.96 seconds. Current RSS: ~1375M. Threads: 2. load average: 1.15 1.10 1.20 1/267 411
292.
293# [RSpecRunTime] Starting example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. Expected to take 1 minute 34.61 seconds.
294SwapColumnsForCiStagesPipelineIdBigintV2
295 when pipeline_id sql type is bigint
296main: == [advisory_lock_connection] object_id: 107867500, pg_backend_pid: 208
297main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrating =========
298main: -- columns(:ci_stages)
299main: -> 0.0044s
300main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrated (0.0222s)
301main: == [advisory_lock_connection] object_id: 107867500, pg_backend_pid: 208
302 does nothing
303# [RSpecRunTime] RSpec elapsed time: 9 minutes 22.79 seconds. Current RSS: ~1388M. Threads: 2. load average: 1.26 1.14 1.20 1/269 412
304.
305 when pipeline_id sql type is integer
306 behaves like swap conversion columns
307main: == [advisory_lock_connection] object_id: 108736000, pg_backend_pid: 211
308main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrating =========
309main: -- columns(:ci_stages)
310main: -> 0.0041s
311main: -- quote_table_name(:ci_pipelines)
312main: -> 0.0000s
313main: -- quote_table_name(:ci_stages)
314main: -> 0.0000s
315main: -- execute("LOCK TABLE \"ci_pipelines\", \"ci_stages\" IN ACCESS EXCLUSIVE MODE")
316main: -> 0.0015s
317main: -- clear_cache!(nil)
318main: -> 0.0000s
319main: -- quote_table_name(:ci_stages)
320main: -> 0.0000s
321main: -- quote_column_name(:pipeline_id)
322main: -> 0.0000s
323main: -- quote_column_name(:temp_name_for_renaming)
324main: -> 0.0000s
325main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"pipeline_id\" TO \"temp_name_for_renaming\"\n")
326main: -> 0.0013s
327main: -- clear_cache!(nil)
328main: -> 0.0000s
329main: -- quote_table_name(:ci_stages)
330main: -> 0.0000s
331main: -- quote_column_name(:pipeline_id_convert_to_bigint)
332main: -> 0.0000s
333main: -- quote_column_name(:pipeline_id)
334main: -> 0.0000s
335main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"pipeline_id_convert_to_bigint\" TO \"pipeline_id\"\n")
336main: -> 0.0013s
337main: -- clear_cache!(nil)
338main: -> 0.0000s
339main: -- quote_table_name(:ci_stages)
340main: -> 0.0000s
341main: -- quote_column_name(:temp_name_for_renaming)
342main: -> 0.0000s
343main: -- quote_column_name(:pipeline_id_convert_to_bigint)
344main: -> 0.0000s
345main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"temp_name_for_renaming\" TO \"pipeline_id_convert_to_bigint\"\n")
346main: -> 0.0011s
347main: -- quote_table_name(:trigger_07bc3c48f407)
348main: -> 0.0000s
349main: -- execute("ALTER FUNCTION \"trigger_07bc3c48f407\" RESET ALL")
350main: -> 0.0013s
351main: -- quote_table_name(:ci_stages)
352main: -> 0.0000s
353main: -- quote_column_name(:fk_fb57e6cc56)
354main: -> 0.0000s
355main: -- quote_column_name(:temp_name_for_renaming)
356main: -> 0.0000s
357main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"fk_fb57e6cc56\" TO \"temp_name_for_renaming\"\n")
358main: -> 0.0012s
359main: -- quote_table_name(:ci_stages)
360main: -> 0.0000s
361main: -- quote_column_name(:fk_c5ddde695f)
362main: -> 0.0000s
363main: -- quote_column_name(:fk_fb57e6cc56)
364main: -> 0.0000s
365main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"fk_c5ddde695f\" TO \"fk_fb57e6cc56\"\n")
366main: -> 0.0012s
367main: -- quote_table_name(:ci_stages)
368main: -> 0.0000s
369main: -- quote_column_name(:temp_name_for_renaming)
370main: -> 0.0000s
371main: -- quote_column_name(:fk_c5ddde695f)
372main: -> 0.0000s
373main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"temp_name_for_renaming\" TO \"fk_c5ddde695f\"\n")
374main: -> 0.0013s
375main: -- quote_table_name("index_ci_stages_on_pipeline_id")
376main: -> 0.0000s
377main: -- quote_table_name("temp_5fd4e9a225")
378main: -> 0.0000s
379main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id\" RENAME TO \"temp_5fd4e9a225\"")
380main: -> 0.0012s
381main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint")
382main: -> 0.0000s
383main: -- quote_table_name(:index_ci_stages_on_pipeline_id)
384main: -> 0.0000s
385main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint\" RENAME TO \"index_ci_stages_on_pipeline_id\"")
386main: -> 0.0011s
387main: -- quote_table_name("temp_5fd4e9a225")
388main: -> 0.0000s
389main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint)
390main: -> 0.0000s
391main: -- execute("ALTER INDEX \"temp_5fd4e9a225\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint\"")
392main: -> 0.0011s
393main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_id")
394main: -> 0.0000s
395main: -- quote_table_name("temp_ced8356685")
396main: -> 0.0000s
397main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_id\" RENAME TO \"temp_ced8356685\"")
398main: -> 0.0013s
399main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_id")
400main: -> 0.0000s
401main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_id)
402main: -> 0.0000s
403main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_id\" RENAME TO \"index_ci_stages_on_pipeline_id_and_id\"")
404main: -> 0.0010s
405main: -- quote_table_name("temp_ced8356685")
406main: -> 0.0000s
407main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_id)
408main: -> 0.0000s
409main: -- execute("ALTER INDEX \"temp_ced8356685\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_id\"")
410main: -> 0.0011s
411main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_name")
412main: -> 0.0000s
413main: -- quote_table_name("temp_348df98ef6")
414main: -> 0.0000s
415main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_name\" RENAME TO \"temp_348df98ef6\"")
416main: -> 0.0010s
417main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_name")
418main: -> 0.0000s
419main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_name)
420main: -> 0.0000s
421main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_name\" RENAME TO \"index_ci_stages_on_pipeline_id_and_name\"")
422main: -> 0.0013s
423main: -- quote_table_name("temp_348df98ef6")
424main: -> 0.0000s
425main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_name)
426main: -> 0.0000s
427main: -- execute("ALTER INDEX \"temp_348df98ef6\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_name\"")
428main: -> 0.0010s
429main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_position")
430main: -> 0.0000s
431main: -- quote_table_name("temp_d0b3b501ce")
432main: -> 0.0000s
433main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_position\" RENAME TO \"temp_d0b3b501ce\"")
434main: -> 0.0011s
435main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_position")
436main: -> 0.0000s
437main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_position)
438main: -> 0.0000s
439main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_position\" RENAME TO \"index_ci_stages_on_pipeline_id_and_position\"")
440main: -> 0.0013s
441main: -- quote_table_name("temp_d0b3b501ce")
442main: -> 0.0000s
443main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_position)
444main: -> 0.0000s
445main: -- execute("ALTER INDEX \"temp_d0b3b501ce\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_position\"")
446main: -> 0.0010s
447main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrated (0.0592s)
448main: == [advisory_lock_connection] object_id: 108736000, pg_backend_pid: 211
449 correctly swaps conversion columns
450# [RSpecRunTime] RSpec elapsed time: 9 minutes 37.57 seconds. Current RSS: ~1420M. Threads: 2. load average: 1.28 1.15 1.20 1/269 413
451.
452I, [2024-07-31T10:26:56.961396 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
453INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
454INFO: analyzing "public.ci_pipeline_variables"
455INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
456I, [2024-07-31T10:26:58.727339 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
457I, [2024-07-31T10:26:58.728797 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
458I, [2024-07-31T10:26:58.729844 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
459INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
460INFO: analyzing "public.ci_pipeline_variables"
461INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
462INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
463INFO: analyzing "public.ci_job_artifacts"
464INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
465I, [2024-07-31T10:27:05.521331 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
466I, [2024-07-31T10:27:05.522971 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
467I, [2024-07-31T10:27:05.523977 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
468INFO: analyzing "public.p_ci_stages" inheritance tree
469INFO: analyzing "public.ci_stages"
470INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
471I, [2024-07-31T10:27:09.215815 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
472I, [2024-07-31T10:27:09.217138 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
473I, [2024-07-31T10:27:09.218069 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
474I, [2024-07-31T10:27:10.561047 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
475I, [2024-07-31T10:27:17.692949 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
476I, [2024-07-31T10:27:32.705607 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
477I, [2024-07-31T10:27:32.707508 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
478I, [2024-07-31T10:27:32.708375 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
479I, [2024-07-31T10:27:45.748695 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
480I, [2024-07-31T10:27:53.302360 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
481# [RSpecRunTime] Finishing example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. It took 3 minutes 2.08 seconds. Expected to take 1 minute 34.61 seconds.
482# [RSpecRunTime] RSpec elapsed time: 11 minutes 4.1 seconds. Current RSS: ~1392M. Threads: 2. load average: 0.98 1.08 1.17 1/269 414
483.
484# [RSpecRunTime] Starting example group spec/migrations/20230822151454_remove_free_user_cap_email_workers_spec.rb. Expected to take 1 minute 20.6 seconds.
485RemoveFreeUserCapEmailWorkers
486 #up
487main: == [advisory_lock_connection] object_id: 146581520, pg_backend_pid: 230
488main: == 20230822151454 RemoveFreeUserCapEmailWorkers: migrating ====================
489main: == 20230822151454 RemoveFreeUserCapEmailWorkers: migrated (0.0108s) ===========
490main: == [advisory_lock_connection] object_id: 146581520, pg_backend_pid: 230
491 calls sidekiq_remove_jobs with correct argument
492# [RSpecRunTime] RSpec elapsed time: 12 minutes 34.58 seconds. Current RSS: ~1403M. Threads: 2. load average: 1.07 1.11 1.17 2/269 415
493.
494INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
495INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
496INFO: "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
497INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
498INFO: "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
499INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
500INFO: "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
501INFO: analyzing "public.p_ci_job_annotations" inheritance tree
502INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
503INFO: "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
504INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
505INFO: "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
506INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
507INFO: "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
508INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
509INFO: analyzing "public.ci_builds_metadata"
510INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
511INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
512INFO: "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
513INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
514INFO: "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
515INFO: analyzing "public.p_ci_builds" inheritance tree
516INFO: analyzing "public.ci_builds"
517INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
518INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
519INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
520INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
521INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
522I, [2024-07-31T10:29:39.794716 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
523I, [2024-07-31T10:30:01.769464 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
524INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
525INFO: analyzing "public.ci_pipeline_variables"
526INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
527I, [2024-07-31T10:30:03.703688 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
528I, [2024-07-31T10:30:03.704894 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
529I, [2024-07-31T10:30:03.705743 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
530INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
531INFO: analyzing "public.ci_pipeline_variables"
532INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
533INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
534INFO: analyzing "public.ci_job_artifacts"
535INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
536I, [2024-07-31T10:30:10.376452 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
537I, [2024-07-31T10:30:10.377665 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
538I, [2024-07-31T10:30:10.378784 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
539INFO: analyzing "public.p_ci_stages" inheritance tree
540INFO: analyzing "public.ci_stages"
541INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
542I, [2024-07-31T10:30:14.011640 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
543I, [2024-07-31T10:30:14.012926 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
544I, [2024-07-31T10:30:14.013816 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
545I, [2024-07-31T10:30:15.406392 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
546I, [2024-07-31T10:30:22.270413 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
547I, [2024-07-31T10:30:37.040330 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
548I, [2024-07-31T10:30:37.041601 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
549I, [2024-07-31T10:30:37.042512 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
550I, [2024-07-31T10:30:50.117614 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
551I, [2024-07-31T10:30:57.821337 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
552# [RSpecRunTime] Finishing example group spec/migrations/20230822151454_remove_free_user_cap_email_workers_spec.rb. It took 3 minutes 4.66 seconds. Expected to take 1 minute 20.6 seconds.
553# [RSpecRunTime] RSpec elapsed time: 14 minutes 8.82 seconds. Current RSS: ~1352M. Threads: 2. load average: 0.95 1.05 1.14 1/269 416
554.
555# [RSpecRunTime] Starting example group spec/migrations/20231214111617_queue_backfill_owasp_top_ten_of_vulnerability_reads_spec.rb. Expected to take 1 minute 7.77 seconds.
556QueueBackfillOwaspTopTenOfVulnerabilityReads
557main: == [advisory_lock_connection] object_id: 183278100, pg_backend_pid: 247
558main: == 20231214111617 QueueBackfillOwaspTopTenOfVulnerabilityReads: migrating =====
559main: == 20231214111617 QueueBackfillOwaspTopTenOfVulnerabilityReads: migrated (0.0689s)
560main: == [advisory_lock_connection] object_id: 183278100, pg_backend_pid: 247
561 schedules a new batched migration
562I, [2024-07-31T10:32:25.597606 #328] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
563INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
564INFO: analyzing "public.ci_pipeline_variables"
565INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
566I, [2024-07-31T10:32:27.425307 #328] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
567I, [2024-07-31T10:32:27.426598 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
568I, [2024-07-31T10:32:27.427473 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
569INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
570INFO: analyzing "public.ci_pipeline_variables"
571INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
572INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
573INFO: analyzing "public.ci_job_artifacts"
574INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
575I, [2024-07-31T10:32:34.005715 #328] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
576I, [2024-07-31T10:32:34.006855 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
577I, [2024-07-31T10:32:34.007634 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
578INFO: analyzing "public.p_ci_stages" inheritance tree
579INFO: analyzing "public.ci_stages"
580INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
581I, [2024-07-31T10:32:37.752306 #328] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
582I, [2024-07-31T10:32:37.754084 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
583I, [2024-07-31T10:32:37.755070 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
584I, [2024-07-31T10:32:39.145918 #328] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
585I, [2024-07-31T10:32:46.257461 #328] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
586I, [2024-07-31T10:33:01.094671 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
587I, [2024-07-31T10:33:01.095933 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
588I, [2024-07-31T10:33:01.096751 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
589I, [2024-07-31T10:33:14.036757 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
590I, [2024-07-31T10:33:21.343398 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
591# [RSpecRunTime] Finishing example group spec/migrations/20231214111617_queue_backfill_owasp_top_ten_of_vulnerability_reads_spec.rb. It took 2 minutes 22.95 seconds. Expected to take 1 minute 7.77 seconds.
592# [RSpecRunTime] RSpec elapsed time: 16 minutes 31.83 seconds. Current RSS: ~1360M. Threads: 2. load average: 1.04 1.10 1.15 1/267 417
593.
594# [RSpecRunTime] Starting example group spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb. Expected to take 47.19 seconds.
595MoveSelfManagedCrToInstance
596 #up
597 when on self managed
598main: -- execute("UPDATE member_roles mr SET name = CONCAT(mr.name, ' (', g.name, ' - ', g.id, ')'), namespace_id = NULL\n FROM namespaces g WHERE mr.namespace_id IS NOT NULL AND\n g.id = mr.namespace_id\n")
599main: -> 0.0027s
600 sets namespace_id to nil and updates name
601# [RSpecRunTime] RSpec elapsed time: 17 minutes 15.57 seconds. Current RSS: ~1418M. Threads: 2. load average: 1.02 1.08 1.14 1/268 418
602.
603 when on SaaS
604 does not update the custom roles
605# [RSpecRunTime] RSpec elapsed time: 17 minutes 21.37 seconds. Current RSS: ~1420M. Threads: 2. load average: 1.01 1.08 1.14 1/268 419
606.
607I, [2024-07-31T10:34:38.524239 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
608I, [2024-07-31T10:34:38.525481 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
609I, [2024-07-31T10:34:38.526344 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
610I, [2024-07-31T10:34:51.271786 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
611I, [2024-07-31T10:34:58.799924 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
612# [RSpecRunTime] Finishing example group spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb. It took 1 minute 37.76 seconds. Expected to take 47.19 seconds.
613# [RSpecRunTime] RSpec elapsed time: 18 minutes 9.69 seconds. Current RSS: ~1387M. Threads: 2. load average: 0.95 1.05 1.13 1/268 420
614.
615# [RSpecRunTime] Starting example group spec/migrations/increase_quantity_for_gitlabcom_duo_pro_trials_spec.rb. Expected to take 27.99 seconds.
616IncreaseQuantityForGitlabcomDuoProTrials
617 #up
618main: == [advisory_lock_connection] object_id: 226040180, pg_backend_pid: 276
619main: == 20240510125400 IncreaseQuantityForGitlabcomDuoProTrials: migrating =========
620main: -- exec_query("SELECT COUNT(*) AS count FROM \"subscription_add_on_purchases\" WHERE \"subscription_add_on_purchases\".\"subscription_add_on_id\" = 1 AND \"subscription_add_on_purchases\".\"trial\" = TRUE AND \"subscription_add_on_purchases\".\"expires_on\" >= '2024-07-31'")
621main: -> 0.0025s
622main: -- exec_query("SELECT \"subscription_add_on_purchases\".\"id\" FROM \"subscription_add_on_purchases\" WHERE \"subscription_add_on_purchases\".\"subscription_add_on_id\" = 1 AND \"subscription_add_on_purchases\".\"trial\" = TRUE AND \"subscription_add_on_purchases\".\"expires_on\" >= '2024-07-31' ORDER BY \"subscription_add_on_purchases\".\"id\" ASC LIMIT 1")
623main: -> 0.0027s
624main: -- exec_query("SELECT \"subscription_add_on_purchases\".\"id\" FROM \"subscription_add_on_purchases\" WHERE \"subscription_add_on_purchases\".\"id\" >= 2 AND \"subscription_add_on_purchases\".\"subscription_add_on_id\" = 1 AND \"subscription_add_on_purchases\".\"trial\" = TRUE AND \"subscription_add_on_purchases\".\"expires_on\" >= '2024-07-31' ORDER BY \"subscription_add_on_purchases\".\"id\" ASC LIMIT 1 OFFSET 1")
625main: -> 0.0026s
626main: -- transaction(nil)
627main: -- execute("UPDATE \"subscription_add_on_purchases\" SET \"quantity\" = 100 WHERE \"subscription_add_on_purchases\".\"id\" >= 2 AND \"subscription_add_on_purchases\".\"id\" < 3 AND \"subscription_add_on_purchases\".\"subscription_add_on_id\" = 1 AND \"subscription_add_on_purchases\".\"trial\" = TRUE AND \"subscription_add_on_purchases\".\"expires_on\" >= '2024-07-31'")
628main: -> 0.0039s
629main: -> 0.0054s
630main: -- exec_query("SELECT \"subscription_add_on_purchases\".\"id\" FROM \"subscription_add_on_purchases\" WHERE \"subscription_add_on_purchases\".\"id\" >= 3 AND \"subscription_add_on_purchases\".\"subscription_add_on_id\" = 1 AND \"subscription_add_on_purchases\".\"trial\" = TRUE AND \"subscription_add_on_purchases\".\"expires_on\" >= '2024-07-31' ORDER BY \"subscription_add_on_purchases\".\"id\" ASC LIMIT 1 OFFSET 1")
631main: -> 0.0028s
632main: -- transaction(nil)
633main: -- execute("UPDATE \"subscription_add_on_purchases\" SET \"quantity\" = 100 WHERE \"subscription_add_on_purchases\".\"id\" >= 3 AND \"subscription_add_on_purchases\".\"subscription_add_on_id\" = 1 AND \"subscription_add_on_purchases\".\"trial\" = TRUE AND \"subscription_add_on_purchases\".\"expires_on\" >= '2024-07-31'")
634main: -> 0.0032s
635main: -> 0.0050s
636main: == 20240510125400 IncreaseQuantityForGitlabcomDuoProTrials: migrated (0.0560s)
637main: == [advisory_lock_connection] object_id: 226040180, pg_backend_pid: 276
638 update only the active duo_pro trials quantity to 100
639# [RSpecRunTime] RSpec elapsed time: 18 minutes 39.07 seconds. Current RSS: ~1407M. Threads: 2. load average: 1.03 1.06 1.13 1/268 421
640.
641I, [2024-07-31T10:35:42.392636 #328] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
642I, [2024-07-31T10:35:42.393918 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
643I, [2024-07-31T10:35:42.394839 #328] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
644I, [2024-07-31T10:35:54.813196 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
645I, [2024-07-31T10:36:02.114988 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
646# [RSpecRunTime] Finishing example group spec/migrations/increase_quantity_for_gitlabcom_duo_pro_trials_spec.rb. It took 1 minute 2.79 seconds. Expected to take 27.99 seconds.
647# [RSpecRunTime] RSpec elapsed time: 19 minutes 12.66 seconds. Current RSS: ~1357M. Threads: 2. load average: 1.02 1.05 1.12 1/270 422
648.
649# [RSpecRunTime] Starting example group spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb. Expected to take 25.76 seconds.
650QueueBackfillWorkItemHierarchyForEpics
651main: == [advisory_lock_connection] object_id: 234320820, pg_backend_pid: 288
652main: == 20240517141003 QueueBackfillWorkItemHierarchyForEpics: migrating ===========
653main: == 20240517141003 QueueBackfillWorkItemHierarchyForEpics: migrated (0.0680s) ==
654main: == [advisory_lock_connection] object_id: 234320820, pg_backend_pid: 288
655 schedules a new batched migration
656I, [2024-07-31T10:36:50.351632 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
657I, [2024-07-31T10:36:57.496443 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
658# [RSpecRunTime] Finishing example group spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb. It took 55.2 seconds. Expected to take 25.76 seconds.
659# [RSpecRunTime] RSpec elapsed time: 20 minutes 7.92 seconds. Current RSS: ~1355M. Threads: 2. load average: 1.05 1.06 1.11 1/270 423
660.
661# [RSpecRunTime] Starting example group spec/migrations/20240612074837_queue_backfill_packages_debian_project_distribution_keys_project_id_spec.rb. Expected to take 18.62 seconds.
662QueueBackfillPackagesDebianProjectDistributionKeysProjectId
663main: == [advisory_lock_connection] object_id: 241144940, pg_backend_pid: 301
664main: == 20240612074837 QueueBackfillPackagesDebianProjectDistributionKeysProjectId: migrating
665main: == 20240612074837 QueueBackfillPackagesDebianProjectDistributionKeysProjectId: migrated (0.0688s)
666main: == [advisory_lock_connection] object_id: 241144940, pg_backend_pid: 301
667 schedules a new batched migration
668I, [2024-07-31T10:37:31.688095 #328] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
669I, [2024-07-31T10:37:38.810598 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
670# [RSpecRunTime] Finishing example group spec/migrations/20240612074837_queue_backfill_packages_debian_project_distribution_keys_project_id_spec.rb. It took 41.27 seconds. Expected to take 18.62 seconds.
671# [RSpecRunTime] RSpec elapsed time: 20 minutes 49.26 seconds. Current RSS: ~1343M. Threads: 2. load average: 1.08 1.06 1.11 1/270 424
672.
673# [RSpecRunTime] Starting example group spec/migrations/20240613071715_queue_backfill_packages_conan_metadata_project_id_spec.rb. Expected to take 14.09 seconds.
674QueueBackfillPackagesConanMetadataProjectId
675main: == [advisory_lock_connection] object_id: 245961660, pg_backend_pid: 312
676main: == 20240613071715 QueueBackfillPackagesConanMetadataProjectId: migrating ======
677main: == 20240613071715 QueueBackfillPackagesConanMetadataProjectId: migrated (0.0733s)
678main: == [advisory_lock_connection] object_id: 245961660, pg_backend_pid: 312
679 schedules a new batched migration
680I, [2024-07-31T10:38:12.657213 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
681# [RSpecRunTime] Finishing example group spec/migrations/20240613071715_queue_backfill_packages_conan_metadata_project_id_spec.rb. It took 33.99 seconds. Expected to take 14.09 seconds.
682# [RSpecRunTime] RSpec elapsed time: 21 minutes 23.31 seconds. Current RSS: ~1341M. Threads: 2. load average: 1.10 1.07 1.11 1/270 425
683.
684# [RSpecRunTime] Starting example group spec/migrations/20240702072519_queue_backfill_packages_debian_group_architectures_group_id_spec.rb. Expected to take 11.23 seconds.
685QueueBackfillPackagesDebianGroupArchitecturesGroupId
686main: == [advisory_lock_connection] object_id: 249825580, pg_backend_pid: 324
687main: == 20240702072519 QueueBackfillPackagesDebianGroupArchitecturesGroupId: migrating
688main: == 20240702072519 QueueBackfillPackagesDebianGroupArchitecturesGroupId: migrated (0.0685s)
689main: == [advisory_lock_connection] object_id: 249825580, pg_backend_pid: 324
690 schedules a new batched migration
691I, [2024-07-31T10:38:39.106434 #328] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
692# [RSpecRunTime] Finishing example group spec/migrations/20240702072519_queue_backfill_packages_debian_group_architectures_group_id_spec.rb. It took 26.13 seconds. Expected to take 11.23 seconds.
693# [RSpecRunTime] RSpec elapsed time: 21 minutes 49.5 seconds. Current RSS: ~1342M. Threads: 2. load average: 0.99 1.05 1.10 1/270 426
694.
695auto_explain log contains 987 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-9-26.328.main.ndjson.gz
696took 32.514452059
697auto_explain log contains 988 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-9-26.328.ci.ndjson.gz
698took 30.345980806
699[TEST PROF INFO] EventProf results for sql.active_record
700Total time: 14:12.920 of 21:43.910 (65.41%)
701Total events: 584371
702Top 5 slowest suites (by time):
703AddExpiryNotifiedAtToMember (./spec/migrations/add_expiry_notified_at_to_member_spec.rb:6) – 02:32.754 (95394 / 1) of 03:38.334 (69.96%)
704SwapNotesIdTo...ForSelfManaged (./spec/migrations/20230726144458_swap_notes_id_to_bigint_for_self_managed_spec.rb:6) – 02:22.242 (99839 / 5) of 04:18.043 (55.12%)
705RemoveFreeUserCapEmailWorkers (./spec/migrations/20230822151454_remove_free_user_cap_email_workers_spec.rb:6) – 02:11.392 (88024 / 1) of 03:04.719 (71.13%)
706SwapColumnsFo...lineIdBigintV2 (./spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb:6) – 02:00.648 (83036 / 2) of 03:02.141 (66.24%)
707QueueBackfill...erabilityReads (./spec/migrations/20231214111617_queue_backfill_owasp_top_ten_of_vulnerability_reads_spec.rb:6) – 01:38.730 (70179 / 1) of 02:23.011 (69.04%)
708Knapsack report was generated. Preview:
709{
710 "spec/migrations/20230726144458_swap_notes_id_to_bigint_for_self_managed_spec.rb": 168.4661672139996,
711 "spec/migrations/add_expiry_notified_at_to_member_spec.rb": 108.63696631499988,
712 "spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb": 95.7580480659999,
713 "spec/migrations/20230822151454_remove_free_user_cap_email_workers_spec.rb": 90.6244896090002,
714 "spec/migrations/20231214111617_queue_backfill_owasp_top_ten_of_vulnerability_reads_spec.rb": 71.20209659600005,
715 "spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb": 49.67650591100028,
716 "spec/migrations/increase_quantity_for_gitlabcom_duo_pro_trials_spec.rb": 29.401049678000163,
717 "spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb": 26.279830020999725,
718 "spec/migrations/20240612074837_queue_backfill_packages_debian_project_distribution_keys_project_id_spec.rb": 18.46772832700026,
719 "spec/migrations/20240613071715_queue_backfill_packages_conan_metadata_project_id_spec.rb": 15.012561117000587,
720 "spec/migrations/20240702072519_queue_backfill_packages_debian_group_architectures_group_id_spec.rb": 11.596835852999902
721}
722Knapsack global time execution for tests: 11m 25s
723Finished in 22 minutes 53 seconds (files took 2 minutes 12.8 seconds to load)
72417 examples, 0 failures
725Randomized with seed 50349
726[TEST PROF INFO] Time spent in factories: 00:00.200 (0.01% of total time)
727RSpec exited with 0.
728No examples to retry, congrats!
729 Running after_script 00:11
730Running after script...
731$ source scripts/utils.sh
732$ bundle exec gem list gitlab_quality-test_tooling
733gitlab_quality-test_tooling (1.33.0)
734$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
735 Report test failures 00:03
738$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
739 Report test flakiness 00:00
741$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
742 Report test slowness 00:02
745$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
746 Report test slowness in MR note 00:00
748$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
749 Report test files close to timing out 00:02
752$ tooling/bin/push_job_metrics || true
753[job-metrics] Pushing job metrics file for the CI/CD job.
754[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
755 Saving cache for successful job 00:01
756Not uploading cache ruby-gems-debian-bookworm-ruby-3.1.5-17 due to policy
757 Uploading artifacts for successful job 00:05
758Uploading artifacts...
759auto_explain/: found 3 matching artifact files and directories
760coverage/: found 5 matching artifact files and directories
761crystalball/: found 5 matching artifact files and directories
762WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
763knapsack/: found 4 matching artifact files and directories
764rspec/: found 12 matching artifact files and directories
765WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
766log/*.log: found 13 matching artifact files and directories
767WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7471345417/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
768WARNING: Retrying... context=artifacts-uploader error=request redirected
769Uploading artifacts as "archive" to coordinator... 201 Created id=7471345417 responseStatus=201 Created token=glcbt-66
770Uploading artifacts...
771rspec/rspec-*.xml: found 1 matching artifact files and directories
772WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7471345417/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
773WARNING: Retrying... context=artifacts-uploader error=request redirected
774Uploading artifacts as "junit" to coordinator... 201 Created id=7471345417 responseStatus=201 Created token=glcbt-66
775 Cleaning up project directory and file based variables 00:00
776Job succeeded