Skip to content
Snippets Groups Projects

rspec migration pg14 8/15

Passed Started by
Krasimir Angelov
@krasio
1Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
2 on green-6.private.runners-manager.gitlab.com/gitlab.com/gitlab-org vHriyjxu, system ID: s_44fde8db605e
3 feature flags: FF_NETWORK_PER_BUILD:true
4Resolving secrets
5 Preparing the "docker+machine" executor 00:07
6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
8Authenticating with credentials from job payload (GitLab Registry)
9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
10Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...
11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
14Authenticating with credentials from job payload (GitLab Registry)
15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
16Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...
17Starting service redis:6.2-alpine ...
18Pulling docker image redis:6.2-alpine ...
19Using docker image sha256:0fc0b666ef9eb249e61edd4e2ec434a328e98117079727c5e7cf6dc9a00d7a94 for redis:6.2-alpine with digest redis@sha256:c6abddbb4223951cf5cb54a19d4a83418582fa69c97c0458ab4f0974f24f119c ...
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.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
23Using docker image sha256:d10d211334d3c94a5e626cf961467431b7d3f3bd8b6109e5caf9357421c0117b for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14@sha256:3c4cd9a237deb59350b4836552151ba2aae96247034074c8f0c2ccaff4558cab ...
24 Preparing environment 00:00
25Running on runner-vhriyjxu-project-278964-concurrent-0 via runner-vhriyjxu-private-1717023116-1a05d1c9...
26 Getting source from Git repository 00:01
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
30 Restoring cache 00:15
31Checking cache for ruby-gems-debian-bookworm-ruby-3.2-17...
32cache.zip is up to date
33Successfully extracted cache
34 Downloading artifacts 00:43
35Downloading artifacts for clone-gitlab-repo (6975007209)...
36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007209 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 (6975007223)...
40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007223 responseStatus=200 OK token=glcbt-65
41Downloading artifacts for detect-tests (6975007229)...
42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007229 responseStatus=200 OK token=glcbt-65
43Downloading artifacts for retrieve-tests-metadata (6975007235)...
44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007235 responseStatus=200 OK token=glcbt-65
45Downloading artifacts for setup-test-env (6975007225)...
46Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007225 responseStatus=200 OK token=glcbt-65
47 Executing "step_script" stage of the job script
48Using docker image sha256:d10d211334d3c94a5e626cf961467431b7d3f3bd8b6109e5caf9357421c0117b for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14@sha256:3c4cd9a237deb59350b4836552151ba2aae96247034074c8f0c2ccaff4558cab ...
49$ echo $FOSS_ONLY
50$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
51$ export GOPATH=$CI_PROJECT_DIR/.go
52$ mkdir -p $GOPATH
53$ source scripts/utils.sh
54$ source scripts/prepare_build.sh
55 Installing gems 00:14
755Using decomposed database config (config/database.yml.decomposed-postgresql)
756Geo DB won't be set up.
757Embedding DB won't be set up.
758 Setting up DBs 00:55
769$ source ./scripts/rspec_helpers.sh
770$ run_timed_command "gem install knapsack --no-document"
771$ gem install knapsack --no-document
772Successfully installed knapsack-4.0.0
7731 gem installed
774==> 'gem install knapsack --no-document' succeeded in 0 seconds.
775$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
776 Spawning Gitaly 00:03
780$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
781$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
782$ tooling/bin/create_job_metrics_file || true
783[job-metrics] Creating the job metrics file for the CI/CD job.
784$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
785 RSpec 29:21
786[23:31:24] Starting rspec_parallelized_job
787RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
788KNAPSACK_GENERATE_REPORT: true
789FLAKY_RSPEC_GENERATE_REPORT: true
790KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
791KNAPSACK_LOG_LEVEL: debug
792KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_8_15_278964_report.json
793FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
794FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_8_15_278964_report.json
795NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_8_15_278964_report.json
796RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6975007381.txt
797CRYSTALBALL:
798RSPEC_TESTS_MAPPING_ENABLED:
799RSPEC_TESTS_FILTER_FILE:
800Shell set options (set -o) enabled:
801braceexpand on
802hashall on
803interactive-comments on
804pipefail on
805Knapsack report generator started!
806warning: parser/current is loading parser/ruby32, which recognizes 3.2.3-compliant syntax, but you are running 3.2.4.
807Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
808Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
809# [RSpecRunTime] Starting RSpec timer...
810[TEST PROF INFO] EventProf enabled (sql.active_record)
811unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
812unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
813Test environment set up in 2.286818227 seconds
814# [RSpecRunTime] Starting example group spec/migrations/20230809174702_swap_system_note_metadata_note_id_to_bigint_for_self_managed_spec.rb. Expected to take 3 minutes 34.72 seconds.
815SwapSystemNoteMetadataNoteIdToBigintForSelfManaged
816 #up
817 when GitLab.com, dev, or test
818 behaves like column `note_id_convert_to_bigint` is already dropped
819 does not swap the columns
820 when self-managed instance with the `note_id_convert_to_bigint` column already dropped
821 behaves like column `note_id_convert_to_bigint` is already dropped
822 does not swap the columns
823# [RSpecRunTime] RSpec elapsed time: 2 minutes 0.28 second. Current RSS: ~1174M. Threads: 2. load average: 1.06 1.15 1.13 1/290 393
824.
825 when self-managed instance columns already swapped
826 does not swaps the columns
827# [RSpecRunTime] RSpec elapsed time: 2 minutes 17.2 seconds. Current RSS: ~1151M. Threads: 2. load average: 1.04 1.14 1.12 1/288 394
828.
829 when self-managed instance
830 swaps the columns
831# [RSpecRunTime] RSpec elapsed time: 2 minutes 47.94 seconds. Current RSS: ~1146M. Threads: 2. load average: 1.02 1.12 1.12 1/288 395
832.
833INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
834INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
835INFO: "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
836INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
837INFO: "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
838INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
839INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
840INFO: analyzing "public.p_ci_job_annotations" inheritance tree
841INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
842INFO: "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
843INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
844INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
845INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
846INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
847INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
848INFO: analyzing "public.ci_builds_metadata"
849INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
850INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
851INFO: "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
852INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
853INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
854INFO: analyzing "public.p_ci_builds" inheritance tree
855INFO: analyzing "public.ci_builds"
856INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
857INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
858INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
859INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
860INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
861I, [2024-05-29T23:35:02.897783 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
862I, [2024-05-29T23:35:21.550649 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
863INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
864INFO: analyzing "public.ci_pipeline_variables"
865INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
866I, [2024-05-29T23:35:23.085170 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
867I, [2024-05-29T23:35:23.086215 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
868I, [2024-05-29T23:35:23.086956 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
869INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
870INFO: analyzing "public.ci_pipeline_variables"
871INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
872INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
873INFO: analyzing "public.ci_job_artifacts"
874INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
875I, [2024-05-29T23:35:28.900074 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
876I, [2024-05-29T23:35:28.901271 #344] 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-05-29T23:35:28.902021 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
878INFO: analyzing "public.p_ci_stages" inheritance tree
879INFO: analyzing "public.ci_stages"
880INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
881I, [2024-05-29T23:35:31.896104 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
882I, [2024-05-29T23:35:31.897188 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
883I, [2024-05-29T23:35:31.897980 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
884I, [2024-05-29T23:35:32.967305 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
885I, [2024-05-29T23:35:38.830730 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
886# [RSpecRunTime] Finishing example group spec/migrations/20230809174702_swap_system_note_metadata_note_id_to_bigint_for_self_managed_spec.rb. It took 3 minutes 45.04 seconds. Expected to take 3 minutes 34.72 seconds.
887# [RSpecRunTime] RSpec elapsed time: 3 minutes 50.27 seconds. Current RSS: ~1131M. Threads: 2. load average: 0.91 1.07 1.09 1/287 396
888.
889# [RSpecRunTime] Starting example group spec/migrations/swap_events_target_id_to_bigint_for_gitlab_dot_com_spec.rb. Expected to take 2 minutes 46.24 seconds.
890SwapEventsTargetIdToBigintForGitlabDotCom
891 #up
892 swaps the integer and bigint columns for GitLab.com, dev, or test
893 is a no-op for other instances
894# [RSpecRunTime] RSpec elapsed time: 5 minutes 59.73 seconds. Current RSS: ~1169M. Threads: 2. load average: 0.92 0.99 1.05 1/288 397
895.
896INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
897INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
898INFO: "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
899INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
900INFO: "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
901INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
902INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
903INFO: analyzing "public.p_ci_job_annotations" inheritance tree
904INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
905INFO: "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
906INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
907INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
908INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
909INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
910INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
911INFO: analyzing "public.ci_builds_metadata"
912INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
913INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
914INFO: "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
915INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
916INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
917INFO: analyzing "public.p_ci_builds" inheritance tree
918INFO: analyzing "public.ci_builds"
919INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
920INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
921INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
922INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
923INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
924I, [2024-05-29T23:38:26.626802 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
925I, [2024-05-29T23:38:45.595297 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
926INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
927INFO: analyzing "public.ci_pipeline_variables"
928INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
929I, [2024-05-29T23:38:47.128926 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
930I, [2024-05-29T23:38:47.129948 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
931I, [2024-05-29T23:38:47.130564 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
932INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
933INFO: analyzing "public.ci_pipeline_variables"
934INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
935INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
936INFO: analyzing "public.ci_job_artifacts"
937INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
938I, [2024-05-29T23:38:52.682957 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
939I, [2024-05-29T23:38:52.684004 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
940I, [2024-05-29T23:38:52.684710 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
941INFO: analyzing "public.p_ci_stages" inheritance tree
942INFO: analyzing "public.ci_stages"
943INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
944I, [2024-05-29T23:38:55.849198 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
945I, [2024-05-29T23:38:55.850200 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
946I, [2024-05-29T23:38:55.850869 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
947I, [2024-05-29T23:38:56.986839 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
948I, [2024-05-29T23:39:03.011944 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
949# [RSpecRunTime] Finishing example group spec/migrations/swap_events_target_id_to_bigint_for_gitlab_dot_com_spec.rb. It took 3 minutes 23.68 seconds. Expected to take 2 minutes 46.24 seconds.
950# [RSpecRunTime] RSpec elapsed time: 7 minutes 13.99 seconds. Current RSS: ~1133M. Threads: 2. load average: 0.79 0.94 1.03 1/288 398
951.
952# [RSpecRunTime] Starting example group spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb. Expected to take 2 minutes 18.62 seconds.
953QueueBackfillPartitionIdCiPipelineArtifact
954 #down
955ci: == [advisory_lock_connection] object_id: 114981920, pg_backend_pid: 899
956ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrating =======
957ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrated (0.0700s)
958ci: == [advisory_lock_connection] object_id: 114981920, pg_backend_pid: 899
959 deletes all batched migration records
960# [RSpecRunTime] RSpec elapsed time: 8 minutes 50.14 seconds. Current RSS: ~1139M. Threads: 2. load average: 0.93 0.95 1.02 1/288 399
961.
962 #up
963 with migration present
964 when migration finished successfully
965ci: == [advisory_lock_connection] object_id: 115818680, pg_backend_pid: 906
966ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrating =======
967ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrated (0.0650s)
968ci: == [advisory_lock_connection] object_id: 115818680, pg_backend_pid: 906
969 does not raise exception
970ci: == [advisory_lock_connection] object_id: 116447600, pg_backend_pid: 913
971ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrating =======
972ci: == 20240109090354 QueueBackfillPartitionIdCiPipelineArtifact: migrated (0.0634s)
973ci: == [advisory_lock_connection] object_id: 116447600, pg_backend_pid: 913
974 schedules background jobs for each batch of ci_pipeline_artifacts
975# [RSpecRunTime] RSpec elapsed time: 9 minutes 8.98 seconds. Current RSS: ~1133M. Threads: 2. load average: 0.95 0.95 1.01 1/288 400
976.
977I, [2024-05-29T23:41:20.966199 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': 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-05-29T23:41:22.521404 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
982I, [2024-05-29T23:41:22.522362 #344] 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-05-29T23:41:22.523024 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
984INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
985INFO: analyzing "public.ci_pipeline_variables"
986INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
987INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
988INFO: analyzing "public.ci_job_artifacts"
989INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
990I, [2024-05-29T23:41:27.824291 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
991I, [2024-05-29T23:41:27.826038 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
992I, [2024-05-29T23:41:27.826911 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
993INFO: analyzing "public.p_ci_stages" inheritance tree
994INFO: analyzing "public.ci_stages"
995INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
996I, [2024-05-29T23:41:31.003449 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
997I, [2024-05-29T23:41:31.005099 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
998I, [2024-05-29T23:41:31.005888 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
999I, [2024-05-29T23:41:32.157204 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1000I, [2024-05-29T23:41:37.857389 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1001I, [2024-05-29T23:41:55.723784 #344] INFO -- : Database: 'ci', Table: 'audit_events_group_external_streaming_destinations': Lock Writes
1002I, [2024-05-29T23:41:55.781833 #344] INFO -- : Database: 'ci', Table: 'packages_terraform_module_metadata': Lock Writes
1003I, [2024-05-29T23:41:55.810577 #344] INFO -- : Database: 'ci', Table: 'member_approvals': Lock Writes
1004I, [2024-05-29T23:41:55.839558 #344] INFO -- : Database: 'ci', Table: 'zoekt_repositories': Lock Writes
1005I, [2024-05-29T23:41:56.851868 #344] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_streaming_destinations': Lock Writes
1006I, [2024-05-29T23:41:56.878233 #344] INFO -- : Database: 'ci', Table: 'catalog_verified_namespaces': Lock Writes
1007I, [2024-05-29T23:41:56.904717 #344] INFO -- : Database: 'ci', Table: 'group_saved_replies': Lock Writes
1008INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1009INFO: analyzing "public.ci_pipeline_variables"
1010INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1011INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1012INFO: analyzing "public.ci_pipeline_variables"
1013INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1014I, [2024-05-29T23:42:03.308016 #344] INFO -- : Database: 'ci', Table: 'zoekt_tasks': Lock Writes
1015I, [2024-05-29T23:42:03.532817 #344] INFO -- : Database: 'ci', Table: 'merge_request_diff_commits_b5377a7a34': Lock Writes
1016I, [2024-05-29T23:42:03.534526 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1017I, [2024-05-29T23:42:03.535275 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1018I, [2024-05-29T23:42:03.718078 #344] INFO -- : Database: 'ci', Table: 'audit_events_group_streaming_event_type_filters': Lock Writes
1019I, [2024-05-29T23:42:04.943993 #344] INFO -- : Database: 'ci', Table: 'remote_development_namespace_cluster_agent_mappings': Lock Writes
1020I, [2024-05-29T23:42:04.945179 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1021I, [2024-05-29T23:42:04.946263 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1022I, [2024-05-29T23:42:05.618027 #344] INFO -- : Database: 'ci', Table: 'p_catalog_resource_component_usages': Lock Writes
1023INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1024INFO: analyzing "public.ci_job_artifacts"
1025INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1026INFO: analyzing "public.p_ci_stages" inheritance tree
1027INFO: analyzing "public.ci_stages"
1028INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1029I, [2024-05-29T23:42:10.645035 #344] INFO -- : Database: 'ci', Table: 'temp_notes_backup': Lock Writes
1030I, [2024-05-29T23:42:11.084727 #344] INFO -- : Database: 'ci', Table: 'audit_events_instance_streaming_event_type_filters': Lock Writes
1031I, [2024-05-29T23:42:11.188517 #344] INFO -- : Database: 'ci', Table: 'security_policies': Lock Writes
1032I, [2024-05-29T23:42:11.217047 #344] INFO -- : Database: 'ci', Table: 'approval_policy_rules': Lock Writes
1033I, [2024-05-29T23:42:11.396621 #344] INFO -- : Database: 'ci', Table: 'relation_import_trackers': Lock Writes
1034I, [2024-05-29T23:42:11.706874 #344] INFO -- : Database: 'ci', Table: 'project_saved_replies': Lock Writes
1035I, [2024-05-29T23:42:16.385930 #344] INFO -- : Database: 'ci', Table: 'import_source_users': Lock Writes
1036I, [2024-05-29T23:42:16.413255 #344] INFO -- : Database: 'ci', Table: 'audit_events_streaming_instance_namespace_filters': Lock Writes
1037I, [2024-05-29T23:42:16.491579 #344] INFO -- : Database: 'ci', Table: 'early_access_program_tracking_events': Lock Writes
1038I, [2024-05-29T23:42:16.995543 #344] INFO -- : Database: 'ci', Table: 'merge_request_diff_files_99208b8fac': Lock Writes
1039I, [2024-05-29T23:42:16.996570 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1040I, [2024-05-29T23:42:16.997356 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1041I, [2024-05-29T23:42:17.874473 #344] INFO -- : Database: 'ci', Table: 'audit_events_streaming_group_namespace_filters': Lock Writes
1042I, [2024-05-29T23:42:17.982356 #344] INFO -- : Database: 'ci', Table: 'ai_self_hosted_models': Lock Writes
1043I, [2024-05-29T23:42:18.009682 #344] INFO -- : Database: 'ci', Table: 'merge_request_requested_changes': Lock Writes
1044I, [2024-05-29T23:42:18.619247 #344] INFO -- : Database: 'ci', Table: 'custom_software_licenses': Lock Writes
1045I, [2024-05-29T23:42:18.645436 #344] INFO -- : Database: 'ci', Table: 'ai_vectorizable_files': Lock Writes
1046I, [2024-05-29T23:42:18.646482 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1047I, [2024-05-29T23:42:18.647161 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1048I, [2024-05-29T23:42:18.675074 #344] INFO -- : Database: 'ci', Table: 'ai_agent_version_attachments': Lock Writes
1049I, [2024-05-29T23:42:18.676096 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1050I, [2024-05-29T23:42:18.676729 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1051I, [2024-05-29T23:42:30.302301 #344] INFO -- : Database: 'ci', Table: 'ai_feature_settings': Lock Writes
1052I, [2024-05-29T23:42:30.331326 #344] INFO -- : Database: 'ci', Table: 'user_broadcast_message_dismissals': Lock Writes
1053I, [2024-05-29T23:42:30.526429 #344] INFO -- : Database: 'ci', Table: 'user_audit_events': Lock Writes
1054I, [2024-05-29T23:42:30.558217 #344] INFO -- : Database: 'ci', Table: 'group_audit_events': Lock Writes
1055I, [2024-05-29T23:42:30.589125 #344] INFO -- : Database: 'ci', Table: 'project_audit_events': Lock Writes
1056I, [2024-05-29T23:42:30.621252 #344] INFO -- : Database: 'ci', Table: 'instance_audit_events': Lock Writes
1057# [RSpecRunTime] Finishing example group spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb. It took 3 minutes 14.02 seconds. Expected to take 2 minutes 18.62 seconds.
1058# [RSpecRunTime] RSpec elapsed time: 10 minutes 28.05 seconds. Current RSS: ~1127M. Threads: 2. load average: 0.91 0.94 1.00 1/287 401
1059.
1060# [RSpecRunTime] Starting example group spec/migrations/add_work_items_related_link_restrictions_spec.rb. Expected to take 1 minute 52.85 seconds.
1061AddWorkItemsRelatedLinkRestrictions
1062main: == [advisory_lock_connection] object_id: 156760920, pg_backend_pid: 1637
1063main: == 20231005131445 AddWorkItemsRelatedLinkRestrictions: migrating ==============
1064main: == 20231005131445 AddWorkItemsRelatedLinkRestrictions: migrated (0.0353s) =====
1065main: == [advisory_lock_connection] object_id: 156760920, pg_backend_pid: 1637
1066 creates default restrictions
1067 when work item types are missing
1068main: == [advisory_lock_connection] object_id: 157519800, pg_backend_pid: 1641
1069main: == 20231005131445 AddWorkItemsRelatedLinkRestrictions: migrating ==============
1070main: == 20231005131445 AddWorkItemsRelatedLinkRestrictions: migrated (0.0309s) =====
1071main: == [advisory_lock_connection] object_id: 157519800, pg_backend_pid: 1641
1072 does not add restrictions
1073# [RSpecRunTime] RSpec elapsed time: 11 minutes 50.7 seconds. Current RSS: ~1160M. Threads: 2. load average: 0.81 0.90 0.98 1/287 402
1074.
1075I, [2024-05-29T23:44:16.600310 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1076INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1077INFO: analyzing "public.ci_pipeline_variables"
1078INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1079I, [2024-05-29T23:44:18.105965 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1080I, [2024-05-29T23:44:18.106970 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1081I, [2024-05-29T23:44:18.107632 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1082INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1083INFO: analyzing "public.ci_pipeline_variables"
1084INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1085INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1086INFO: analyzing "public.ci_job_artifacts"
1087INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1088I, [2024-05-29T23:44:23.499030 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1089I, [2024-05-29T23:44:23.499949 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1090I, [2024-05-29T23:44:23.500606 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1091INFO: analyzing "public.p_ci_stages" inheritance tree
1092INFO: analyzing "public.ci_stages"
1093INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1094I, [2024-05-29T23:44:26.556709 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1095I, [2024-05-29T23:44:26.557701 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1096I, [2024-05-29T23:44:26.558786 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1097I, [2024-05-29T23:44:27.687522 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1098I, [2024-05-29T23:44:33.357391 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1099# [RSpecRunTime] Finishing example group spec/migrations/add_work_items_related_link_restrictions_spec.rb. It took 2 minutes 15.29 seconds. Expected to take 1 minute 52.85 seconds.
1100# [RSpecRunTime] RSpec elapsed time: 12 minutes 43.38 seconds. Current RSS: ~1123M. Threads: 2. load average: 0.92 0.92 0.99 1/286 403
1101.
1102# [RSpecRunTime] Starting example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. Expected to take 1 minute 43.21 seconds.
1103SwapColumnsForCiStagesPipelineIdBigintV2
1104 when pipeline_id sql type is integer
1105 behaves like swap conversion columns
1106main: == [advisory_lock_connection] object_id: 188009020, pg_backend_pid: 1655
1107main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrating =========
1108main: -- columns(:ci_stages)
1109main: -> 0.0035s
1110main: -- execute("LOCK TABLE ci_pipelines, ci_stages IN ACCESS EXCLUSIVE MODE")
1111main: -> 0.0013s
1112main: -- clear_cache!(nil)
1113main: -> 0.0000s
1114main: -- quote_table_name(:ci_stages)
1115main: -> 0.0000s
1116main: -- quote_column_name(:pipeline_id)
1117main: -> 0.0000s
1118main: -- quote_column_name(:temp_name_for_renaming)
1119main: -> 0.0000s
1120main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"pipeline_id\" TO \"temp_name_for_renaming\"\n")
1121main: -> 0.0009s
1122main: -- clear_cache!(nil)
1123main: -> 0.0000s
1124main: -- quote_table_name(:ci_stages)
1125main: -> 0.0000s
1126main: -- quote_column_name(:pipeline_id_convert_to_bigint)
1127main: -> 0.0000s
1128main: -- quote_column_name(:pipeline_id)
1129main: -> 0.0000s
1130main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"pipeline_id_convert_to_bigint\" TO \"pipeline_id\"\n")
1131main: -> 0.0009s
1132main: -- clear_cache!(nil)
1133main: -> 0.0000s
1134main: -- quote_table_name(:ci_stages)
1135main: -> 0.0000s
1136main: -- quote_column_name(:temp_name_for_renaming)
1137main: -> 0.0000s
1138main: -- quote_column_name(:pipeline_id_convert_to_bigint)
1139main: -> 0.0000s
1140main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"temp_name_for_renaming\" TO \"pipeline_id_convert_to_bigint\"\n")
1141main: -> 0.0009s
1142main: -- quote_table_name(:trigger_07bc3c48f407)
1143main: -> 0.0000s
1144main: -- execute("ALTER FUNCTION \"trigger_07bc3c48f407\" RESET ALL")
1145main: -> 0.0008s
1146main: -- quote_table_name(:ci_stages)
1147main: -> 0.0000s
1148main: -- quote_column_name(:fk_fb57e6cc56)
1149main: -> 0.0000s
1150main: -- quote_column_name(:temp_name_for_renaming)
1151main: -> 0.0000s
1152main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"fk_fb57e6cc56\" TO \"temp_name_for_renaming\"\n")
1153main: -> 0.0009s
1154main: -- quote_table_name(:ci_stages)
1155main: -> 0.0000s
1156main: -- quote_column_name(:fk_c5ddde695f)
1157main: -> 0.0000s
1158main: -- quote_column_name(:fk_fb57e6cc56)
1159main: -> 0.0000s
1160main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"fk_c5ddde695f\" TO \"fk_fb57e6cc56\"\n")
1161main: -> 0.0009s
1162main: -- quote_table_name(:ci_stages)
1163main: -> 0.0000s
1164main: -- quote_column_name(:temp_name_for_renaming)
1165main: -> 0.0000s
1166main: -- quote_column_name(:fk_c5ddde695f)
1167main: -> 0.0000s
1168main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"temp_name_for_renaming\" TO \"fk_c5ddde695f\"\n")
1169main: -> 0.0010s
1170main: -- quote_table_name("index_ci_stages_on_pipeline_id")
1171main: -> 0.0000s
1172main: -- quote_table_name("temp_5fd4e9a225")
1173main: -> 0.0000s
1174main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id\" RENAME TO \"temp_5fd4e9a225\"")
1175main: -> 0.0009s
1176main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint")
1177main: -> 0.0000s
1178main: -- quote_table_name(:index_ci_stages_on_pipeline_id)
1179main: -> 0.0000s
1180main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint\" RENAME TO \"index_ci_stages_on_pipeline_id\"")
1181main: -> 0.0008s
1182main: -- quote_table_name("temp_5fd4e9a225")
1183main: -> 0.0000s
1184main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint)
1185main: -> 0.0000s
1186main: -- execute("ALTER INDEX \"temp_5fd4e9a225\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint\"")
1187main: -> 0.0009s
1188main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_id")
1189main: -> 0.0000s
1190main: -- quote_table_name("temp_ced8356685")
1191main: -> 0.0000s
1192main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_id\" RENAME TO \"temp_ced8356685\"")
1193main: -> 0.0009s
1194main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_id")
1195main: -> 0.0000s
1196main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_id)
1197main: -> 0.0000s
1198main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_id\" RENAME TO \"index_ci_stages_on_pipeline_id_and_id\"")
1199main: -> 0.0008s
1200main: -- quote_table_name("temp_ced8356685")
1201main: -> 0.0000s
1202main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_id)
1203main: -> 0.0000s
1204main: -- execute("ALTER INDEX \"temp_ced8356685\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_id\"")
1205main: -> 0.0008s
1206main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_name")
1207main: -> 0.0000s
1208main: -- quote_table_name("temp_348df98ef6")
1209main: -> 0.0000s
1210main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_name\" RENAME TO \"temp_348df98ef6\"")
1211main: -> 0.0008s
1212main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_name")
1213main: -> 0.0000s
1214main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_name)
1215main: -> 0.0000s
1216main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_name\" RENAME TO \"index_ci_stages_on_pipeline_id_and_name\"")
1217main: -> 0.0014s
1218main: -- quote_table_name("temp_348df98ef6")
1219main: -> 0.0000s
1220main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_name)
1221main: -> 0.0000s
1222main: -- execute("ALTER INDEX \"temp_348df98ef6\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_name\"")
1223main: -> 0.0008s
1224main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_position")
1225main: -> 0.0000s
1226main: -- quote_table_name("temp_d0b3b501ce")
1227main: -> 0.0000s
1228main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_position\" RENAME TO \"temp_d0b3b501ce\"")
1229main: -> 0.0008s
1230main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_position")
1231main: -> 0.0000s
1232main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_position)
1233main: -> 0.0000s
1234main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_position\" RENAME TO \"index_ci_stages_on_pipeline_id_and_position\"")
1235main: -> 0.0008s
1236main: -- quote_table_name("temp_d0b3b501ce")
1237main: -> 0.0000s
1238main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_position)
1239main: -> 0.0000s
1240main: -- execute("ALTER INDEX \"temp_d0b3b501ce\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_position\"")
1241main: -> 0.0008s
1242main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrated (0.0448s)
1243main: == [advisory_lock_connection] object_id: 188009020, pg_backend_pid: 1655
1244 correctly swaps conversion columns
1245# [RSpecRunTime] RSpec elapsed time: 13 minutes 52.26 seconds. Current RSS: ~1186M. Threads: 2. load average: 1.03 0.95 1.00 1/287 404
1246.
1247 when pipeline_id sql type is bigint
1248main: == [advisory_lock_connection] object_id: 188759980, pg_backend_pid: 1660
1249main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrating =========
1250main: -- columns(:ci_stages)
1251main: -> 0.0034s
1252main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrated (0.0196s)
1253main: == [advisory_lock_connection] object_id: 188759980, pg_backend_pid: 1660
1254 does nothing
1255# [RSpecRunTime] RSpec elapsed time: 14 minutes 5.57 seconds. Current RSS: ~1160M. Threads: 2. load average: 1.02 0.95 1.00 1/287 405
1256.
1257I, [2024-05-29T23:46:30.118866 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1258INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1259INFO: analyzing "public.ci_pipeline_variables"
1260INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1261I, [2024-05-29T23:46:31.732309 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1262I, [2024-05-29T23:46:31.733239 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1263I, [2024-05-29T23:46:31.734821 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1264INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1265INFO: analyzing "public.ci_pipeline_variables"
1266INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1267INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1268INFO: analyzing "public.ci_job_artifacts"
1269INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1270I, [2024-05-29T23:46:37.368757 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1271I, [2024-05-29T23:46:37.369779 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1272I, [2024-05-29T23:46:37.370501 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1273INFO: analyzing "public.p_ci_stages" inheritance tree
1274INFO: analyzing "public.ci_stages"
1275INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1276I, [2024-05-29T23:46:40.375161 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1277I, [2024-05-29T23:46:40.376124 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1278I, [2024-05-29T23:46:40.376803 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1279I, [2024-05-29T23:46:41.426522 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1280I, [2024-05-29T23:46:47.086506 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1281# [RSpecRunTime] Finishing example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. It took 2 minutes 13.99 seconds. Expected to take 1 minute 43.21 seconds.
1282# [RSpecRunTime] RSpec elapsed time: 14 minutes 57.41 seconds. Current RSS: ~1115M. Threads: 2. load average: 1.01 0.96 1.00 1/287 406
1283.
1284# [RSpecRunTime] Starting example group spec/migrations/20230721095222_delete_orphans_approval_project_rules2_spec.rb. Expected to take 1 minute 32.08 seconds.
1285DeleteOrphansApprovalProjectRules2
1286 #up
1287main: == [advisory_lock_connection] object_id: 220914740, pg_backend_pid: 1675
1288main: == 20230721095222 DeleteOrphansApprovalProjectRules2: migrating ===============
1289main: == 20230721095222 DeleteOrphansApprovalProjectRules2: migrated (0.0508s) ======
1290main: == [advisory_lock_connection] object_id: 220914740, pg_backend_pid: 1675
1291 schedules background migration for project approval rules
1292# [RSpecRunTime] RSpec elapsed time: 16 minutes 16.58 seconds. Current RSS: ~1176M. Threads: 2. load average: 1.02 0.98 1.00 1/287 407
1293.
1294INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1295INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1296INFO: "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
1297INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1298INFO: "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
1299INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1300INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1301INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1302INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1303INFO: "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
1304INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1305INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1306INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1307INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1308INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1309INFO: analyzing "public.ci_builds_metadata"
1310INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1311INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1312INFO: "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
1313INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1314INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1315INFO: analyzing "public.p_ci_builds" inheritance tree
1316INFO: analyzing "public.ci_builds"
1317INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1318INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1319INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1320INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1321INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1322I, [2024-05-29T23:48:44.792858 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1323I, [2024-05-29T23:49:03.503730 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1324INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1325INFO: analyzing "public.ci_pipeline_variables"
1326INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1327I, [2024-05-29T23:49:05.097040 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1328I, [2024-05-29T23:49:05.098168 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1329I, [2024-05-29T23:49:05.098919 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1330INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1331INFO: analyzing "public.ci_pipeline_variables"
1332INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1333INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1334INFO: analyzing "public.ci_job_artifacts"
1335INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1336I, [2024-05-29T23:49:10.451792 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1337I, [2024-05-29T23:49:10.452711 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1338I, [2024-05-29T23:49:10.469500 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1339INFO: analyzing "public.p_ci_stages" inheritance tree
1340INFO: analyzing "public.ci_stages"
1341INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1342I, [2024-05-29T23:49:13.617841 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1343I, [2024-05-29T23:49:13.618843 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1344I, [2024-05-29T23:49:13.619557 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1345I, [2024-05-29T23:49:14.698293 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1346I, [2024-05-29T23:49:20.497339 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1347# [RSpecRunTime] Finishing example group spec/migrations/20230721095222_delete_orphans_approval_project_rules2_spec.rb. It took 2 minutes 33.29 seconds. Expected to take 1 minute 32.08 seconds.
1348# [RSpecRunTime] RSpec elapsed time: 17 minutes 30.73 seconds. Current RSS: ~1123M. Threads: 2. load average: 0.86 0.95 0.99 1/288 408
1349.
1350# [RSpecRunTime] Starting example group spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb. Expected to take 1 minute 18.32 seconds.
1351QueueBackfillMergeRequestDiffsProjectId
1352main: == [advisory_lock_connection] object_id: 253532040, pg_backend_pid: 1690
1353main: == 20231114043522 QueueBackfillMergeRequestDiffsProjectId: migrating ==========
1354main: == 20231114043522 QueueBackfillMergeRequestDiffsProjectId: migrated (0.0529s) =
1355main: == [advisory_lock_connection] object_id: 253532040, pg_backend_pid: 1690
1356 schedules a new batched migration
1357I, [2024-05-29T23:50:49.859134 #344] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1358INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1359INFO: analyzing "public.ci_pipeline_variables"
1360INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1361I, [2024-05-29T23:50:51.485653 #344] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1362I, [2024-05-29T23:50:51.486607 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1363I, [2024-05-29T23:50:51.487244 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1364INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1365INFO: analyzing "public.ci_pipeline_variables"
1366INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1367INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1368INFO: analyzing "public.ci_job_artifacts"
1369INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1370I, [2024-05-29T23:50:57.194222 #344] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1371I, [2024-05-29T23:50:57.195209 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1372I, [2024-05-29T23:50:57.195990 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1373INFO: analyzing "public.p_ci_stages" inheritance tree
1374INFO: analyzing "public.ci_stages"
1375INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1376I, [2024-05-29T23:51:00.561509 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1377I, [2024-05-29T23:51:00.562614 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1378I, [2024-05-29T23:51:00.563331 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1379I, [2024-05-29T23:51:01.725641 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1380I, [2024-05-29T23:51:07.626352 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1381# [RSpecRunTime] Finishing example group spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb. It took 1 minute 47.71 seconds. Expected to take 1 minute 18.32 seconds.
1382# [RSpecRunTime] RSpec elapsed time: 19 minutes 18.48 seconds. Current RSS: ~1119M. Threads: 2. load average: 0.94 0.95 0.99 1/288 409
1383.
1384# [RSpecRunTime] Starting example group spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb. Expected to take 1 minute 2.62 seconds.
1385AddDevelopmentWidgetToWorkItemTypes
1386 behaves like migration that adds widget to work items definitions
1387 #up
1388main: == [advisory_lock_connection] object_id: 277318840, pg_backend_pid: 1705
1389main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1390main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrated (0.0219s) =====
1391main: == [advisory_lock_connection] object_id: 277318840, pg_backend_pid: 1705
1392 creates widget definition in all types (FAILED - 1)
1393main: == [advisory_lock_connection] object_id: 278717600, pg_backend_pid: 1707
1394main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1395main: == [advisory_lock_connection] object_id: 278717600, pg_backend_pid: 1707
1396 logs a warning if the type is missing (FAILED - 2)
1397 when the widget already exists
1398 upserts the widget definitions and raises no error (FAILED - 3)
1399# [RSpecRunTime] RSpec elapsed time: 20 minutes 1.4 seconds. Current RSS: ~1123M. Threads: 2. load average: 0.86 0.93 0.99 1/288 410
1400.
1401 #down
1402main: == [advisory_lock_connection] object_id: 279894940, pg_backend_pid: 1711
1403main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1404main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrated (0.0214s) =====
1405main: == [advisory_lock_connection] object_id: 279894940, pg_backend_pid: 1711
1406 removes definitions for widget (FAILED - 4)
1407# [RSpecRunTime] RSpec elapsed time: 20 minutes 8.06 seconds. Current RSS: ~1124M. Threads: 2. load average: 0.79 0.91 0.98 1/288 411
1408.
1409I, [2024-05-29T23:52:24.049818 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1410# [RSpecRunTime] Finishing example group spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb. It took 1 minute 15.95 seconds. Expected to take 1 minute 2.62 seconds.
1411# [RSpecRunTime] RSpec elapsed time: 20 minutes 34.47 seconds. Current RSS: ~1120M. Threads: 2. load average: 0.92 0.94 0.99 1/288 412
1412.
1413# [RSpecRunTime] Starting example group spec/migrations/20240214203242_queue_backfill_sbom_occurrences_traversal_ids_and_archived_spec.rb. Expected to take 49.47 seconds.
1414QueueBackfillSbomOccurrencesTraversalIdsAndArchived
1415main: == [advisory_lock_connection] object_id: 299058980, pg_backend_pid: 1723
1416main: == 20240214203242 QueueBackfillSbomOccurrencesTraversalIdsAndArchived: migrating
1417main: == 20240214203242 QueueBackfillSbomOccurrencesTraversalIdsAndArchived: migrated (0.0534s)
1418main: == [advisory_lock_connection] object_id: 299058980, pg_backend_pid: 1723
1419 schedules a new batched migration
1420INFO: analyzing "public.p_ci_stages" inheritance tree
1421INFO: analyzing "public.ci_stages"
1422INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1423I, [2024-05-29T23:53:21.609307 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1424I, [2024-05-29T23:53:21.610406 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1425I, [2024-05-29T23:53:21.611292 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1426I, [2024-05-29T23:53:22.769168 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1427I, [2024-05-29T23:53:28.908395 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1428# [RSpecRunTime] Finishing example group spec/migrations/20240214203242_queue_backfill_sbom_occurrences_traversal_ids_and_archived_spec.rb. It took 1 minute 6.37 seconds. Expected to take 49.47 seconds.
1429# [RSpecRunTime] RSpec elapsed time: 21 minutes 40.87 seconds. Current RSS: ~1118M. Threads: 2. load average: 0.95 0.94 0.99 1/291 413
1430.
1431# [RSpecRunTime] Starting example group spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb. Expected to take 37.3 seconds.
1432QueueUpdateSbomComponentsNameBasedOnPep503
1433main: == [advisory_lock_connection] object_id: 317430520, pg_backend_pid: 1736
1434main: == 20240223130548 QueueUpdateSbomComponentsNameBasedOnPep503: migrating =======
1435main: == 20240223130548 QueueUpdateSbomComponentsNameBasedOnPep503: migrated (0.0533s)
1436main: == [advisory_lock_connection] object_id: 317430520, pg_backend_pid: 1736
1437 schedules a new batched migration
1438I, [2024-05-29T23:54:28.567563 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1439# [RSpecRunTime] Finishing example group spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb. It took 58.34 seconds. Expected to take 37.3 seconds.
1440# [RSpecRunTime] RSpec elapsed time: 22 minutes 39.25 seconds. Current RSS: ~1121M. Threads: 2. load average: 1.12 1.00 1.00 1/291 414
1441.
1442# [RSpecRunTime] Starting example group spec/migrations/20240419035360_queue_backfill_workspace_variables_project_id_spec.rb. Expected to take 27.18 seconds.
1443QueueBackfillWorkspaceVariablesProjectId
1444main: == [advisory_lock_connection] object_id: 333334780, pg_backend_pid: 1748
1445main: == 20240419035360 QueueBackfillWorkspaceVariablesProjectId: migrating =========
1446main: == 20240419035360 QueueBackfillWorkspaceVariablesProjectId: migrated (0.0514s)
1447main: == [advisory_lock_connection] object_id: 333334780, pg_backend_pid: 1748
1448 schedules a new batched migration
1449# [RSpecRunTime] Finishing example group spec/migrations/20240419035360_queue_backfill_workspace_variables_project_id_spec.rb. It took 38.65 seconds. Expected to take 27.18 seconds.
1450# [RSpecRunTime] RSpec elapsed time: 23 minutes 17.94 seconds. Current RSS: ~1136M. Threads: 2. load average: 0.87 0.95 0.99 1/291 415
1451.
1452# [RSpecRunTime] Starting example group spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb. Expected to take 9.37 seconds.
1453QueueBackfillAuditEventsStreamingHeadersGroupId
1454main: == [advisory_lock_connection] object_id: 339300120, pg_backend_pid: 1760
1455main: == 20240514142206 QueueBackfillAuditEventsStreamingHeadersGroupId: migrating ==
1456main: == 20240514142206 QueueBackfillAuditEventsStreamingHeadersGroupId: migrated (0.0625s)
1457main: == [advisory_lock_connection] object_id: 339300120, pg_backend_pid: 1760
1458 schedules a new batched migration
1459# [RSpecRunTime] Finishing example group spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb. It took 16.59 seconds. Expected to take 9.37 seconds.
1460# [RSpecRunTime] RSpec elapsed time: 23 minutes 34.57 seconds. Current RSS: ~1132M. Threads: 2. load average: 0.90 0.96 0.99 1/291 416
1461.
1462auto_explain log contains 921 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-8-15.344.main.ndjson.gz
1463took 22.015935207
1464auto_explain log contains 922 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-8-15.344.ci.ndjson.gz
1465took 21.943126959
1466[TEST PROF INFO] EventProf results for sql.active_record
1467Total time: 13:17.479 of 23:29.368 (56.58%)
1468Total events: 691347
1469Top 5 slowest suites (by time):
1470QueueBackfill...pelineArtifact (./spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb:6) – 01:56.815 (116449 / 3) of 03:14.055 (60.2%)
1471SwapEventsTar...orGitlabDotCom (./spec/migrations/swap_events_target_id_to_bigint_for_gitlab_dot_com_spec.rb:6) – 01:47.812 (78643 / 2) of 03:23.728 (52.92%)
1472DeleteOrphans...lProjectRules2 (./spec/migrations/20230721095222_delete_orphans_approval_project_rules2_spec.rb:6) – 01:47.573 (75905 / 1) of 02:33.329 (70.16%)
1473SwapSystemNot...ForSelfManaged (./spec/migrations/20230809174702_swap_system_note_metadata_note_id_to_bigint_for_self_managed_spec.rb:6) – 01:30.982 (77869 / 4) of 03:45.075 (40.42%)
1474SwapColumnsFo...lineIdBigintV2 (./spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb:6) – 01:15.398 (64967 / 2) of 02:14.028 (56.26%)
1475Knapsack report was generated. Preview:
1476{
1477 "spec/migrations/20230809174702_swap_system_note_metadata_note_id_to_bigint_for_self_managed_spec.rb": 163.404516994,
1478 "spec/migrations/swap_events_target_id_to_bigint_for_gitlab_dot_com_spec.rb": 129.59951724300026,
1479 "spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb": 115.05987431899985,
1480 "spec/migrations/add_work_items_related_link_restrictions_spec.rb": 82.74537351800018,
1481 "spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb": 82.31075721000025,
1482 "spec/migrations/20230721095222_delete_orphans_approval_project_rules2_spec.rb": 79.27438358100017,
1483 "spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb": 59.86400948100027,
1484 "spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb": 49.79510785399998,
1485 "spec/migrations/20240214203242_queue_backfill_sbom_occurrences_traversal_ids_and_archived_spec.rb": 36.18657366299976,
1486 "spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb": 32.245627926,
1487 "spec/migrations/20240419035360_queue_backfill_workspace_variables_project_id_spec.rb": 22.43787451499975,
1488 "spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb": 8.879878217000169
1489}
1490Knapsack global time execution for tests: 14m 21s
1491Failures:
1492 1) AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #up creates widget definition in all types
1493 Failure/Error: expect { migrate! }.to change { work_item_definitions.count }.by(work_item_type_count)
1494 expected `work_item_definitions.count` to have changed by 3, but was changed by 0
1495 Shared Example Group: "migration that adds widget to work items definitions" called from ./spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb:7
1496 # ./spec/support/shared_examples/migrations/add_work_item_widget_shared_examples.rb:20:in `block (3 levels) in <top (required)>'
1497 # ./spec/spec_helper.rb:511:in `block (3 levels) in <top (required)>'
1498 # ./spec/spec_helper.rb:448:in `block (3 levels) in <top (required)>'
1499 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
1500 # ./spec/spec_helper.rb:447:in `block (2 levels) in <top (required)>'
1501 # ./spec/spec_helper.rb:442:in `block (3 levels) in <top (required)>'
1502 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
1503 # ./spec/spec_helper.rb:433:in `block (2 levels) in <top (required)>'
1504 # ./spec/spec_helper.rb:429:in `block (3 levels) in <top (required)>'
1505 # ./lib/gitlab/application_context.rb:70:in `with_raw_context'
1506 # ./spec/spec_helper.rb:429:in `block (2 levels) in <top (required)>'
1507 # ./spec/spec_helper.rb:261:in `block (2 levels) in <top (required)>'
1508 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
1509 # ./spec/support/migration.rb:43:in `block (2 levels) in <top (required)>'
1510 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
1511 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
1512 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
1513 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
1514 2) AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #up logs a warning if the type is missing
1515 Failure/Error: super
1516 #<Gitlab::AppLogger (class)> received :warn with unexpected arguments
1517 expected: ("type Issue is missing, not adding widget")
1518 got: ("type Task is missing, not adding widget")
1519 Shared Example Group: "migration that adds widget to work items definitions" called from ./spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb:7
1520 # ./db/migrate/20240327214545_add_development_widget_to_work_item_types.rb:31:in `block in up'
1521 # ./db/migrate/20240327214545_add_development_widget_to_work_item_types.rb:27:in `each'
1522 # ./db/migrate/20240327214545_add_development_widget_to_work_item_types.rb:27:in `up'
1523 # ./lib/gitlab/database/migration_helpers/restrict_gitlab_schema.rb:33:in `block in exec_migration'
1524 # ./lib/gitlab/database/query_analyzer.rb:40:in `within'
1525 # ./lib/gitlab/database/migration_helpers/restrict_gitlab_schema.rb:30:in `exec_migration'
1526 # ./lib/gitlab/database/migration_helpers/automatic_lock_writes_on_tables.rb:21:in `exec_migration'
1527 # ./lib/gitlab/database/migrations/lock_retry_mixin.rb:54:in `ddl_transaction'
1528 # ./lib/gitlab/database/migrations/runner_backoff/active_record_mixin.rb:21:in `execute_migration_in_transaction'
1529 # ./lib/gitlab/database/migrations/pg_backend_pid.rb:15:in `block in with_advisory_lock_connection'
1530 # ./lib/gitlab/database/migrations/pg_backend_pid.rb:12:in `with_advisory_lock_connection'
1531 # ./spec/support/helpers/migrations_helpers.rb:177:in `migrate!'
1532 # ./ee/spec/support/helpers/ee/migrations_helpers.rb:43:in `block in migrate!'
1533 # ./ee/spec/support/helpers/ee/migrations_helpers.rb:50:in `with_db_config'
1534 # ./ee/spec/support/helpers/ee/migrations_helpers.rb:43:in `migrate!'
1535 # ./spec/support/shared_examples/migrations/add_work_item_widget_shared_examples.rb:32:in `block (3 levels) in <top (required)>'
1536 # ./spec/spec_helper.rb:511:in `block (3 levels) in <top (required)>'
1537 # ./spec/spec_helper.rb:448:in `block (3 levels) in <top (required)>'
1538 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
1539 # ./spec/spec_helper.rb:447:in `block (2 levels) in <top (required)>'
1540 # ./spec/spec_helper.rb:442:in `block (3 levels) in <top (required)>'
1541 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
1542 # ./spec/spec_helper.rb:433:in `block (2 levels) in <top (required)>'
1543 # ./spec/spec_helper.rb:429:in `block (3 levels) in <top (required)>'
1544 # ./lib/gitlab/application_context.rb:70:in `with_raw_context'
1545 # ./spec/spec_helper.rb:429:in `block (2 levels) in <top (required)>'
1546 # ./spec/spec_helper.rb:261:in `block (2 levels) in <top (required)>'
1547 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
1548 # ./spec/support/migration.rb:43:in `block (2 levels) in <top (required)>'
1549 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
1550 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
1551 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
1552 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
1553 3) AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #up when the widget already exists upserts the widget definitions and raises no error
1554 Failure/Error: work_item_type_id: type.id,
1555 NoMethodError:
1556 undefined method `id' for nil:NilClass
1557 Shared Example Group: "migration that adds widget to work items definitions" called from ./spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb:7
1558 # ./spec/support/shared_examples/migrations/add_work_item_widget_shared_examples.rb:43:in `block (5 levels) in <top (required)>'
1559 # ./spec/support/shared_examples/migrations/add_work_item_widget_shared_examples.rb:39:in `each'
1560 # ./spec/support/shared_examples/migrations/add_work_item_widget_shared_examples.rb:39:in `block (4 levels) in <top (required)>'
1561 # ./spec/spec_helper.rb:511:in `block (3 levels) in <top (required)>'
1562 # ./spec/spec_helper.rb:448:in `block (3 levels) in <top (required)>'
1563 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
1564 # ./spec/spec_helper.rb:447:in `block (2 levels) in <top (required)>'
1565 # ./spec/spec_helper.rb:442:in `block (3 levels) in <top (required)>'
1566 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
1567 # ./spec/spec_helper.rb:433:in `block (2 levels) in <top (required)>'
1568 # ./spec/spec_helper.rb:429:in `block (3 levels) in <top (required)>'
1569 # ./lib/gitlab/application_context.rb:70:in `with_raw_context'
1570 # ./spec/spec_helper.rb:429:in `block (2 levels) in <top (required)>'
1571 # ./spec/spec_helper.rb:261:in `block (2 levels) in <top (required)>'
1572 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
1573 # ./spec/support/migration.rb:43:in `block (2 levels) in <top (required)>'
1574 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
1575 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
1576 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
1577 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
1578 4) AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #down removes definitions for widget
1579 Failure/Error: expect { migration.down }.to change { work_item_definitions.count }.by(-work_item_type_count)
1580 expected `work_item_definitions.count` to have changed by -3, but was changed by 0
1581 Shared Example Group: "migration that adds widget to work items definitions" called from ./spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb:7
1582 # ./spec/support/shared_examples/migrations/add_work_item_widget_shared_examples.rb:61:in `block (3 levels) in <top (required)>'
1583 # ./spec/spec_helper.rb:511:in `block (3 levels) in <top (required)>'
1584 # ./spec/spec_helper.rb:448:in `block (3 levels) in <top (required)>'
1585 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
1586 # ./spec/spec_helper.rb:447:in `block (2 levels) in <top (required)>'
1587 # ./spec/spec_helper.rb:442:in `block (3 levels) in <top (required)>'
1588 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
1589 # ./spec/spec_helper.rb:433:in `block (2 levels) in <top (required)>'
1590 # ./spec/spec_helper.rb:429:in `block (3 levels) in <top (required)>'
1591 # ./lib/gitlab/application_context.rb:70:in `with_raw_context'
1592 # ./spec/spec_helper.rb:429:in `block (2 levels) in <top (required)>'
1593 # ./spec/spec_helper.rb:261:in `block (2 levels) in <top (required)>'
1594 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
1595 # ./spec/support/migration.rb:43:in `block (2 levels) in <top (required)>'
1596 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
1597 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
1598 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
1599 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
1600Finished in 24 minutes 19 seconds (files took 44.01 seconds to load)
160123 examples, 4 failures
1602Failed examples:
1603rspec './spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb[1:1:1:1]' # AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #up creates widget definition in all types
1604rspec './spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb[1:1:1:2]' # AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #up logs a warning if the type is missing
1605rspec './spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb[1:1:1:3:1]' # AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #up when the widget already exists upserts the widget definitions and raises no error
1606rspec './spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb[1:1:2:1]' # AddDevelopmentWidgetToWorkItemTypes behaves like migration that adds widget to work items definitions #down removes definitions for widget
1607Randomized with seed 6914
1608Stopped processing SimpleCov as a previous error not related to SimpleCov has been detected
1609[TEST PROF INFO] Time spent in factories: 00:00.188 (0.01% of total time)
1610RSpec exited with 1.
1611[job-metrics] Updating job metrics tag for the CI/CD job.
1612RSPEC_RETRIED_TESTS_REPORT_PATH: rspec/retried_tests-6975007381.txt
1613Retrying the failing examples in a new RSpec process...
1614$ gem install junit_merge --no-document --version 0.1.2
1615Successfully installed nokogiri-1.16.5-x86_64-linux
1616Successfully installed junit_merge-0.1.2
16172 gems installed
1618==> 'gem install junit_merge --no-document --version 0.1.2' succeeded in 1 seconds.
1619Running RSpec command: bin/rspec -Ispec -rspec_helper --color --failure-exit-code 1 --error-exit-code 2 --format documentation --format Support::Formatters::JsonFormatter --out rspec/rspec-retry-6975007381.json --format RspecJunitFormatter --out rspec/rspec-retry-6975007381.xml --only-failures --pattern "spec/{migrations}{,/**/}*_spec.rb"
1620warning: parser/current is loading parser/ruby32, which recognizes 3.2.3-compliant syntax, but you are running 3.2.4.
1621Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
1622Run options: include {:last_run_status=>"failed"}
1623# [RSpecRunTime] Starting RSpec timer...
1624[TEST PROF INFO] EventProf enabled (sql.active_record)
1625unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
1626unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
1627Test environment set up in 0.523685282 seconds
1628# [RSpecRunTime] Starting example group spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb. Expected to take 1 minute 2.62 seconds.
1629AddDevelopmentWidgetToWorkItemTypes
1630 behaves like migration that adds widget to work items definitions
1631 #up
1632main: == [advisory_lock_connection] object_id: 8565080, pg_backend_pid: 1787
1633main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1634main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrated (0.0349s) =====
1635main: == [advisory_lock_connection] object_id: 8565080, pg_backend_pid: 1787
1636 creates widget definition in all types
1637main: == [advisory_lock_connection] object_id: 10051920, pg_backend_pid: 1789
1638main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1639main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrated (0.0369s) =====
1640main: == [advisory_lock_connection] object_id: 10051920, pg_backend_pid: 1789
1641 logs a warning if the type is missing
1642 when the widget already exists
1643main: == [advisory_lock_connection] object_id: 10801460, pg_backend_pid: 1791
1644main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1645main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrated (0.0246s) =====
1646main: == [advisory_lock_connection] object_id: 10801460, pg_backend_pid: 1791
1647 upserts the widget definitions and raises no error
1648 #down
1649main: == [advisory_lock_connection] object_id: 11305540, pg_backend_pid: 1794
1650main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrating ==============
1651main: == 20240327214545 AddDevelopmentWidgetToWorkItemTypes: migrated (0.0510s) =====
1652main: == [advisory_lock_connection] object_id: 11305540, pg_backend_pid: 1794
1653 removes definitions for widget
1654# [RSpecRunTime] RSpec elapsed time: 45.69 seconds. Current RSS: ~1130M. Threads: 2. load average: 1.09 1.01 1.01 1/291 482
1655.
1656I, [2024-05-29T23:58:10.320515 #433] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1657# [RSpecRunTime] Finishing example group spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb. It took 1 minute 7.51 seconds. Expected to take 1 minute 2.62 seconds.
1658# [RSpecRunTime] RSpec elapsed time: 1 minute 10.65 seconds. Current RSS: ~1144M. Threads: 2. load average: 1.00 0.99 1.00 1/289 483
1659.
1660auto_explain log contains 925 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-8-15.433.main.ndjson.gz
1661took 24.082837862
1662auto_explain log contains 925 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-8-15.433.ci.ndjson.gz
1663took 24.279935519
1664[TEST PROF INFO] EventProf results for sql.active_record
1665Total time: 00:41.194 of 01:07.548 (60.98%)
1666Total events: 44515
1667Top 5 slowest suites (by time):
1668AddDevelopmen...oWorkItemTypes (./spec/migrations/20240327214545_add_development_widget_to_work_item_types_spec.rb:6) – 00:41.194 (44515 / 4) of 01:07.548 (60.98%)
1669Finished in 1 minute 59.06 seconds (files took 45.43 seconds to load)
16704 examples, 0 failures
1671Randomized with seed 53200
1672[TEST PROF INFO] Time spent in factories: 00:00.022 (0.02% of total time)
1673Loading rspec/rspec-6975007381.json...
1674Merged rspec/rspec-retry-6975007381.json adding 5 results.
1675Saved rspec/rspec-6975007381.json.
1676A test was flaky and succeeded after being retried. Checking to see if flaky test is part of this MR...
1677Flaky test was not part of this MR.
1678 Running after_script 00:05
1679Running after script...
1680$ source scripts/utils.sh
1681$ bundle exec gem list gitlab_quality-test_tooling
1682gitlab_quality-test_tooling (1.26.0)
1683$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
1684 Report test failures 00:00
1686$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
1687 Report test flakiness 00:00
1689$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
1690 Report test slowness 00:00
1692$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
1693 Report test slowness in MR note 00:01
1697$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
1698 Report test files close to timing out 00:01
1701$ tooling/bin/push_job_metrics || true
1702[job-metrics] Pushing job metrics file for the CI/CD job.
1703[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
1704 Saving cache for successful job 00:01
1705Not uploading cache ruby-gems-debian-bookworm-ruby-3.2-17 due to policy
1706 Uploading artifacts for successful job 00:04
1707Uploading artifacts...
1708auto_explain/: found 5 matching artifact files and directories
1709coverage/: found 4 matching artifact files and directories
1710crystalball/: found 2 matching artifact files and directories
1711WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
1712knapsack/: found 4 matching artifact files and directories
1713rspec/: found 19 matching artifact files and directories
1714WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
1715log/*.log: found 13 matching artifact files and directories
1716WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6975007381/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
1717WARNING: Retrying... context=artifacts-uploader error=request redirected
1718Uploading artifacts as "archive" to coordinator... 201 Created id=6975007381 responseStatus=201 Created token=glcbt-65
1719Uploading artifacts...
1720rspec/rspec-*.xml: found 2 matching artifact files and directories
1721WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6975007381/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
1722WARNING: Retrying... context=artifacts-uploader error=request redirected
1723Uploading artifacts as "junit" to coordinator... 201 Created id=6975007381 responseStatus=201 Created token=glcbt-65
1724 Cleaning up project directory and file based variables 00:01
1725Job succeeded