rspec migration pg14 11/15

Passed Started 1 year ago by
1Running with gitlab-runner 16.8.0~beta.67.gb5664e8e (b5664e8e)
2  on blue-6.private.runners-manager.gitlab.com/gitlab.com/gitlab-org mKJTFBei, system ID: s_90a66139ec06
3  feature flags: FF_NETWORK_PER_BUILD:true, FF_USE_IMPROVED_URL_MASKING:true
 4  Resolving secrets  
  00:00
 5  Preparing the "docker+machine" executor  
  00:08
6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-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:b6c412dc298714b5c26feca1330d8f743b48caf0b1feeadfd554d7d446f985a5 for redis:6.2-alpine with digest redis@sha256:51d6c56749a4243096327e3fb964a48ed92254357108449cb6e23999c37773c5 ...
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.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 ...
23Using docker image sha256:0704d2ebff510f3d6f608cd8e9064640a9dd041c4e3ac1e1ff2afb5fe22850bc for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14@sha256:bbf2c8a67a31469f488a4541826628901b6eb5c0a4d68147919aca0b98107df6 ...
 24  Preparing environment  
  00:01
25Running on runner-mkjtfbei-project-278964-concurrent-0 via runner-mkjtfbei-private-1708467131-a21558fd...
 26  Getting source from Git repository  
  00:00
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
 30  Restoring cache  
  00:17
31Checking cache for ruby-gems-debian-bookworm-ruby-3.1-16...
32cache.zip is up to date                            
33Successfully extracted cache
 34  Downloading artifacts  
  00:34
35Downloading artifacts for clone-gitlab-repo (6218670526)...
36Downloading artifacts from coordinator... ok        host=storage.googleapis.com id=6218670526 responseStatus=200 OK token=glcbt-65
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 (6218670555)...
40Downloading artifacts from coordinator... ok        host=storage.googleapis.com id=6218670555 responseStatus=200 OK token=glcbt-65
41Downloading artifacts for retrieve-tests-metadata (6218670576)...
42Downloading artifacts from coordinator... ok        host=storage.googleapis.com id=6218670576 responseStatus=200 OK token=glcbt-65
43Downloading artifacts for setup-test-env (6218670568)...
44Downloading artifacts from coordinator... ok        host=storage.googleapis.com id=6218670568 responseStatus=200 OK token=glcbt-65
 45  Executing "step_script" stage of the job script  
  30:00
46Using docker image sha256:0704d2ebff510f3d6f608cd8e9064640a9dd041c4e3ac1e1ff2afb5fe22850bc for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14@sha256:bbf2c8a67a31469f488a4541826628901b6eb5c0a4d68147919aca0b98107df6 ...
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:18
731Using decomposed database config (config/database.yml.decomposed-postgresql)
732Geo DB won't be set up.
733Embedding DB won't be set up.
 734  Setting up DBs  
  00:53
785$ source ./scripts/rspec_helpers.sh
786$ run_timed_command "gem install knapsack --no-document"
787$ gem install knapsack --no-document
788Successfully installed knapsack-4.0.0
7891 gem installed
790==> 'gem install knapsack --no-document' succeeded in 1 seconds.
791$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
 792  Spawning Gitaly  
  00:03
796$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
797$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
798$ tooling/bin/create_job_metrics_file || true
799[job-metrics] Creating the job metrics file for the CI/CD job.
800$ rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
801[23:18:47] Starting rspec_parallelized_job
802RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
803KNAPSACK_GENERATE_REPORT: true
804FLAKY_RSPEC_GENERATE_REPORT: true
805KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
806KNAPSACK_LOG_LEVEL: debug
807KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_11_15_278964_report.json
808FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
809FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_11_15_278964_report.json
810NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_11_15_278964_report.json
811RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6218670739.txt
812CRYSTALBALL: 
813RSPEC_TESTS_MAPPING_ENABLED: 
814RSPEC_TESTS_FILTER_FILE: 
815Shell set options (set -o) enabled:
816braceexpand    	on
817hashall        	on
818interactive-comments	on
819pipefail       	on
820Knapsack report generator started!
821Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
822# [RSpecRunTime] Starting RSpec timer...
823[TEST PROF INFO] EventProf enabled (sql.active_record)
824unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
825unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
826Test environment set up in 0.872531925 seconds
827# [RSpecRunTime] Starting example group spec/migrations/20230418215853_add_assignee_widget_to_incidents_spec.rb. Expected to take 1 minute 32.08 seconds.
828AddAssigneeWidgetToIncidents
829  #up
830main: == [advisory_lock_connection] object_id: 9139640, pg_backend_pid: 135
831main: == 20230418215853 AddAssigneeWidgetToIncidents: migrating =====================
832main: == 20230418215853 AddAssigneeWidgetToIncidents: migrated (0.0238s) ============
833main: == [advisory_lock_connection] object_id: 9139640, pg_backend_pid: 135
834    creates widget definition
835main: == [advisory_lock_connection] object_id: 10219680, pg_backend_pid: 137
836main: == 20230418215853 AddAssigneeWidgetToIncidents: migrating =====================
837main: -- type Incident is missing, not adding widget
838main: == 20230418215853 AddAssigneeWidgetToIncidents: migrated (0.0096s) ============
839main: == [advisory_lock_connection] object_id: 10219680, pg_backend_pid: 137
840    logs a warning if the type is missing
841  #down
842main: == [advisory_lock_connection] object_id: 10770100, pg_backend_pid: 139
843main: == 20230418215853 AddAssigneeWidgetToIncidents: migrating =====================
844main: == 20230418215853 AddAssigneeWidgetToIncidents: migrated (0.0398s) ============
845main: == [advisory_lock_connection] object_id: 10770100, pg_backend_pid: 139
846    removes definitions for widget
847# [RSpecRunTime] RSpec elapsed time: 1 minute 12.42 seconds. Current RSS: ~1355M. load average: 1.03 1.15 1.25 1/272 386
848INFO:  analyzing "public.p_ci_builds" inheritance tree
849INFO:  analyzing "public.ci_builds"
850INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
851I, [2024-02-20T23:20:52.665991 #340]  INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes
852I, [2024-02-20T23:20:52.667932 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
853I, [2024-02-20T23:20:52.668879 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
854I, [2024-02-20T23:21:05.941321 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
855INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
856INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
857INFO:  "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
858INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
859INFO:  "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
860INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
861INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
862INFO:  "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
863INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
864INFO:  analyzing "public.ci_builds_metadata"
865INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
866INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
867INFO:  "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
868INFO:  analyzing "public.p_ci_builds" inheritance tree
869INFO:  analyzing "public.ci_builds"
870INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
871I, [2024-02-20T23:21:30.251630 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
872INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
873INFO:  analyzing "public.ci_pipeline_variables"
874INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
875I, [2024-02-20T23:21:51.371898 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
876I, [2024-02-20T23:21:51.372984 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
877I, [2024-02-20T23:21:51.373740 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
878I, [2024-02-20T23:21:51.922819 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
879INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
880INFO:  analyzing "public.ci_job_artifacts"
881INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
882I, [2024-02-20T23:21:53.107668 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
883I, [2024-02-20T23:21:53.108883 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
884I, [2024-02-20T23:21:53.109824 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
885INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
886INFO:  analyzing "public.ci_pipeline_variables"
887INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
888# [RSpecRunTime] Finishing example group spec/migrations/20230418215853_add_assignee_widget_to_incidents_spec.rb. It took 2 minutes 24.51 seconds. Expected to take 1 minute 32.08 seconds.
889# [RSpecRunTime] RSpec elapsed time: 2 minutes 28.02 seconds. Current RSS: ~1343M. load average: 1.08 1.13 1.23 1/272 388
890# [RSpecRunTime] Starting example group spec/migrations/swap_vulnerability_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb. Expected to take 1 minute 22.75 seconds.
891SwapVulnerabilityUserMentionsNoteIdToBigintForGitlabDotCom
892  #up
893    swaps the integer and bigint columns for GitLab.com, dev, or test
894    is a no-op for other instances
895# [RSpecRunTime] RSpec elapsed time: 3 minutes 39.52 seconds. Current RSS: ~1348M. load average: 1.22 1.16 1.23 1/274 389
896INFO:  analyzing "public.p_ci_builds" inheritance tree
897INFO:  analyzing "public.ci_builds"
898INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
899I, [2024-02-20T23:23:26.286207 #340]  INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes
900I, [2024-02-20T23:23:26.288104 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
901I, [2024-02-20T23:23:26.289902 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
902I, [2024-02-20T23:23:40.674351 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
903INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
904INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
905INFO:  "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
906INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
907INFO:  "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
908INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
909INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
910INFO:  "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
911INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
912INFO:  analyzing "public.ci_builds_metadata"
913INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
914INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
915INFO:  "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
916INFO:  analyzing "public.p_ci_builds" inheritance tree
917INFO:  analyzing "public.ci_builds"
918INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
919I, [2024-02-20T23:24:04.914324 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
920INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
921INFO:  analyzing "public.ci_pipeline_variables"
922INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
923I, [2024-02-20T23:24:25.194203 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
924I, [2024-02-20T23:24:25.195214 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
925I, [2024-02-20T23:24:25.195958 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
926I, [2024-02-20T23:24:25.636334 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
927INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
928INFO:  analyzing "public.ci_job_artifacts"
929INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
930I, [2024-02-20T23:24:26.640082 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
931I, [2024-02-20T23:24:26.641153 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
932I, [2024-02-20T23:24:26.641996 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
933INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
934INFO:  analyzing "public.ci_pipeline_variables"
935INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
936# [RSpecRunTime] Finishing example group spec/migrations/swap_vulnerability_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb. It took 2 minutes 33.1 seconds. Expected to take 1 minute 22.75 seconds.
937# [RSpecRunTime] RSpec elapsed time: 5 minutes 1.16 seconds. Current RSS: ~1363M. load average: 0.97 1.10 1.21 1/275 390
938# [RSpecRunTime] Starting example group spec/migrations/20230224144233_migrate_evidences_from_raw_metadata_spec.rb. Expected to take 1 minute 18.68 seconds.
939MigrateEvidencesFromRawMetadata
940  #down
941main: == [advisory_lock_connection] object_id: 63801640, pg_backend_pid: 176
942main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrating ==================
943main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrated (0.0080s) =========
944main: == [advisory_lock_connection] object_id: 63801640, pg_backend_pid: 176
945    deletes all batched migration records
946# [RSpecRunTime] RSpec elapsed time: 6 minutes 11.68 seconds. Current RSS: ~1343M. load average: 1.21 1.15 1.21 1/273 392
947  #up
948main: == [advisory_lock_connection] object_id: 65119180, pg_backend_pid: 179
949main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrating ==================
950main: == 20230224144233 MigrateEvidencesFromRawMetadata: migrated (0.0091s) =========
951main: == [advisory_lock_connection] object_id: 65119180, pg_backend_pid: 179
952    schedules a batched background migration
953# [RSpecRunTime] RSpec elapsed time: 6 minutes 14.97 seconds. Current RSS: ~1348M. load average: 1.20 1.15 1.21 1/273 393
954INFO:  analyzing "public.p_ci_builds" inheritance tree
955INFO:  analyzing "public.ci_builds"
956INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
957I, [2024-02-20T23:26:05.540351 #340]  INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes
958I, [2024-02-20T23:26:05.541679 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
959I, [2024-02-20T23:26:05.542588 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
960I, [2024-02-20T23:26:20.625323 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
961INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
962INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
963INFO:  "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
964INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
965INFO:  "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
966INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
967INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
968INFO:  "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
969INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
970INFO:  analyzing "public.ci_builds_metadata"
971INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
972INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
973INFO:  "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
974INFO:  analyzing "public.p_ci_builds" inheritance tree
975INFO:  analyzing "public.ci_builds"
976INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
977I, [2024-02-20T23:26:46.520781 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
978INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
979INFO:  analyzing "public.ci_pipeline_variables"
980INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
981I, [2024-02-20T23:27:08.357863 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
982I, [2024-02-20T23:27:08.358919 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
983I, [2024-02-20T23:27:08.359664 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
984I, [2024-02-20T23:27:08.850385 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
985INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
986INFO:  analyzing "public.ci_job_artifacts"
987INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
988I, [2024-02-20T23:27:10.134284 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
989I, [2024-02-20T23:27:10.135355 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
990I, [2024-02-20T23:27:10.136146 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
991INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
992INFO:  analyzing "public.ci_pipeline_variables"
993INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
994# [RSpecRunTime] Finishing example group spec/migrations/20230224144233_migrate_evidences_from_raw_metadata_spec.rb. It took 2 minutes 44.22 seconds. Expected to take 1 minute 18.68 seconds.
995# [RSpecRunTime] RSpec elapsed time: 7 minutes 45.42 seconds. Current RSS: ~1369M. load average: 1.10 1.12 1.19 1/274 394
996# [RSpecRunTime] Starting example group spec/migrations/swap_snippet_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb. Expected to take 1 minute 15.89 seconds.
997SwapSnippetUserMentionsNoteIdToBigintForGitlabDotCom
998  #up
999    swaps the integer and bigint columns for GitLab.com, dev, or test
1000    is a no-op for other instances
1001# [RSpecRunTime] RSpec elapsed time: 8 minutes 57.54 seconds. Current RSS: ~1365M. load average: 1.02 1.09 1.17 1/274 395
1002INFO:  analyzing "public.p_ci_builds" inheritance tree
1003INFO:  analyzing "public.ci_builds"
1004INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1005I, [2024-02-20T23:28:43.091085 #340]  INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes
1006I, [2024-02-20T23:28:43.092212 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1007I, [2024-02-20T23:28:43.093044 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1008I, [2024-02-20T23:28:57.261873 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
1009INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1010INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1011INFO:  "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
1012INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1013INFO:  "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
1014INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1015INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1016INFO:  "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
1017INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1018INFO:  analyzing "public.ci_builds_metadata"
1019INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1020INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1021INFO:  "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
1022INFO:  analyzing "public.p_ci_builds" inheritance tree
1023INFO:  analyzing "public.ci_builds"
1024INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1025I, [2024-02-20T23:29:21.929403 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1026INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1027INFO:  analyzing "public.ci_pipeline_variables"
1028INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1029I, [2024-02-20T23:29:43.151651 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1030I, [2024-02-20T23:29:43.152983 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1031I, [2024-02-20T23:29:43.153775 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1032I, [2024-02-20T23:29:43.677931 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1033INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1034INFO:  analyzing "public.ci_job_artifacts"
1035INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1036I, [2024-02-20T23:29:44.870673 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1037I, [2024-02-20T23:29:44.871832 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1038I, [2024-02-20T23:29:44.872704 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1039INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1040INFO:  analyzing "public.ci_pipeline_variables"
1041INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1042# [RSpecRunTime] Finishing example group spec/migrations/swap_snippet_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb. It took 2 minutes 34.54 seconds. Expected to take 1 minute 15.89 seconds.
1043# [RSpecRunTime] RSpec elapsed time: 10 minutes 20.01 seconds. Current RSS: ~1348M. load average: 0.88 1.02 1.14 1/274 396
1044# [RSpecRunTime] Starting example group spec/migrations/20230321153035_add_package_id_created_at_desc_index_to_package_files_spec.rb. Expected to take 1 minute 12.35 seconds.
1045AddPackageIdCreatedAtDescIndexToPackageFiles
1046main: == [advisory_lock_connection] object_id: 119031120, pg_backend_pid: 217
1047main: == 20230321153035 AddPackageIdCreatedAtDescIndexToPackageFiles: migrating =====
1048main: -- view_exists?(:postgres_partitions)
1049main:    -> 0.0020s
1050main: -- index_exists?(:packages_package_files, "package_id, created_at DESC", {:name=>"index_packages_package_files_on_package_id_and_created_at_desc", :algorithm=>:concurrently})
1051main:    -> 0.0192s
1052main: -- add_index(:packages_package_files, "package_id, created_at DESC", {:name=>"index_packages_package_files_on_package_id_and_created_at_desc", :algorithm=>:concurrently})
1053main:    -> 0.0020s
1054main: == 20230321153035 AddPackageIdCreatedAtDescIndexToPackageFiles: migrated (0.0560s) 
1055main: == [advisory_lock_connection] object_id: 119031120, pg_backend_pid: 217
1056  correctly migrates up and down
1057INFO:  analyzing "public.p_ci_builds" inheritance tree
1058INFO:  analyzing "public.ci_builds"
1059INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1060I, [2024-02-20T23:31:18.863272 #340]  INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes
1061I, [2024-02-20T23:31:18.864437 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1062I, [2024-02-20T23:31:18.865242 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1063I, [2024-02-20T23:31:35.128470 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
1064INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1065INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1066INFO:  "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
1067INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1068INFO:  "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
1069INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1070INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1071INFO:  "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
1072INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1073INFO:  analyzing "public.ci_builds_metadata"
1074INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1075INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1076INFO:  "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
1077INFO:  analyzing "public.p_ci_builds" inheritance tree
1078INFO:  analyzing "public.ci_builds"
1079INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1080I, [2024-02-20T23:32:02.771654 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1081INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1082INFO:  analyzing "public.ci_pipeline_variables"
1083INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1084I, [2024-02-20T23:32:25.246388 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1085I, [2024-02-20T23:32:25.248270 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1086I, [2024-02-20T23:32:25.249315 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1087I, [2024-02-20T23:32:25.810690 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1088INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1089INFO:  analyzing "public.ci_job_artifacts"
1090INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1091I, [2024-02-20T23:32:26.890560 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1092I, [2024-02-20T23:32:26.891898 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1093I, [2024-02-20T23:32:26.892851 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1094INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1095INFO:  analyzing "public.ci_pipeline_variables"
1096INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1097# [RSpecRunTime] Finishing example group spec/migrations/20230321153035_add_package_id_created_at_desc_index_to_package_files_spec.rb. It took 2 minutes 42.09 seconds. Expected to take 1 minute 12.35 seconds.
1098# [RSpecRunTime] RSpec elapsed time: 13 minutes 2.14 seconds. Current RSS: ~1355M. load average: 0.96 1.04 1.13 1/274 397
1099# [RSpecRunTime] Starting example group spec/migrations/20230322145403_add_project_id_foreign_key_to_packages_npm_metadata_caches_spec.rb. Expected to take 1 minute 11.32 seconds.
1100AddProjectIdForeignKeyToPackagesNpmMetadataCaches
1101main: -- foreign_keys(:packages_npm_metadata_caches)
1102main:    -> 0.0055s
1103main: == [advisory_lock_connection] object_id: 145355700, pg_backend_pid: 235
1104main: == 20230322145403 AddProjectIdForeignKeyToPackagesNpmMetadataCaches: migrating 
1105main: -- execute("ALTER TABLE packages_npm_metadata_caches ADD CONSTRAINT fk_ada23b1d30 FOREIGN KEY (project_id) REFERENCES projects (id) ON DELETE SET NULL NOT VALID;")
1106main:    -> 0.0022s
1107main: -- execute("ALTER TABLE packages_npm_metadata_caches VALIDATE CONSTRAINT fk_ada23b1d30;")
1108main:    -> 0.0025s
1109main: == 20230322145403 AddProjectIdForeignKeyToPackagesNpmMetadataCaches: migrated (0.0437s) 
1110main: == [advisory_lock_connection] object_id: 145355700, pg_backend_pid: 235
1111main: -- foreign_keys(:packages_npm_metadata_caches)
1112main:    -> 0.0065s
1113main: -- foreign_keys(:packages_npm_metadata_caches)
1114main:    -> 0.0062s
1115  creates and drops the foreign key
1116The application_settings (main) table has 1316 columns.
1117Recreating the database
1118Dropped database 'gitlabhq_test'
1119Dropped database 'gitlabhq_test_ci'
1120Created database 'gitlabhq_test'
1121Created database 'gitlabhq_test_ci'
1122main: == [advisory_lock_connection] object_id: 146392860, pg_backend_pid: 247
1123main: == [advisory_lock_connection] object_id: 146392860, pg_backend_pid: 247
1124ci: == [advisory_lock_connection] object_id: 146559960, pg_backend_pid: 249
1125ci: == [advisory_lock_connection] object_id: 146559960, pg_backend_pid: 249
1126Databases re-creation done in 11.675050093000209
1127# [RSpecRunTime] Finishing example group spec/migrations/20230322145403_add_project_id_foreign_key_to_packages_npm_metadata_caches_spec.rb. It took 1 minute 24.66 seconds. Expected to take 1 minute 11.32 seconds.
1128# [RSpecRunTime] RSpec elapsed time: 14 minutes 26.84 seconds. Current RSS: ~1340M. load average: 1.05 1.05 1.12 1/274 400
1129# [RSpecRunTime] Starting example group spec/migrations/20230517163300_queue_backfill_root_storage_statistics_fork_storage_sizes_spec.rb. Expected to take 1 minute 1.96 seconds.
1130QueueBackfillRootStorageStatisticsForkStorageSizes
1131main: == [advisory_lock_connection] object_id: 154939820, pg_backend_pid: 256
1132main: == 20230517163300 QueueBackfillRootStorageStatisticsForkStorageSizes: migrating 
1133main: == 20230517163300 QueueBackfillRootStorageStatisticsForkStorageSizes: migrated (0.0665s) 
1134main: == [advisory_lock_connection] object_id: 154939820, pg_backend_pid: 256
1135  schedules a new batched migration
1136I, [2024-02-20T23:35:09.197706 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
1137INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1138INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1139INFO:  "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
1140INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1141INFO:  "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
1142INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1143INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1144INFO:  "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
1145INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1146INFO:  analyzing "public.ci_builds_metadata"
1147INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1148INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1149INFO:  "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
1150INFO:  analyzing "public.p_ci_builds" inheritance tree
1151INFO:  analyzing "public.ci_builds"
1152INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1153INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_101"
1154INFO:  "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1155I, [2024-02-20T23:35:33.761532 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1156INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1157INFO:  analyzing "public.ci_pipeline_variables"
1158INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1159I, [2024-02-20T23:35:54.740486 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1160I, [2024-02-20T23:35:54.741670 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1161I, [2024-02-20T23:35:54.742439 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1162I, [2024-02-20T23:35:55.245004 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1163INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1164INFO:  analyzing "public.ci_job_artifacts"
1165INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1166I, [2024-02-20T23:35:56.768302 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1167I, [2024-02-20T23:35:56.769725 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1168I, [2024-02-20T23:35:56.770658 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1169INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1170INFO:  analyzing "public.ci_pipeline_variables"
1171INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1172# [RSpecRunTime] Finishing example group spec/migrations/20230517163300_queue_backfill_root_storage_statistics_fork_storage_sizes_spec.rb. It took 2 minutes 5.49 seconds. Expected to take 1 minute 1.96 seconds.
1173# [RSpecRunTime] RSpec elapsed time: 16 minutes 32.38 seconds. Current RSS: ~1359M. load average: 1.00 1.03 1.10 1/273 401
1174# [RSpecRunTime] Starting example group spec/migrations/ensure_award_emoji_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb. Expected to take 59.7 seconds.
1175EnsureAwardEmojiBigintBackfillIsFinishedForGitlabDotCom
1176  #up
1177main: == [advisory_lock_connection] object_id: 178888020, pg_backend_pid: 273
1178main: == 20230420005610 EnsureAwardEmojiBigintBackfillIsFinishedForGitlabDotCom: migrating 
1179main: == 20230420005610 EnsureAwardEmojiBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0085s) 
1180main: == [advisory_lock_connection] object_id: 178888020, pg_backend_pid: 273
1181    ensures the migration is completed for GitLab.com, dev, or test
1182main: == [advisory_lock_connection] object_id: 179982400, pg_backend_pid: 275
1183main: == 20230420005610 EnsureAwardEmojiBigintBackfillIsFinishedForGitlabDotCom: migrating 
1184main: == 20230420005610 EnsureAwardEmojiBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0070s) 
1185main: == [advisory_lock_connection] object_id: 179982400, pg_backend_pid: 275
1186    skips the check for other instances
1187# [RSpecRunTime] RSpec elapsed time: 17 minutes 33.99 seconds. Current RSS: ~1368M. load average: 0.90 0.98 1.08 1/273 402
1188INFO:  analyzing "public.p_ci_builds" inheritance tree
1189INFO:  analyzing "public.ci_builds"
1190INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1191I, [2024-02-20T23:37:13.352302 #340]  INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes
1192I, [2024-02-20T23:37:13.353910 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1193I, [2024-02-20T23:37:13.354919 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1194I, [2024-02-20T23:37:26.956372 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes
1195INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1196INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1197INFO:  "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
1198INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1199INFO:  "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
1200INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1201INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1202INFO:  "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
1203INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1204INFO:  analyzing "public.ci_builds_metadata"
1205INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1206INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1207INFO:  "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
1208INFO:  analyzing "public.p_ci_builds" inheritance tree
1209INFO:  analyzing "public.ci_builds"
1210INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1211I, [2024-02-20T23:37:51.856517 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1212INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1213INFO:  analyzing "public.ci_pipeline_variables"
1214INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1215I, [2024-02-20T23:38:13.086353 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1216I, [2024-02-20T23:38:13.087739 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1217I, [2024-02-20T23:38:13.088582 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1218I, [2024-02-20T23:38:13.606224 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1219INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1220INFO:  analyzing "public.ci_job_artifacts"
1221INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1222I, [2024-02-20T23:38:14.833914 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1223I, [2024-02-20T23:38:14.835160 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1224I, [2024-02-20T23:38:14.836035 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1225INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1226INFO:  analyzing "public.ci_pipeline_variables"
1227INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1228# [RSpecRunTime] Finishing example group spec/migrations/ensure_award_emoji_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb. It took 2 minutes 17.37 seconds. Expected to take 59.7 seconds.
1229# [RSpecRunTime] RSpec elapsed time: 18 minutes 49.79 seconds. Current RSS: ~1357M. load average: 0.89 0.97 1.06 1/273 403
1230# [RSpecRunTime] Starting example group spec/migrations/ensure_events_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb. Expected to take 47.54 seconds.
1231EnsureEventsBigintBackfillIsFinishedForGitlabDotCom
1232  #up
1233main: == [advisory_lock_connection] object_id: 200460920, pg_backend_pid: 290
1234main: == 20230629024032 EnsureEventsBigintBackfillIsFinishedForGitlabDotCom: migrating 
1235main: == 20230629024032 EnsureEventsBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0094s) 
1236main: == [advisory_lock_connection] object_id: 200460920, pg_backend_pid: 290
1237    ensures the migration is completed for GitLab.com, dev, or test
1238main: == [advisory_lock_connection] object_id: 201457780, pg_backend_pid: 292
1239main: == 20230629024032 EnsureEventsBigintBackfillIsFinishedForGitlabDotCom: migrating 
1240main: == 20230629024032 EnsureEventsBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0085s) 
1241main: == [advisory_lock_connection] object_id: 201457780, pg_backend_pid: 292
1242    skips the check for other instances
1243# [RSpecRunTime] RSpec elapsed time: 19 minutes 33.93 seconds. Current RSS: ~1375M. load average: 0.95 0.98 1.06 1/273 404
1244INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1245INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1246INFO:  "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
1247INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1248INFO:  "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
1249INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1250INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1251INFO:  "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
1252INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1253INFO:  analyzing "public.ci_builds_metadata"
1254INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1255INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1256INFO:  "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
1257INFO:  analyzing "public.p_ci_builds" inheritance tree
1258INFO:  analyzing "public.ci_builds"
1259INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1260I, [2024-02-20T23:39:30.014002 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1261INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1262INFO:  analyzing "public.ci_pipeline_variables"
1263INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1264I, [2024-02-20T23:39:49.804606 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1265I, [2024-02-20T23:39:49.805561 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1266I, [2024-02-20T23:39:49.806392 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1267I, [2024-02-20T23:39:50.264145 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1268INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1269INFO:  analyzing "public.ci_job_artifacts"
1270INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1271I, [2024-02-20T23:39:51.496131 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1272I, [2024-02-20T23:39:51.497174 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1273I, [2024-02-20T23:39:51.497990 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1274INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1275INFO:  analyzing "public.ci_pipeline_variables"
1276INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1277# [RSpecRunTime] Finishing example group spec/migrations/ensure_events_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb. It took 1 minute 36.47 seconds. Expected to take 47.54 seconds.
1278# [RSpecRunTime] RSpec elapsed time: 20 minutes 26.3 seconds. Current RSS: ~1349M. load average: 1.00 1.00 1.06 1/273 405
1279# [RSpecRunTime] Starting example group spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb. Expected to take 42.62 seconds.
1280QueueFixAllowDescendantsOverrideDisabledSharedRunners
1281main: == [advisory_lock_connection] object_id: 215991840, pg_backend_pid: 305
1282main: == 20230802085923 QueueFixAllowDescendantsOverrideDisabledSharedRunners: migrating 
1283main: == 20230802085923 QueueFixAllowDescendantsOverrideDisabledSharedRunners: migrated (0.0555s) 
1284main: == [advisory_lock_connection] object_id: 215991840, pg_backend_pid: 305
1285  schedules a new batched migration
1286INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1287INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1288INFO:  "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
1289INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1290INFO:  "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
1291INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1292INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1293INFO:  "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
1294INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1295INFO:  analyzing "public.ci_builds_metadata"
1296INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1297INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1298INFO:  "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
1299INFO:  analyzing "public.p_ci_builds" inheritance tree
1300INFO:  analyzing "public.ci_builds"
1301INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1302I, [2024-02-20T23:40:38.417543 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1303INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1304INFO:  analyzing "public.ci_pipeline_variables"
1305INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1306I, [2024-02-20T23:40:59.270719 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1307I, [2024-02-20T23:40:59.272225 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1308I, [2024-02-20T23:40:59.272990 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1309I, [2024-02-20T23:40:59.744608 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1310INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1311INFO:  analyzing "public.ci_job_artifacts"
1312INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1313I, [2024-02-20T23:41:00.975210 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1314I, [2024-02-20T23:41:00.976295 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1315I, [2024-02-20T23:41:00.977066 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1316INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1317INFO:  analyzing "public.ci_pipeline_variables"
1318INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1319# [RSpecRunTime] Finishing example group spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb. It took 1 minute 9.41 seconds. Expected to take 42.62 seconds.
1320# [RSpecRunTime] RSpec elapsed time: 21 minutes 35.75 seconds. Current RSS: ~1338M. load average: 0.94 0.99 1.05 1/272 406
1321# [RSpecRunTime] Starting example group spec/migrations/20230906204934_restart_self_hosted_sent_notifications_bigint_conversion_spec.rb. Expected to take 39.99 seconds.
1322RestartSelfHostedSentNotificationsBigintConversion
1323  when is not self-hosted
1324    #up
1325      is a bigint and result in no change
1326# [RSpecRunTime] RSpec elapsed time: 22 minutes 0.61 second. Current RSS: ~1342M. load average: 0.89 0.97 1.04 1/272 407
1327  when is self-hosted
1328    #up
1329      when id is already a bigint
1330        does nothing
1331# [RSpecRunTime] RSpec elapsed time: 22 minutes 3.64 seconds. Current RSS: ~1345M. load average: 0.90 0.97 1.04 1/272 408
1332      when id is an integer and id_convert_to_bigint exists
1333        does nothing
1334# [RSpecRunTime] RSpec elapsed time: 22 minutes 6.39 seconds. Current RSS: ~1352M. load average: 0.90 0.97 1.04 1/272 409
1335      when id is an integer and id_convert_to_bigint does not exist
1336        creates id_convert_to_bigint
1337# [RSpecRunTime] RSpec elapsed time: 22 minutes 9.25 seconds. Current RSS: ~1354M. load average: 0.91 0.97 1.04 1/272 410
1338    #down
1339      when id is an integer and id_convert_to_bigint exists
1340        drops id_convert_to_bigint
1341# [RSpecRunTime] RSpec elapsed time: 22 minutes 12.36 seconds. Current RSS: ~1341M. load average: 0.91 0.97 1.04 1/272 411
1342INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1343INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1344INFO:  "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
1345INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1346INFO:  "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
1347INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1348INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1349INFO:  "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
1350INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1351INFO:  analyzing "public.ci_builds_metadata"
1352INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1353INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1354INFO:  "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
1355INFO:  analyzing "public.p_ci_builds" inheritance tree
1356INFO:  analyzing "public.ci_builds"
1357INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1358I, [2024-02-20T23:41:48.623716 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1359INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1360INFO:  analyzing "public.ci_pipeline_variables"
1361INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1362I, [2024-02-20T23:42:08.362244 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1363I, [2024-02-20T23:42:08.363196 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1364I, [2024-02-20T23:42:08.364161 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1365I, [2024-02-20T23:42:08.795736 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1366INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1367INFO:  analyzing "public.ci_job_artifacts"
1368INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1369I, [2024-02-20T23:42:10.006812 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1370I, [2024-02-20T23:42:10.008113 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1371I, [2024-02-20T23:42:10.008941 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1372INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1373INFO:  analyzing "public.ci_pipeline_variables"
1374INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1375# [RSpecRunTime] Finishing example group spec/migrations/20230906204934_restart_self_hosted_sent_notifications_bigint_conversion_spec.rb. It took 1 minute 8.71 seconds. Expected to take 39.99 seconds.
1376# [RSpecRunTime] RSpec elapsed time: 22 minutes 44.5 seconds. Current RSS: ~1337M. load average: 1.01 0.99 1.04 1/273 412
1377# [RSpecRunTime] Starting example group spec/migrations/20230802212443_add_current_user_todos_widget_to_epic_work_item_type_spec.rb. Expected to take 37.01 seconds.
1378AddCurrentUserTodosWidgetToEpicWorkItemType
1379  behaves like migration that adds a widget to a work item type
1380    #up
1381main: == [advisory_lock_connection] object_id: 242399700, pg_backend_pid: 342
1382main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrating ======
1383main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrated (0.0163s) 
1384main: == [advisory_lock_connection] object_id: 242399700, pg_backend_pid: 342
1385      adds widget to work item type
1386      when type does not exist
1387main: == [advisory_lock_connection] object_id: 243180880, pg_backend_pid: 344
1388main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrating ======
1389main: -- Epic work item type does not exist, skipping widget creation
1390main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrated (0.0092s) 
1391main: == [advisory_lock_connection] object_id: 243180880, pg_backend_pid: 344
1392        skips creating the new widget definition
1393# [RSpecRunTime] RSpec elapsed time: 23 minutes 16.09 seconds. Current RSS: ~1349M. load average: 0.86 0.96 1.03 1/273 413
1394    #down
1395main: == [advisory_lock_connection] object_id: 243788800, pg_backend_pid: 346
1396main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrating ======
1397main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrated (0.0200s) 
1398main: == [advisory_lock_connection] object_id: 243788800, pg_backend_pid: 346
1399      removes widget from work item type
1400# [RSpecRunTime] RSpec elapsed time: 23 minutes 19.25 seconds. Current RSS: ~1338M. load average: 0.79 0.94 1.02 1/273 414
1401INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1402INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1403INFO:  "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
1404INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1405INFO:  "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
1406INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1407INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1408INFO:  "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
1409INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1410INFO:  analyzing "public.ci_builds_metadata"
1411INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1412INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1413INFO:  "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
1414INFO:  analyzing "public.p_ci_builds" inheritance tree
1415INFO:  analyzing "public.ci_builds"
1416INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1417I, [2024-02-20T23:43:01.862701 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1418INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1419INFO:  analyzing "public.ci_pipeline_variables"
1420INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1421I, [2024-02-20T23:43:21.325307 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1422I, [2024-02-20T23:43:21.326650 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1423I, [2024-02-20T23:43:21.327439 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1424I, [2024-02-20T23:43:21.759624 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1425INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1426INFO:  analyzing "public.ci_job_artifacts"
1427INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1428I, [2024-02-20T23:43:22.900136 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1429I, [2024-02-20T23:43:22.901124 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1430I, [2024-02-20T23:43:22.901908 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1431INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1432INFO:  analyzing "public.ci_pipeline_variables"
1433INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1434# [RSpecRunTime] Finishing example group spec/migrations/20230802212443_add_current_user_todos_widget_to_epic_work_item_type_spec.rb. It took 1 minute 12.61 seconds. Expected to take 37.01 seconds.
1435# [RSpecRunTime] RSpec elapsed time: 23 minutes 57.15 seconds. Current RSS: ~1328M. load average: 0.91 0.95 1.02 1/273 415
1436# [RSpecRunTime] Starting example group spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb. Expected to take 29.25 seconds.
1437QueueBackfillProjectStatisticsStorageSizeWithRecentSize
1438main: == [advisory_lock_connection] object_id: 255956300, pg_backend_pid: 359
1439main: == 20230823090001 QueueBackfillProjectStatisticsStorageSizeWithRecentSize: migrating 
1440main: == 20230823090001 QueueBackfillProjectStatisticsStorageSizeWithRecentSize: migrated (0.0480s) 
1441main: == [advisory_lock_connection] object_id: 255956300, pg_backend_pid: 359
1442  schedules a new batched migration
1443INFO:  analyzing "public.p_ci_runner_machine_builds" inheritance tree
1444INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1445INFO:  "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
1446INFO:  analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1447INFO:  "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
1448INFO:  analyzing "public.p_ci_job_annotations" inheritance tree
1449INFO:  analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1450INFO:  "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
1451INFO:  analyzing "public.p_ci_builds_metadata" inheritance tree
1452INFO:  analyzing "public.ci_builds_metadata"
1453INFO:  "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1454INFO:  analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1455INFO:  "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
1456INFO:  analyzing "public.p_ci_builds" inheritance tree
1457INFO:  analyzing "public.ci_builds"
1458INFO:  "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1459I, [2024-02-20T23:44:00.110962 #340]  INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1460INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1461INFO:  analyzing "public.ci_pipeline_variables"
1462INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1463I, [2024-02-20T23:44:19.716665 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1464I, [2024-02-20T23:44:19.717688 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1465I, [2024-02-20T23:44:19.718431 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1466I, [2024-02-20T23:44:20.164142 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1467INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1468INFO:  analyzing "public.ci_job_artifacts"
1469INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1470I, [2024-02-20T23:44:21.305302 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1471I, [2024-02-20T23:44:21.306316 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1472I, [2024-02-20T23:44:21.307045 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1473INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1474INFO:  analyzing "public.ci_pipeline_variables"
1475INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1476# [RSpecRunTime] Finishing example group spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb. It took 58.57 seconds. Expected to take 29.25 seconds.
1477# [RSpecRunTime] RSpec elapsed time: 24 minutes 55.77 seconds. Current RSS: ~1326M. load average: 1.11 1.00 1.03 1/274 416
1478# [RSpecRunTime] Starting example group spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb. Expected to take 26.48 seconds.
1479QueueBackfillPartitionIdCiPipelineArtifact
1480  #down
1481ci: == [advisory_lock_connection] object_id: 267183260, pg_backend_pid: 548
1482ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrating =======
1483ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrated (0.0718s) 
1484ci: == [advisory_lock_connection] object_id: 267183260, pg_backend_pid: 548
1485    deletes all batched migration records
1486# [RSpecRunTime] RSpec elapsed time: 25 minutes 13.95 seconds. Current RSS: ~1340M. load average: 1.08 1.00 1.02 1/274 417
1487  #up
1488    with migration present
1489      when migration finished successfully
1490ci: == [advisory_lock_connection] object_id: 268665100, pg_backend_pid: 555
1491ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrating =======
1492ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrated (0.0712s) 
1493ci: == [advisory_lock_connection] object_id: 268665100, pg_backend_pid: 555
1494        does not raise exception
1495ci: == [advisory_lock_connection] object_id: 269278460, pg_backend_pid: 561
1496ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrating =======
1497ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrated (0.0628s) 
1498ci: == [advisory_lock_connection] object_id: 269278460, pg_backend_pid: 561
1499        schedules background jobs for each batch of ci_pipeline_artifacts
1500# [RSpecRunTime] RSpec elapsed time: 25 minutes 20.13 seconds. Current RSS: ~1336M. load average: 1.07 1.00 1.02 1/274 418
1501INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1502INFO:  analyzing "public.ci_pipeline_variables"
1503INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1504I, [2024-02-20T23:44:57.898747 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1505I, [2024-02-20T23:44:57.899746 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1506I, [2024-02-20T23:44:57.900480 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1507I, [2024-02-20T23:44:58.342946 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1508INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1509INFO:  analyzing "public.ci_job_artifacts"
1510INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1511I, [2024-02-20T23:44:59.615492 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1512I, [2024-02-20T23:44:59.616604 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1513I, [2024-02-20T23:44:59.617663 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1514INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1515INFO:  analyzing "public.ci_pipeline_variables"
1516INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1517I, [2024-02-20T23:45:06.683006 #340]  INFO -- : Database: 'ci', Table: 'audit_events_group_external_streaming_destinations': Lock Writes
1518I, [2024-02-20T23:45:06.860157 #340]  INFO -- : Database: 'ci', Table: 'packages_terraform_module_metadata': Lock Writes
1519I, [2024-02-20T23:45:06.902845 #340]  INFO -- : Database: 'ci', Table: 'member_approvals': Lock Writes
1520I, [2024-02-20T23:45:06.928418 #340]  INFO -- : Database: 'ci', Table: 'zoekt_repositories': Lock Writes
1521INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1522INFO:  analyzing "public.ci_pipeline_variables"
1523INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1524INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1525INFO:  analyzing "public.ci_job_artifacts"
1526INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1527INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1528INFO:  analyzing "public.ci_pipeline_variables"
1529INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1530I, [2024-02-20T23:45:10.736649 #340]  INFO -- : Database: 'ci', Table: 'audit_events_instance_external_streaming_destinations': Lock Writes
1531I, [2024-02-20T23:45:10.983477 #340]  INFO -- : Database: 'ci', Table: 'catalog_verified_namespaces': Lock Writes
1532I, [2024-02-20T23:45:11.149942 #340]  INFO -- : Database: 'ci', Table: 'group_saved_replies': Lock Writes
1533# [RSpecRunTime] Finishing example group spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb. It took 47.52 seconds. Expected to take 26.48 seconds.
1534# [RSpecRunTime] RSpec elapsed time: 25 minutes 43.32 seconds. Current RSS: ~1331M. load average: 1.04 1.00 1.02 1/274 419
1535# [RSpecRunTime] Starting example group spec/migrations/20231020181652_add_index_packages_npm_metadata_caches_on_id_and_project_id_and_status_spec.rb. Expected to take 21.1 seconds.
1536AddIndexPackagesNpmMetadataCachesOnIdAndProjectIdAndStatus
1537main: == [advisory_lock_connection] object_id: 277393200, pg_backend_pid: 746
1538main: == 20231020181652 AddIndexPackagesNpmMetadataCachesOnIdAndProjectIdAndStatus: migrating 
1539main: -- view_exists?(:postgres_partitions)
1540main:    -> 0.0015s
1541main: -- index_exists?(:packages_npm_metadata_caches, :id, {:name=>"idx_pkgs_npm_metadata_caches_on_id_and_project_id_and_status", :where=>"project_id IS NULL AND status = 0", :algorithm=>:concurrently})
1542main:    -> 0.0063s
1543main: -- add_index(:packages_npm_metadata_caches, :id, {:name=>"idx_pkgs_npm_metadata_caches_on_id_and_project_id_and_status", :where=>"project_id IS NULL AND status = 0", :algorithm=>:concurrently})
1544main:    -> 0.0019s
1545main: == 20231020181652 AddIndexPackagesNpmMetadataCachesOnIdAndProjectIdAndStatus: migrated (0.0342s) 
1546main: == [advisory_lock_connection] object_id: 277393200, pg_backend_pid: 746
1547  correctly migrates up and down
1548INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1549INFO:  analyzing "public.ci_pipeline_variables"
1550INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1551I, [2024-02-20T23:45:51.805372 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1552I, [2024-02-20T23:45:51.806406 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1553I, [2024-02-20T23:45:51.807098 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1554I, [2024-02-20T23:45:52.207903 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1555INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1556INFO:  analyzing "public.ci_job_artifacts"
1557INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1558I, [2024-02-20T23:45:53.304496 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1559I, [2024-02-20T23:45:53.305541 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1560I, [2024-02-20T23:45:53.306297 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1561INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1562INFO:  analyzing "public.ci_pipeline_variables"
1563INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1564# [RSpecRunTime] Finishing example group spec/migrations/20231020181652_add_index_packages_npm_metadata_caches_on_id_and_project_id_and_status_spec.rb. It took 44.48 seconds. Expected to take 21.1 seconds.
1565# [RSpecRunTime] RSpec elapsed time: 26 minutes 27.85 seconds. Current RSS: ~1321M. load average: 1.10 1.03 1.03 1/274 420
1566# [RSpecRunTime] Starting example group spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb. Expected to take 13.34 seconds.
1567QueueBackfillVsCodeSettingsUuid
1568main: == [advisory_lock_connection] object_id: 284705680, pg_backend_pid: 758
1569main: == 20231130140901 QueueBackfillVsCodeSettingsUuid: migrating ==================
1570main: == 20231130140901 QueueBackfillVsCodeSettingsUuid: migrated (0.0480s) =========
1571main: == [advisory_lock_connection] object_id: 284705680, pg_backend_pid: 758
1572  schedules a new batched migration
1573INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1574INFO:  analyzing "public.ci_pipeline_variables"
1575INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1576I, [2024-02-20T23:46:22.667849 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1577I, [2024-02-20T23:46:22.668844 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1578I, [2024-02-20T23:46:22.669732 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1579I, [2024-02-20T23:46:23.091445 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1580INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1581INFO:  analyzing "public.ci_job_artifacts"
1582INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1583I, [2024-02-20T23:46:24.233820 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1584I, [2024-02-20T23:46:24.235042 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1585I, [2024-02-20T23:46:24.235883 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1586INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1587INFO:  analyzing "public.ci_pipeline_variables"
1588INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1589# [RSpecRunTime] Finishing example group spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb. It took 30.8 seconds. Expected to take 13.34 seconds.
1590# [RSpecRunTime] RSpec elapsed time: 26 minutes 58.68 seconds. Current RSS: ~1316M. load average: 1.05 1.02 1.03 1/276 421
1591# [RSpecRunTime] Starting example group spec/migrations/20240105144908_queue_backfill_issue_search_data_namespace_id_spec.rb. Expected to take 9.42 seconds.
1592QueueBackfillIssueSearchDataNamespaceId
1593main: == [advisory_lock_connection] object_id: 289691140, pg_backend_pid: 770
1594main: == 20240105144908 QueueBackfillIssueSearchDataNamespaceId: migrating ==========
1595main: == 20240105144908 QueueBackfillIssueSearchDataNamespaceId: migrated (0.0747s) =
1596main: == [advisory_lock_connection] object_id: 289691140, pg_backend_pid: 770
1597  schedules a new batched migration
1598INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1599INFO:  analyzing "public.ci_pipeline_variables"
1600INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1601I, [2024-02-20T23:46:46.585500 #340]  INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1602I, [2024-02-20T23:46:46.586482 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1603I, [2024-02-20T23:46:46.587197 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1604I, [2024-02-20T23:46:47.034678 #340]  INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1605INFO:  analyzing "public.p_ci_job_artifacts" inheritance tree
1606INFO:  analyzing "public.ci_job_artifacts"
1607INFO:  "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1608I, [2024-02-20T23:46:48.296775 #340]  INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1609I, [2024-02-20T23:46:48.297814 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1610I, [2024-02-20T23:46:48.298543 #340]  INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1611INFO:  analyzing "public.p_ci_pipeline_variables" inheritance tree
1612INFO:  analyzing "public.ci_pipeline_variables"
1613INFO:  "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1614# [RSpecRunTime] Finishing example group spec/migrations/20240105144908_queue_backfill_issue_search_data_namespace_id_spec.rb. It took 24.18 seconds. Expected to take 9.42 seconds.
1615# [RSpecRunTime] RSpec elapsed time: 27 minutes 22.9 seconds. Current RSS: ~1347M. load average: 0.97 1.01 1.02 1/276 422
1616auto_explain log contains 910 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-11-15.340.main.ndjson.gz
1617took 14.918500363
1618auto_explain log contains 910 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-11-15.340.ci.ndjson.gz
1619took 13.792296063
1620[TEST PROF INFO] EventProf results for sql.active_record
1621Total time: 20:22.319 of 27:19.422 (74.56%)
1622Total events: 727916
1623Top 5 slowest suites (by time):
1624AddPackageIdC...ToPackageFiles (./spec/migrations/20230321153035_add_package_id_created_at_desc_index_to_package_files_spec.rb:6) – 02:04.661 (61755 / 1) of 02:42.131 (76.89%)
1625MigrateEviden...romRawMetadata (./spec/migrations/20230224144233_migrate_evidences_from_raw_metadata_spec.rb:6) – 02:03.547 (67524 / 2) of 02:44.260 (75.21%)
1626SwapSnippetUs...orGitlabDotCom (./spec/migrations/swap_snippet_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb:6) – 01:58.091 (63501 / 2) of 02:34.585 (76.39%)
1627SwapVulnerabi...orGitlabDotCom (./spec/migrations/swap_vulnerability_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb:6) – 01:54.716 (64304 / 2) of 02:33.144 (74.91%)
1628AddAssigneeWidgetToIncidents (./spec/migrations/20230418215853_add_assignee_widget_to_incidents_spec.rb:6) – 01:48.370 (61178 / 3) of 02:24.549 (74.97%)
1629Knapsack report was generated. Preview:
1630{
1631  "spec/migrations/20230418215853_add_assignee_widget_to_incidents_spec.rb": 69.47900804300025,
1632  "spec/migrations/swap_vulnerability_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb": 71.5936768520005,
1633  "spec/migrations/20230224144233_migrate_evidences_from_raw_metadata_spec.rb": 73.912841458,
1634  "spec/migrations/swap_snippet_user_mentions_note_id_to_bigint_for_gitlab_dot_com_spec.rb": 72.21274258199992,
1635  "spec/migrations/20230321153035_add_package_id_created_at_desc_index_to_package_files_spec.rb": 72.08057003700014,
1636  "spec/migrations/20230322145403_add_project_id_foreign_key_to_packages_npm_metadata_caches_spec.rb": 72.86159896399931,
1637  "spec/migrations/20230517163300_queue_backfill_root_storage_statistics_fork_storage_sizes_spec.rb": 54.44772160299999,
1638  "spec/migrations/ensure_award_emoji_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb": 61.703327145,
1639  "spec/migrations/ensure_events_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb": 44.22468729799948,
1640  "spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb": 29.831621349999295,
1641  "spec/migrations/20230906204934_restart_self_hosted_sent_notifications_bigint_conversion_spec.rb": 36.70394001400018,
1642  "spec/migrations/20230802212443_add_current_user_todos_widget_to_epic_work_item_type_spec.rb": 34.90012946099978,
1643  "spec/migrations/20230823090001_queue_backfill_project_statistics_storage_size_with_recent_size_spec.rb": 25.397831171999314,
1644  "spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb": 24.43230844199934,
1645  "spec/migrations/20231020181652_add_index_packages_npm_metadata_caches_on_id_and_project_id_and_status_spec.rb": 19.30688532899967,
1646  "spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb": 12.522725859000275,
1647  "spec/migrations/20240105144908_queue_backfill_issue_search_data_namespace_id_spec.rb": 9.946932885000024
1648}
1649Knapsack global time execution for tests: 13m 05s
1650Finished in 27 minutes 52 seconds (files took 45.75 seconds to load)
165132 examples, 0 failures
1652Randomized with seed 33284
1653[TEST PROF INFO] Time spent in factories: 00:00.279 (0.02% of total time)
1654RSpec exited with 0.
1655No examples to retry, congrats!
 1656  Running after_script  
  00:08
1657Running after script...
1658$ echo -e "\e[0Ksection_start:`date +%s`:report_results_section[collapsed=true]\r\e[0KReport results"
 1659  Report results  
  00:06
1670$ tooling/bin/push_job_metrics || true
1671[job-metrics] Pushing job metrics file for the CI/CD job.
1672[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
 1673  Saving cache for successful job  
  00:01
1674Not uploading cache ruby-gems-debian-bookworm-ruby-3.1-16 due to policy
 1675  Uploading artifacts for successful job  
  00:05
1676Uploading artifacts...
1677auto_explain/: found 3 matching artifact files and directories 
1678coverage/: found 5 matching artifact files and directories 
1679WARNING: crystalball/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 
1680WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 
1681knapsack/: found 4 matching artifact files and directories 
1682rspec/: found 12 matching artifact files and directories 
1683WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 
1684log/*.log: found 13 matching artifact files and directories 
1685WARNING: Upload request redirected                  location=https://gitlab.com/api/v4/jobs/6218670739/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
1686WARNING: Retrying...                                context=artifacts-uploader error=request redirected
1687Uploading artifacts as "archive" to coordinator... 201 Created  id=6218670739 responseStatus=201 Created token=glcbt-65
1688Uploading artifacts...
1689rspec/rspec-*.xml: found 1 matching artifact files and directories 
1690WARNING: Upload request redirected                  location=https://gitlab.com/api/v4/jobs/6218670739/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
1691WARNING: Retrying...                                context=artifacts-uploader error=request redirected
1692Uploading artifacts as "junit" to coordinator... 201 Created  id=6218670739 responseStatus=201 Created token=glcbt-65
 1693  Cleaning up project directory and file based variables  
  00:00
1694Job succeeded
Tags: gitlab-org
Job artifacts
The artifacts were removed 11 months ago
Commit 4ae1f4be
Merge branch 'jy-cascadig-duo-features-enabled-2' into 'master'
Related jobs

db:migrate:multi-version-upgrade-1
rspec-ee unit gitlab-duo-chat-qa pg14
compile-storybook
db:backup_and_restore
db:backup_and_restore single-db
db:backup_and_restore single-db-ci-connection
db:check-schema
db:migrate:reset
db:rollback
db:rollback geo
gitlab:clickhouse:rollback:main
gitlab:setup
gitlab_git_test
glfm-verify
graphql-verify
jest 1/12
jest 10/12
jest 11/12
jest 12/12
jest 2/12
jest 3/12
jest 4/12
jest 5/12
jest 6/12
jest 7/12
jest 8/12
jest 9/12
jest-integration
memory-on-boot
rspec background_migration pg14 1/5
rspec background_migration pg14 2/5
rspec background_migration pg14 3/5
rspec background_migration pg14 4/5
rspec background_migration pg14 5/5
rspec integration pg14 1/16
rspec integration pg14 10/16
rspec integration pg14 11/16
rspec integration pg14 12/16
rspec integration pg14 13/16
rspec integration pg14 14/16
rspec integration pg14 15/16
rspec integration pg14 16/16
rspec integration pg14 2/16
rspec integration pg14 3/16
rspec integration pg14 4/16
rspec integration pg14 5/16
rspec integration pg14 6/16
rspec integration pg14 7/16
rspec integration pg14 8/16
rspec integration pg14 9/16
rspec migration pg14 1/15
rspec migration pg14 10/15
rspec migration pg14 11/15
rspec migration pg14 12/15
rspec migration pg14 13/15
rspec migration pg14 14/15
rspec migration pg14 15/15
rspec migration pg14 2/15
rspec migration pg14 3/15
rspec migration pg14 4/15
rspec migration pg14 5/15
rspec migration pg14 6/15
rspec migration pg14 7/15
rspec migration pg14 8/15
rspec migration pg14 9/15
rspec system pg14 1/32
rspec system pg14 10/32
rspec system pg14 11/32
rspec system pg14 12/32
rspec system pg14 13/32
rspec system pg14 14/32
rspec system pg14 15/32
rspec system pg14 16/32
rspec system pg14 17/32
rspec system pg14 18/32
rspec system pg14 19/32
rspec system pg14 2/32
rspec system pg14 20/32
rspec system pg14 21/32
rspec system pg14 22/32
rspec system pg14 23/32
rspec system pg14 24/32
rspec system pg14 25/32
rspec system pg14 26/32
rspec system pg14 27/32
rspec system pg14 28/32
rspec system pg14 29/32
rspec system pg14 3/32
rspec system pg14 30/32
rspec system pg14 31/32
rspec system pg14 32/32
rspec system pg14 4/32
rspec system pg14 5/32
rspec system pg14 6/32
rspec system pg14 7/32
rspec system pg14 8/32
rspec system pg14 9/32
rspec unit clickhouse
rspec unit pg14 1/32
rspec unit pg14 10/32
rspec unit pg14 11/32
rspec unit pg14 12/32
rspec unit pg14 13/32
rspec unit pg14 14/32
rspec unit pg14 15/32
rspec unit pg14 16/32
rspec unit pg14 17/32
rspec unit pg14 18/32
rspec unit pg14 19/32
rspec unit pg14 2/32
rspec unit pg14 20/32
rspec unit pg14 21/32
rspec unit pg14 22/32
rspec unit pg14 23/32
rspec unit pg14 24/32
rspec unit pg14 25/32
rspec unit pg14 26/32
rspec unit pg14 27/32
rspec unit pg14 28/32
rspec unit pg14 29/32
rspec unit pg14 3/32
rspec unit pg14 30/32
rspec unit pg14 31/32
rspec unit pg14 32/32
rspec unit pg14 4/32
rspec unit pg14 5/32
rspec unit pg14 6/32
rspec unit pg14 7/32
rspec unit pg14 8/32
rspec unit pg14 9/32
rspec-ee background_migration pg14 1/2
rspec-ee background_migration pg14 2/2
rspec-ee integration pg14 1/7
rspec-ee integration pg14 2/7
rspec-ee integration pg14 3/7
rspec-ee integration pg14 4/7
rspec-ee integration pg14 5/7
rspec-ee integration pg14 6/7
rspec-ee integration pg14 7/7
rspec-ee migration pg14 1/2
rspec-ee migration pg14 2/2
rspec-ee system pg14 1/14
rspec-ee system pg14 10/14
rspec-ee system pg14 11/14
rspec-ee system pg14 12/14
rspec-ee system pg14 13/14
rspec-ee system pg14 14/14
rspec-ee system pg14 2/14
rspec-ee system pg14 3/14
rspec-ee system pg14 4/14
rspec-ee system pg14 5/14
rspec-ee system pg14 6/14
rspec-ee system pg14 7/14
rspec-ee system pg14 8/14
rspec-ee system pg14 9/14
rspec-ee unit clickhouse
rspec-ee unit pg14 1/28
rspec-ee unit pg14 10/28
rspec-ee unit pg14 11/28
rspec-ee unit pg14 12/28
rspec-ee unit pg14 13/28
rspec-ee unit pg14 14/28
rspec-ee unit pg14 15/28
rspec-ee unit pg14 16/28
rspec-ee unit pg14 17/28
rspec-ee unit pg14 18/28
rspec-ee unit pg14 19/28
rspec-ee unit pg14 2/28
rspec-ee unit pg14 20/28
rspec-ee unit pg14 21/28
rspec-ee unit pg14 22/28
rspec-ee unit pg14 23/28
rspec-ee unit pg14 24/28
rspec-ee unit pg14 25/28
rspec-ee unit pg14 26/28
rspec-ee unit pg14 27/28
rspec-ee unit pg14 28/28
rspec-ee unit pg14 3/28
rspec-ee unit pg14 4/28
rspec-ee unit pg14 5/28
rspec-ee unit pg14 6/28
rspec-ee unit pg14 7/28
rspec-ee unit pg14 8/28
rspec-ee unit pg14 9/28
run-dev-fixtures
run-dev-fixtures-ee
verify-tests-yml
webpack-dev-server
db:migrate:multi-version-upgrade-2