Skip to content
Snippets Groups Projects

rspec migration pg14 5/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 01:19
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-1722418060-b6000504...
26 Getting source from Git repository 00:00
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
30 Restoring cache 00:24
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:44
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:42
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 1 seconds.
102$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
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 25:59
113[10:16:19] 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_5_26_278964_report.json
120FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
121FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_5_26_278964_report.json
122NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_5_26_278964_report.json
123RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7471345400.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.387752505 seconds
141# [RSpecRunTime] Starting example group spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb. Expected to take 2 minutes 55.9 seconds.
142QueueBackfillPartitionIdCiPipelineChatData
143 #down
144ci: == [advisory_lock_connection] object_id: 58086600, pg_backend_pid: 1605
145ci: == 20231218092401 QueueBackfillPartitionIdCiPipelineChatData: migrating =======
146ci: == 20231218092401 QueueBackfillPartitionIdCiPipelineChatData: migrated (0.0930s)
147ci: == [advisory_lock_connection] object_id: 58086600, pg_backend_pid: 1605
148 deletes all batched migration records
149 #up
150 with migration present
151 when migration finished successfully
152ci: == [advisory_lock_connection] object_id: 60072800, pg_backend_pid: 1612
153ci: == 20231218092401 QueueBackfillPartitionIdCiPipelineChatData: migrating =======
154ci: == 20231218092401 QueueBackfillPartitionIdCiPipelineChatData: migrated (0.0356s)
155ci: == [advisory_lock_connection] object_id: 60072800, pg_backend_pid: 1612
156 does not raise exception
157ci: == [advisory_lock_connection] object_id: 60738800, pg_backend_pid: 1618
158ci: == 20231218092401 QueueBackfillPartitionIdCiPipelineChatData: migrating =======
159ci: == 20231218092401 QueueBackfillPartitionIdCiPipelineChatData: migrated (0.0348s)
160ci: == [advisory_lock_connection] object_id: 60738800, pg_backend_pid: 1618
161 schedules background jobs for each batch of ci_pipeline_chat_data
162# [RSpecRunTime] RSpec elapsed time: 3 minutes 3.14 seconds. Current RSS: ~1480M. Threads: 2. load average: 1.01 1.11 1.15 1/263 407
163.
164I, [2024-07-31T10:21:44.835194 #326] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
165INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
166INFO: analyzing "public.ci_pipeline_variables"
167INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
168I, [2024-07-31T10:21:47.618248 #326] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
169I, [2024-07-31T10:21:47.619965 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
170I, [2024-07-31T10:21:47.620898 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
171INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
172INFO: analyzing "public.ci_pipeline_variables"
173INFO: "ci_pipeline_variables": 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_job_artifacts" inheritance tree
175INFO: analyzing "public.ci_job_artifacts"
176INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
177I, [2024-07-31T10:21:54.007674 #326] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
178I, [2024-07-31T10:21:54.008839 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
179I, [2024-07-31T10:21:54.009733 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
180INFO: analyzing "public.p_ci_stages" inheritance tree
181INFO: analyzing "public.ci_stages"
182INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
183I, [2024-07-31T10:21:57.475508 #326] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
184I, [2024-07-31T10:21:57.476756 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
185I, [2024-07-31T10:21:57.477704 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
186I, [2024-07-31T10:21:58.685269 #326] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
187I, [2024-07-31T10:22:05.007360 #326] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
188I, [2024-07-31T10:22:18.614660 #326] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
189I, [2024-07-31T10:22:18.615847 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
190I, [2024-07-31T10:22:18.616610 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
191I, [2024-07-31T10:22:29.971133 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
192I, [2024-07-31T10:22:36.545408 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
193I, [2024-07-31T10:22:46.700665 #326] INFO -- : Database: 'ci', Table: 'audit_events_group_external_streaming_destinations': Lock Writes
194I, [2024-07-31T10:22:46.764384 #326] INFO -- : Database: 'ci', Table: 'packages_terraform_module_metadata': Lock Writes
195I, [2024-07-31T10:22:46.798187 #326] INFO -- : Database: 'ci', Table: 'member_approvals': Lock Writes
196I, [2024-07-31T10:22:46.830876 #326] INFO -- : Database: 'ci', Table: 'zoekt_repositories': Lock Writes
197I, [2024-07-31T10:22:47.912133 #326] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_streaming_destinations': Lock Writes
198I, [2024-07-31T10:22:47.943565 #326] INFO -- : Database: 'ci', Table: 'catalog_verified_namespaces': Lock Writes
199I, [2024-07-31T10:22:47.976612 #326] INFO -- : Database: 'ci', Table: 'group_saved_replies': Lock Writes
200INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
201INFO: analyzing "public.ci_pipeline_variables"
202INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
203INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
204INFO: analyzing "public.ci_pipeline_variables"
205INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
206I, [2024-07-31T10:22:54.179343 #326] INFO -- : Database: 'ci', Table: 'zoekt_tasks': Lock Writes
207I, [2024-07-31T10:22:54.409005 #326] INFO -- : Database: 'ci', Table: 'merge_request_diff_commits_b5377a7a34': Lock Writes
208I, [2024-07-31T10:22:54.410215 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
209I, [2024-07-31T10:22:54.411307 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
210I, [2024-07-31T10:22:54.612576 #326] INFO -- : Database: 'ci', Table: 'audit_events_group_streaming_event_type_filters': Lock Writes
211I, [2024-07-31T10:22:55.805608 #326] INFO -- : Database: 'ci', Table: 'remote_development_namespace_cluster_agent_mappings': Lock Writes
212I, [2024-07-31T10:22:55.806781 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
213I, [2024-07-31T10:22:55.807606 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
214I, [2024-07-31T10:22:56.542835 #326] INFO -- : Database: 'ci', Table: 'p_catalog_resource_component_usages': Lock Writes
215INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
216INFO: analyzing "public.ci_job_artifacts"
217INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
218INFO: analyzing "public.p_ci_stages" inheritance tree
219INFO: analyzing "public.ci_stages"
220INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
221I, [2024-07-31T10:23:02.474175 #326] INFO -- : Database: 'ci', Table: 'audit_events_instance_streaming_event_type_filters': Lock Writes
222I, [2024-07-31T10:23:02.576916 #326] INFO -- : Database: 'ci', Table: 'security_policies': Lock Writes
223I, [2024-07-31T10:23:02.607834 #326] INFO -- : Database: 'ci', Table: 'approval_policy_rules': Lock Writes
224I, [2024-07-31T10:23:02.800190 #326] INFO -- : Database: 'ci', Table: 'relation_import_trackers': Lock Writes
225I, [2024-07-31T10:23:03.120517 #326] INFO -- : Database: 'ci', Table: 'project_saved_replies': Lock Writes
226I, [2024-07-31T10:23:08.402404 #326] INFO -- : Database: 'ci', Table: 'import_source_users': Lock Writes
227I, [2024-07-31T10:23:08.432237 #326] INFO -- : Database: 'ci', Table: 'audit_events_streaming_instance_namespace_filters': Lock Writes
228I, [2024-07-31T10:23:08.513663 #326] INFO -- : Database: 'ci', Table: 'early_access_program_tracking_events': Lock Writes
229I, [2024-07-31T10:23:09.048952 #326] INFO -- : Database: 'ci', Table: 'merge_request_diff_files_99208b8fac': Lock Writes
230I, [2024-07-31T10:23:09.050198 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
231I, [2024-07-31T10:23:09.051015 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
232I, [2024-07-31T10:23:10.003345 #326] INFO -- : Database: 'ci', Table: 'audit_events_streaming_group_namespace_filters': Lock Writes
233I, [2024-07-31T10:23:10.120384 #326] INFO -- : Database: 'ci', Table: 'ai_self_hosted_models': Lock Writes
234I, [2024-07-31T10:23:10.151093 #326] INFO -- : Database: 'ci', Table: 'merge_request_requested_changes': Lock Writes
235I, [2024-07-31T10:23:10.807592 #326] INFO -- : Database: 'ci', Table: 'custom_software_licenses': Lock Writes
236I, [2024-07-31T10:23:10.837605 #326] INFO -- : Database: 'ci', Table: 'ai_vectorizable_files': Lock Writes
237I, [2024-07-31T10:23:10.839058 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
238I, [2024-07-31T10:23:10.839874 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
239I, [2024-07-31T10:23:10.871919 #326] INFO -- : Database: 'ci', Table: 'ai_agent_version_attachments': Lock Writes
240I, [2024-07-31T10:23:10.873434 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
241I, [2024-07-31T10:23:10.874249 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
242I, [2024-07-31T10:23:24.080533 #326] INFO -- : Database: 'ci', Table: 'ai_feature_settings': Lock Writes
243I, [2024-07-31T10:23:24.115199 #326] INFO -- : Database: 'ci', Table: 'user_broadcast_message_dismissals': Lock Writes
244I, [2024-07-31T10:23:24.344130 #326] INFO -- : Database: 'ci', Table: 'user_audit_events': Lock Writes
245I, [2024-07-31T10:23:24.379511 #326] INFO -- : Database: 'ci', Table: 'group_audit_events': Lock Writes
246I, [2024-07-31T10:23:24.416315 #326] INFO -- : Database: 'ci', Table: 'project_audit_events': Lock Writes
247I, [2024-07-31T10:23:24.448855 #326] INFO -- : Database: 'ci', Table: 'instance_audit_events': Lock Writes
248I, [2024-07-31T10:23:24.550402 #326] INFO -- : Database: 'ci', Table: 'scan_execution_policy_rules': Lock Writes
249I, [2024-07-31T10:23:25.327438 #326] INFO -- : Database: 'ci', Table: 'vulnerability_export_parts': Lock Writes
250I, [2024-07-31T10:23:25.953776 #326] INFO -- : Database: 'ci', Table: 'security_policy_project_links': Lock Writes
251I, [2024-07-31T10:23:26.855845 #326] INFO -- : Database: 'ci', Table: 'zoekt_replicas': Lock Writes
252I, [2024-07-31T10:23:26.988021 #326] INFO -- : Database: 'ci', Table: 'ai_testing_terms_acceptances': Lock Writes
253I, [2024-07-31T10:23:37.900328 #326] INFO -- : Database: 'ci', Table: 'duo_workflows_workflows': Lock Writes
254I, [2024-07-31T10:23:37.903412 #326] INFO -- : Database: 'ci', Table: 'duo_workflows_checkpoints': Lock Writes
255I, [2024-07-31T10:23:38.730020 #326] INFO -- : Database: 'ci', Table: 'oauth_device_grants': Lock Writes
256I, [2024-07-31T10:23:38.731213 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
257I, [2024-07-31T10:23:38.732023 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
258I, [2024-07-31T10:23:38.940852 #326] INFO -- : Database: 'ci', Table: 'abuse_report_notes': Lock Writes
259I, [2024-07-31T10:23:39.622618 #326] INFO -- : Database: 'ci', Table: 'import_source_user_placeholder_references': Lock Writes
260I, [2024-07-31T10:23:40.167817 #326] INFO -- : Database: 'ci', Table: 'virtual_registries_packages_maven_registries': Lock Writes
261I, [2024-07-31T10:23:40.169119 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
262I, [2024-07-31T10:23:40.169960 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
263I, [2024-07-31T10:23:40.247355 #326] INFO -- : Database: 'ci', Table: 'virtual_registries_packages_maven_upstreams': Lock Writes
264I, [2024-07-31T10:23:40.248658 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
265I, [2024-07-31T10:23:40.249495 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
266I, [2024-07-31T10:23:40.283702 #326] INFO -- : Database: 'ci', Table: 'virtual_registries_packages_maven_registry_upstreams': Lock Writes
267I, [2024-07-31T10:23:40.284958 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
268I, [2024-07-31T10:23:40.285743 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
269I, [2024-07-31T10:23:40.482244 #326] INFO -- : Database: 'ci', Table: 'country_access_logs': Lock Writes
270I, [2024-07-31T10:23:40.657232 #326] INFO -- : Database: 'ci', Table: 'dependency_list_export_parts': Lock Writes
271I, [2024-07-31T10:23:40.956515 #326] INFO -- : Database: 'ci', Table: 'namespace_import_users': Lock Writes
272I, [2024-07-31T10:23:50.065282 #326] INFO -- : Database: 'ci', Table: 'approval_policy_rule_project_links': Lock Writes
273I, [2024-07-31T10:23:50.494877 #326] INFO -- : Database: 'ci', Table: 'virtual_registries_packages_maven_cached_responses': Lock Writes
274I, [2024-07-31T10:23:50.496223 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
275I, [2024-07-31T10:23:50.497096 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
276# [RSpecRunTime] Finishing example group spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb. It took 5 minutes 15.29 seconds. Expected to take 2 minutes 55.9 seconds.
277# [RSpecRunTime] RSpec elapsed time: 5 minutes 22.53 seconds. Current RSS: ~1398M. Threads: 2. load average: 1.35 1.15 1.15 1/261 408
278.
279# [RSpecRunTime] Starting example group spec/migrations/20230809203254_ensure_issue_user_mentions_bigint_backfill_is_finished_for_self_managed_spec.rb. Expected to take 1 minute 42.31 seconds.
280EnsureIssueUserMentionsBigintBackfillIsFinishedForSelfManaged
281 #up
282main: == [advisory_lock_connection] object_id: 112783000, pg_backend_pid: 3083
283main: == 20230809203254 EnsureIssueUserMentionsBigintBackfillIsFinishedForSelfManaged: migrating
284main: == 20230809203254 EnsureIssueUserMentionsBigintBackfillIsFinishedForSelfManaged: migrated (0.0099s)
285main: == [advisory_lock_connection] object_id: 112783000, pg_backend_pid: 3083
286 ensures the migration is completed for self-managed instances
287main: == [advisory_lock_connection] object_id: 113721440, pg_backend_pid: 3085
288main: == 20230809203254 EnsureIssueUserMentionsBigintBackfillIsFinishedForSelfManaged: migrating
289main: == 20230809203254 EnsureIssueUserMentionsBigintBackfillIsFinishedForSelfManaged: migrated (0.0090s)
290main: == [advisory_lock_connection] object_id: 113721440, pg_backend_pid: 3085
291 skips the check for GitLab.com, dev, or test
292# [RSpecRunTime] RSpec elapsed time: 6 minutes 58.79 seconds. Current RSS: ~1452M. Threads: 2. load average: 1.25 1.16 1.15 1/262 409
293.
294INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
295INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
296INFO: "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
297INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
298INFO: "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
299INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
300INFO: "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
301INFO: analyzing "public.p_ci_job_annotations" inheritance tree
302INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
303INFO: "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
304INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
305INFO: "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
306INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
307INFO: "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
308INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
309INFO: analyzing "public.ci_builds_metadata"
310INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
311INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
312INFO: "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
313INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
314INFO: "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
315INFO: analyzing "public.p_ci_builds" inheritance tree
316INFO: analyzing "public.ci_builds"
317INFO: "ci_builds": 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_builds_101"
319INFO: "ci_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_builds_102"
321INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
322I, [2024-07-31T10:25:40.040144 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
323I, [2024-07-31T10:26:00.103097 #326] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
324INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
325INFO: analyzing "public.ci_pipeline_variables"
326INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
327I, [2024-07-31T10:26:01.829795 #326] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
328I, [2024-07-31T10:26:01.831017 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
329I, [2024-07-31T10:26:01.831884 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
330INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
331INFO: analyzing "public.ci_pipeline_variables"
332INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
333INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
334INFO: analyzing "public.ci_job_artifacts"
335INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
336I, [2024-07-31T10:26:08.004861 #326] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
337I, [2024-07-31T10:26:08.006189 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
338I, [2024-07-31T10:26:08.007053 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
339INFO: analyzing "public.p_ci_stages" inheritance tree
340INFO: analyzing "public.ci_stages"
341INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
342I, [2024-07-31T10:26:11.503477 #326] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
343I, [2024-07-31T10:26:11.504912 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
344I, [2024-07-31T10:26:11.505817 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
345I, [2024-07-31T10:26:12.723533 #326] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
346I, [2024-07-31T10:26:19.171417 #326] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
347I, [2024-07-31T10:26:32.986472 #326] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
348I, [2024-07-31T10:26:32.987629 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
349I, [2024-07-31T10:26:32.988382 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
350I, [2024-07-31T10:26:44.546431 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
351I, [2024-07-31T10:26:51.489514 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
352# [RSpecRunTime] Finishing example group spec/migrations/20230809203254_ensure_issue_user_mentions_bigint_backfill_is_finished_for_self_managed_spec.rb. It took 3 minutes 4.1 seconds. Expected to take 1 minute 42.31 seconds.
353# [RSpecRunTime] RSpec elapsed time: 8 minutes 26.68 seconds. Current RSS: ~1371M. Threads: 2. load average: 1.02 1.11 1.14 1/263 411
354.
355# [RSpecRunTime] Starting example group spec/migrations/20230831084632_queue_sync_scan_result_policies_spec.rb. Expected to take 1 minute 32.58 seconds.
356QueueSyncScanResultPolicies
357main: == [advisory_lock_connection] object_id: 153661060, pg_backend_pid: 3103
358main: == 20230831084632 QueueSyncScanResultPolicies: migrating ======================
359main: == 20230831084632 QueueSyncScanResultPolicies: migrated (0.0626s) =============
360main: == [advisory_lock_connection] object_id: 153661060, pg_backend_pid: 3103
361 schedules a new batched migration
362INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
363INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
364INFO: "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
365INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
366INFO: "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
367INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
368INFO: "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
369INFO: analyzing "public.p_ci_job_annotations" inheritance tree
370INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
371INFO: "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
372INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
373INFO: "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
374INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
375INFO: "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
376INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
377INFO: analyzing "public.ci_builds_metadata"
378INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
379INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
380INFO: "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
381INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
382INFO: "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
383INFO: analyzing "public.p_ci_builds" inheritance tree
384INFO: analyzing "public.ci_builds"
385INFO: "ci_builds": 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_builds_101"
387INFO: "ci_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_builds_102"
389INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
390I, [2024-07-31T10:28:33.266880 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
391I, [2024-07-31T10:28:53.118307 #326] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
392INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
393INFO: analyzing "public.ci_pipeline_variables"
394INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
395I, [2024-07-31T10:28:54.725747 #326] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
396I, [2024-07-31T10:28:54.726872 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
397I, [2024-07-31T10:28:54.727633 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
398INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
399INFO: analyzing "public.ci_pipeline_variables"
400INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
401INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
402INFO: analyzing "public.ci_job_artifacts"
403INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
404I, [2024-07-31T10:29:00.789700 #326] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
405I, [2024-07-31T10:29:00.790951 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
406I, [2024-07-31T10:29:00.791764 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
407INFO: analyzing "public.p_ci_stages" inheritance tree
408INFO: analyzing "public.ci_stages"
409INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
410I, [2024-07-31T10:29:04.132992 #326] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
411I, [2024-07-31T10:29:04.134192 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
412I, [2024-07-31T10:29:04.135050 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
413I, [2024-07-31T10:29:05.373164 #326] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
414I, [2024-07-31T10:29:11.708283 #326] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
415I, [2024-07-31T10:29:25.412448 #326] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
416I, [2024-07-31T10:29:25.413711 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
417I, [2024-07-31T10:29:25.414487 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
418I, [2024-07-31T10:29:36.924161 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
419I, [2024-07-31T10:29:43.626005 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
420# [RSpecRunTime] Finishing example group spec/migrations/20230831084632_queue_sync_scan_result_policies_spec.rb. It took 2 minutes 51.46 seconds. Expected to take 1 minute 32.58 seconds.
421# [RSpecRunTime] RSpec elapsed time: 11 minutes 18.21 seconds. Current RSS: ~1360M. Threads: 2. load average: 0.88 1.05 1.10 1/265 412
422.
423# [RSpecRunTime] Starting example group spec/migrations/20240222214106_set_default_organization_visibility_to_public_spec.rb. Expected to take 1 minute 12.74 seconds.
424SetDefaultOrganizationVisibilityToPublic
425 #down
426 when default organization exists
427main: == [advisory_lock_connection] object_id: 187289260, pg_backend_pid: 3120
428main: == 20240222214106 SetDefaultOrganizationVisibilityToPublic: migrating =========
429main: == 20240222214106 SetDefaultOrganizationVisibilityToPublic: migrated (0.0175s)
430main: == [advisory_lock_connection] object_id: 187289260, pg_backend_pid: 3120
431 updates only the default organization visibility_level to private
432# [RSpecRunTime] RSpec elapsed time: 12 minutes 9.18 seconds. Current RSS: ~1382M. Threads: 2. load average: 0.96 1.04 1.10 1/265 413
433.
434 when default organization does not exist
435 does not error
436# [RSpecRunTime] RSpec elapsed time: 12 minutes 17.25 seconds. Current RSS: ~1404M. Threads: 2. load average: 0.97 1.04 1.09 1/265 414
437.
438 #up
439 when default organization exists
440main: == [advisory_lock_connection] object_id: 189290560, pg_backend_pid: 3125
441main: == 20240222214106 SetDefaultOrganizationVisibilityToPublic: migrating =========
442main: == 20240222214106 SetDefaultOrganizationVisibilityToPublic: migrated (0.0154s)
443main: == [advisory_lock_connection] object_id: 189290560, pg_backend_pid: 3125
444 updates only the default organization visibility_level to public
445# [RSpecRunTime] RSpec elapsed time: 12 minutes 23.47 seconds. Current RSS: ~1422M. Threads: 2. load average: 0.97 1.04 1.09 1/265 415
446.
447 when default organization does not exist
448main: == [advisory_lock_connection] object_id: 189915940, pg_backend_pid: 3128
449main: == 20240222214106 SetDefaultOrganizationVisibilityToPublic: migrating =========
450main: == 20240222214106 SetDefaultOrganizationVisibilityToPublic: migrated (0.0496s)
451main: == [advisory_lock_connection] object_id: 189915940, pg_backend_pid: 3128
452 does not error
453# [RSpecRunTime] RSpec elapsed time: 12 minutes 30.65 seconds. Current RSS: ~1467M. Threads: 2. load average: 0.97 1.04 1.09 1/265 416
454.
455I, [2024-07-31T10:31:08.604966 #326] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
456I, [2024-07-31T10:31:14.992313 #326] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
457I, [2024-07-31T10:31:28.746960 #326] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
458I, [2024-07-31T10:31:28.748191 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
459I, [2024-07-31T10:31:28.748953 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
460I, [2024-07-31T10:31:39.916445 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
461I, [2024-07-31T10:31:46.294574 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
462# [RSpecRunTime] Finishing example group spec/migrations/20240222214106_set_default_organization_visibility_to_public_spec.rb. It took 2 minutes 2.51 seconds. Expected to take 1 minute 12.74 seconds.
463# [RSpecRunTime] RSpec elapsed time: 13 minutes 20.78 seconds. Current RSS: ~1379M. Threads: 2. load average: 0.92 1.01 1.08 1/265 417
464.
465# [RSpecRunTime] Starting example group spec/migrations/20231207194620_backfill_catalog_resources_visibility_level_spec.rb. Expected to take 1 minute 10.6 seconds.
466BackfillCatalogResourcesVisibilityLevel
467 #up
468main: == [advisory_lock_connection] object_id: 218272860, pg_backend_pid: 3142
469main: == 20231207194620 BackfillCatalogResourcesVisibilityLevel: migrating ==========
470main: -- execute(" UPDATE catalog_resources\n SET visibility_level = projects.visibility_level\n FROM projects\n WHERE catalog_resources.project_id = projects.id\n")
471main: -> 0.0024s
472main: == 20231207194620 BackfillCatalogResourcesVisibilityLevel: migrated (0.0112s) =
473main: == [advisory_lock_connection] object_id: 218272860, pg_backend_pid: 3142
474 updates the visibility_level to match the project
475# [RSpecRunTime] RSpec elapsed time: 14 minutes 24.35 seconds. Current RSS: ~1395M. Threads: 2. load average: 1.45 1.14 1.12 1/265 418
476.
477I, [2024-07-31T10:33:07.040671 #326] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
478INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
479INFO: analyzing "public.ci_pipeline_variables"
480INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
481I, [2024-07-31T10:33:08.647179 #326] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
482I, [2024-07-31T10:33:08.648403 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
483I, [2024-07-31T10:33:08.649239 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
484INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
485INFO: analyzing "public.ci_pipeline_variables"
486INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
487INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
488INFO: analyzing "public.ci_job_artifacts"
489INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
490I, [2024-07-31T10:33:14.573713 #326] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
491I, [2024-07-31T10:33:14.574846 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
492I, [2024-07-31T10:33:14.575680 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
493INFO: analyzing "public.p_ci_stages" inheritance tree
494INFO: analyzing "public.ci_stages"
495INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
496I, [2024-07-31T10:33:17.929107 #326] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
497I, [2024-07-31T10:33:17.930211 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
498I, [2024-07-31T10:33:17.931024 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
499I, [2024-07-31T10:33:19.053284 #326] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
500I, [2024-07-31T10:33:25.206167 #326] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
501I, [2024-07-31T10:33:38.457884 #326] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
502I, [2024-07-31T10:33:38.459021 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
503I, [2024-07-31T10:33:38.459782 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
504I, [2024-07-31T10:33:49.517137 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
505I, [2024-07-31T10:33:55.877780 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
506# [RSpecRunTime] Finishing example group spec/migrations/20231207194620_backfill_catalog_resources_visibility_level_spec.rb. It took 2 minutes 9.63 seconds. Expected to take 1 minute 10.6 seconds.
507# [RSpecRunTime] RSpec elapsed time: 15 minutes 30.47 seconds. Current RSS: ~1376M. Threads: 2. load average: 1.04 1.08 1.10 1/263 419
508.
509# [RSpecRunTime] Starting example group spec/migrations/20240403121221_migrate_inputs_to_spec_on_catalog_resource_components_spec.rb. Expected to take 43.5 seconds.
510MigrateInputsToSpecOnCatalogResourceComponents
511main: == [advisory_lock_connection] object_id: 246115240, pg_backend_pid: 3157
512main: == 20240403121221 MigrateInputsToSpecOnCatalogResourceComponents: migrating ===
513main: -- execute("UPDATE catalog_resource_components\nSET spec = jsonb_set('{}'::jsonb, '{inputs}', inputs::jsonb)\nWHERE id BETWEEN 1 AND 3\nAND spec = '{}' AND inputs <> '{}'\n")
514main: -> 0.0021s
515main: == 20240403121221 MigrateInputsToSpecOnCatalogResourceComponents: migrated (0.0347s)
516main: == [advisory_lock_connection] object_id: 246115240, pg_backend_pid: 3157
517 fills in the spec column for components with an inputs value and no spec value
518I, [2024-07-31T10:34:51.628365 #326] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
519I, [2024-07-31T10:35:05.384469 #326] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
520I, [2024-07-31T10:35:05.385810 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
521I, [2024-07-31T10:35:05.386559 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
522I, [2024-07-31T10:35:16.743368 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
523I, [2024-07-31T10:35:23.299030 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
524# [RSpecRunTime] Finishing example group spec/migrations/20240403121221_migrate_inputs_to_spec_on_catalog_resource_components_spec.rb. It took 1 minute 27.35 seconds. Expected to take 43.5 seconds.
525# [RSpecRunTime] RSpec elapsed time: 16 minutes 57.88 seconds. Current RSS: ~1359M. Threads: 2. load average: 1.01 1.06 1.09 1/263 420
526.
527# [RSpecRunTime] Starting example group spec/migrations/20240627122810_queue_queue_backfill_autocancel_partition_id_on_ci_pipelines_spec.rb. Expected to take 30.29 seconds.
528QueueQueueBackfillAutocancelPartitionIdOnCiPipelines
529ci: == [advisory_lock_connection] object_id: 263333660, pg_backend_pid: 3401
530ci: == 20240627122810 QueueQueueBackfillAutocancelPartitionIdOnCiPipelines: migrating
531ci: == 20240627122810 QueueQueueBackfillAutocancelPartitionIdOnCiPipelines: migrated (0.0755s)
532ci: == [advisory_lock_connection] object_id: 263333660, pg_backend_pid: 3401
533 schedules a new batched migration
534 when executed on .com
535ci: == [advisory_lock_connection] object_id: 264942660, pg_backend_pid: 3409
536ci: == 20240627122810 QueueQueueBackfillAutocancelPartitionIdOnCiPipelines: migrating
537ci: == 20240627122810 QueueQueueBackfillAutocancelPartitionIdOnCiPipelines: migrated (0.0781s)
538ci: == [advisory_lock_connection] object_id: 264942660, pg_backend_pid: 3409
539 schedules a new batched migration
540# [RSpecRunTime] RSpec elapsed time: 17 minutes 26.33 seconds. Current RSS: ~1398M. Threads: 2. load average: 1.13 1.08 1.09 1/263 421
541.
542I, [2024-07-31T10:36:07.001484 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
543I, [2024-07-31T10:36:18.913404 #326] INFO -- : Database: 'ci', Table: 'approval_policy_rule_project_links': Lock Writes
544I, [2024-07-31T10:36:19.347484 #326] INFO -- : Database: 'ci', Table: 'virtual_registries_packages_maven_cached_responses': Lock Writes
545I, [2024-07-31T10:36:19.348714 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
546I, [2024-07-31T10:36:19.349471 #326] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
547# [RSpecRunTime] Finishing example group spec/migrations/20240627122810_queue_queue_backfill_autocancel_partition_id_on_ci_pipelines_spec.rb. It took 53.02 seconds. Expected to take 30.29 seconds.
548# [RSpecRunTime] RSpec elapsed time: 17 minutes 50.95 seconds. Current RSS: ~1357M. Threads: 2. load average: 1.01 1.06 1.08 1/263 422
549.
550# [RSpecRunTime] Starting example group spec/migrations/20240320193910_queue_backfill_epic_issues_into_work_item_parent_links_spec.rb. Expected to take 21.72 seconds.
551QueueBackfillEpicIssuesIntoWorkItemParentLinks
552main: == [advisory_lock_connection] object_id: 271568840, pg_backend_pid: 3653
553main: == 20240522183910 QueueBackfillEpicIssuesIntoWorkItemParentLinks: migrating ===
554main: == 20240522183910 QueueBackfillEpicIssuesIntoWorkItemParentLinks: migrated (0.0619s)
555main: == [advisory_lock_connection] object_id: 271568840, pg_backend_pid: 3653
556 schedules a new batched migration
557I, [2024-07-31T10:36:56.438456 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
558I, [2024-07-31T10:37:03.095929 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
559# [RSpecRunTime] Finishing example group spec/migrations/20240320193910_queue_backfill_epic_issues_into_work_item_parent_links_spec.rb. It took 46.69 seconds. Expected to take 21.72 seconds.
560# [RSpecRunTime] RSpec elapsed time: 18 minutes 37.69 seconds. Current RSS: ~1342M. Threads: 2. load average: 1.04 1.06 1.09 2/263 423
561.
562# [RSpecRunTime] Starting example group spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb. Expected to take 19.27 seconds.
563QueueBackfillProjectRelationExportsProjectId
564main: == [advisory_lock_connection] object_id: 278515540, pg_backend_pid: 3665
565main: == 20240605113250 QueueBackfillProjectRelationExportsProjectId: migrating =====
566main: == 20240605113250 QueueBackfillProjectRelationExportsProjectId: migrated (0.0577s)
567main: == [advisory_lock_connection] object_id: 278515540, pg_backend_pid: 3665
568 schedules a new batched migration
569I, [2024-07-31T10:37:38.446128 #326] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
570I, [2024-07-31T10:37:44.868440 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
571# [RSpecRunTime] Finishing example group spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb. It took 41.64 seconds. Expected to take 19.27 seconds.
572# [RSpecRunTime] RSpec elapsed time: 19 minutes 19.39 seconds. Current RSS: ~1337M. Threads: 2. load average: 0.98 1.04 1.08 1/263 424
573.
574# [RSpecRunTime] Starting example group spec/migrations/re_add_tags_name_unique_index_spec.rb. Expected to take 17.7 seconds.
575ReAddTagsNameUniqueIndex
576 when the index does not exist
577main: -- indexes(:tags)
578main: -> 0.0050s
579main: -- current_schema(nil)
580main: -> 0.0009s
581main: -- transaction_open?(nil)
582main: -> 0.0002s
583main: -- view_exists?(:postgres_partitions)
584main: -> 0.0018s
585main: -- index_exists?(:tags, :name, {:unique=>true, :name=>"index_tags_on_name", :algorithm=>:concurrently})
586main: -> 0.0049s
587main: -- add_index(:tags, :name, {:unique=>true, :name=>"index_tags_on_name", :algorithm=>:concurrently})
588main: -> 0.0019s
589main: -- indexes(:tags)
590main: -> 0.0061s
591 creates the index
592# [RSpecRunTime] RSpec elapsed time: 19 minutes 32.16 seconds. Current RSS: ~1391M. Threads: 2. load average: 0.98 1.04 1.08 1/263 425
593.
594 when the index already exist
595main: -- indexes(:tags)
596main: -> 0.0070s
597 does not do anything
598# [RSpecRunTime] RSpec elapsed time: 19 minutes 36.5 seconds. Current RSS: ~1410M. Threads: 2. load average: 0.98 1.04 1.07 1/263 426
599.
600I, [2024-07-31T10:38:20.242192 #326] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
601# [RSpecRunTime] Finishing example group spec/migrations/re_add_tags_name_unique_index_spec.rb. It took 35.34 seconds. Expected to take 17.7 seconds.
602# [RSpecRunTime] RSpec elapsed time: 19 minutes 54.79 seconds. Current RSS: ~1340M. Threads: 2. load average: 0.99 1.03 1.07 1/263 427
603.
604# [RSpecRunTime] Starting example group spec/migrations/active_record/schema_spec.rb. Expected to take 8.83 seconds.
605ActiveRecord::Schema
606 > schema version should equal the latest migration timestamp stored in schema_migrations table
607 the schema_migrations table contains all schema versions
608# [RSpecRunTime] Finishing example group spec/migrations/active_record/schema_spec.rb. It took 16.39 seconds. Expected to take 8.83 seconds.
609# [RSpecRunTime] RSpec elapsed time: 20 minutes 11.24 seconds. Current RSS: ~1380M. Threads: 2. load average: 1.13 1.06 1.08 1/263 428
610.
611# [RSpecRunTime] Starting example group spec/migrations/20240718085345_queue_delete_packages_composer_cache_file_records_spec.rb. Expected to take 5.51 seconds.
612QueueDeletePackagesComposerCacheFileRecords
613main: == [advisory_lock_connection] object_id: 288823080, pg_backend_pid: 3697
614main: == 20240718085345 QueueDeletePackagesComposerCacheFileRecords: migrating ======
615main: == 20240718085345 QueueDeletePackagesComposerCacheFileRecords: migrated (0.0654s)
616main: == [advisory_lock_connection] object_id: 288823080, pg_backend_pid: 3697
617 schedules a new batched migration
618# [RSpecRunTime] Finishing example group spec/migrations/20240718085345_queue_delete_packages_composer_cache_file_records_spec.rb. It took 13.87 seconds. Expected to take 5.51 seconds.
619# [RSpecRunTime] RSpec elapsed time: 20 minutes 25.17 seconds. Current RSS: ~1380M. Threads: 2. load average: 1.18 1.07 1.09 1/263 429
620.
621auto_explain log contains 968 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-5-26.326.main.ndjson.gz
622took 29.475422743
623auto_explain log contains 970 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-5-26.326.ci.ndjson.gz
624took 25.790256561
625[TEST PROF INFO] EventProf results for sql.active_record
626Total time: 13:18.146 of 20:17.986 (65.53%)
627Total events: 643981
628Top 5 slowest suites (by time):
629QueueBackfill...pelineChatData (./spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb:6) – 03:20.140 (173787 / 3) of 05:15.350 (63.47%)
630EnsureIssueUs...ForSelfManaged (./spec/migrations/20230809203254_ensure_issue_user_mentions_bigint_backfill_is_finished_for_self_managed_spec.rb:6) – 02:04.589 (91308 / 2) of 03:04.158 (67.65%)
631QueueSyncScanResultPolicies (./spec/migrations/20230831084632_queue_sync_scan_result_policies_spec.rb:6) – 01:58.998 (87332 / 1) of 02:51.523 (69.38%)
632BackfillCatal...isibilityLevel (./spec/migrations/20231207194620_backfill_catalog_resources_visibility_level_spec.rb:6) – 01:33.345 (72109 / 1) of 02:09.686 (71.98%)
633SetDefaultOrg...bilityToPublic (./spec/migrations/20240222214106_set_default_organization_visibility_to_public_spec.rb:6) – 01:15.797 (63515 / 4) of 02:02.571 (61.84%)
634Knapsack report was generated. Preview:
635{
636 "spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb": 177.48624522399996,
637 "spec/migrations/20230809203254_ensure_issue_user_mentions_bigint_backfill_is_finished_for_self_managed_spec.rb": 96.40878429399982,
638 "spec/migrations/20230831084632_queue_sync_scan_result_policies_spec.rb": 87.81071578800038,
639 "spec/migrations/20240222214106_set_default_organization_visibility_to_public_spec.rb": 72.58929476000003,
640 "spec/migrations/20231207194620_backfill_catalog_resources_visibility_level_spec.rb": 63.7041273169998,
641 "spec/migrations/20240403121221_migrate_inputs_to_spec_on_catalog_resource_components_spec.rb": 42.036208486000305,
642 "spec/migrations/20240627122810_queue_queue_backfill_autocancel_partition_id_on_ci_pipelines_spec.rb": 28.588336619000074,
643 "spec/migrations/20240320193910_queue_backfill_epic_issues_into_work_item_parent_links_spec.rb": 20.82516807799948,
644 "spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb": 18.554376834999857,
645 "spec/migrations/re_add_tags_name_unique_index_spec.rb": 17.244582489000095,
646 "spec/migrations/active_record/schema_spec.rb": 8.812014444999477,
647 "spec/migrations/20240718085345_queue_delete_packages_composer_cache_file_records_spec.rb": 5.742680045999805
648}
649Knapsack global time execution for tests: 10m 39s
650Finished in 21 minutes 21 seconds (files took 2 minutes 12.7 seconds to load)
65121 examples, 0 failures
652Randomized with seed 64563
653[TEST PROF INFO] Time spent in factories: 00:00.204 (0.02% of total time)
654RSpec exited with 0.
655No examples to retry, congrats!
656 Running after_script 00:24
657Running after script...
658$ source scripts/utils.sh
659$ bundle exec gem list gitlab_quality-test_tooling
660gitlab_quality-test_tooling (1.33.0)
661$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
662 Report test failures 00:03
665$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
666 Report test flakiness 00:00
668$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
669 Report test slowness 00:15
674$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
675 Report test slowness in MR note 00:00
677$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
678 Report test files close to timing out 00:02
681$ tooling/bin/push_job_metrics || true
682[job-metrics] Pushing job metrics file for the CI/CD job.
683[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
684 Saving cache for successful job 00:01
685Not uploading cache ruby-gems-debian-bookworm-ruby-3.1.5-17 due to policy
686 Uploading artifacts for successful job 00:05
687Uploading artifacts...
688auto_explain/: found 3 matching artifact files and directories
689coverage/: found 5 matching artifact files and directories
690crystalball/: found 5 matching artifact files and directories
691WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
692knapsack/: found 4 matching artifact files and directories
693rspec/: found 12 matching artifact files and directories
694WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
695log/*.log: found 13 matching artifact files and directories
696WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7471345400/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
697WARNING: Retrying... context=artifacts-uploader error=request redirected
698Uploading artifacts as "archive" to coordinator... 201 Created id=7471345400 responseStatus=201 Created token=glcbt-66
699Uploading artifacts...
700rspec/rspec-*.xml: found 1 matching artifact files and directories
701WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7471345400/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
702WARNING: Retrying... context=artifacts-uploader error=request redirected
703Uploading artifacts as "junit" to coordinator... 201 Created id=7471345400 responseStatus=201 Created token=glcbt-66
704 Cleaning up project directory and file based variables 00:01
705Job succeeded