Skip to content
Snippets Groups Projects

rspec migration pg14 single-db-ci-connection 4/15

Passed Started by
Zakir Dzhamaliddinov
@zzaakiirr
1Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
2 on green-1.shared-gitlab-org.runners-manager.gitlab.com/default Mf8beF5G, system ID: s_673deea48c2a
3 feature flags: FF_NETWORK_PER_BUILD:true
4Resolving secrets
5 Preparing the "docker+machine" executor 00:09
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:01
25Running on runner-mf8bef5g-project-41372369-concurrent-0 via runner-mf8bef5g-shared-gitlab-org-1716992570-59b1c937...
26 Getting source from Git repository 00:00
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
30 Restoring cache 00:01
31Checking cache for ruby-gems-debian-bookworm-ruby-3.2...
32WARNING: file does not exist
33Failed to extract cache
34 Downloading artifacts 00:39
35Downloading artifacts for clone-gitlab-repo (6972242766)...
36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6972242766 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 (6972242834)...
40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6972242834 responseStatus=200 OK token=glcbt-65
41Downloading artifacts for detect-tests (6972243022)...
42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6972243022 responseStatus=200 OK token=glcbt-65
43Downloading artifacts for retrieve-tests-metadata (6972243034)...
44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6972243034 responseStatus=200 OK token=glcbt-65
45Downloading artifacts for setup-test-env (6972242864)...
46Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6972242864 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 05:32
1453Using two connections, single database config (config/database.yml.postgresql)
1454Geo DB won't be set up.
1455Embedding DB won't be set up.
1456 Setting up DBs 00:48
1467$ source ./scripts/rspec_helpers.sh
1468$ run_timed_command "gem install knapsack --no-document"
1469$ gem install knapsack --no-document
1470Successfully installed knapsack-4.0.0
14711 gem installed
1472==> 'gem install knapsack --no-document' succeeded in 0 seconds.
1473$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
1474 Spawning Gitaly 00:04
1478$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
1479$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
1480$ tooling/bin/create_job_metrics_file || true
1481[job-metrics] Feature disabled because CI_JOB_METRICS_ENABLED is not set to true.
1482$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
1483 RSpec 30:44
1484[16:43:44] Starting rspec_parallelized_job
1485RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
1486KNAPSACK_GENERATE_REPORT: true
1487FLAKY_RSPEC_GENERATE_REPORT: true
1488KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
1489KNAPSACK_LOG_LEVEL: debug
1490KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_single-db-ci-connection_4_15_41372369_report.json
1491FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
1492FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_single-db-ci-connection_4_15_41372369_report.json
1493NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_single-db-ci-connection_4_15_41372369_report.json
1494RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6972243326.txt
1495CRYSTALBALL:
1496RSPEC_TESTS_MAPPING_ENABLED:
1497RSPEC_TESTS_FILTER_FILE:
1498Shell set options (set -o) enabled:
1499braceexpand on
1500hashall on
1501interactive-comments on
1502pipefail on
1503Knapsack report generator started!
1504warning: parser/current is loading parser/ruby32, which recognizes 3.2.3-compliant syntax, but you are running 3.2.4.
1505Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
1506Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
1507# [RSpecRunTime] Starting RSpec timer...
1508[TEST PROF INFO] EventProf enabled (sql.active_record)
1509unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
1510Test environment set up in 0.678618071 seconds
1511# [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 42.96 seconds.
1512SwapSystemNoteMetadataNoteIdToBigintForSelfManaged
1513 #up
1514 when GitLab.com, dev, or test
1515 behaves like column `note_id_convert_to_bigint` is already dropped
1516 does not swap the columns
1517 when self-managed instance with the `note_id_convert_to_bigint` column already dropped
1518 behaves like column `note_id_convert_to_bigint` is already dropped
1519 does not swap the columns
1520# [RSpecRunTime] RSpec elapsed time: 1 minute 5.74 seconds. Current RSS: ~1091M. load average: 1.12 1.25 0.76 2/264 51885
1521 when self-managed instance columns already swapped
1522 does not swaps the columns
1523# [RSpecRunTime] RSpec elapsed time: 1 minute 9.41 seconds. Current RSS: ~1088M. load average: 1.11 1.25 0.76 1/264 51886
1524 when self-managed instance
1525 swaps the columns
1526# [RSpecRunTime] RSpec elapsed time: 1 minute 13.6 seconds. Current RSS: ~1084M. load average: 1.10 1.25 0.76 1/264 51887
1527INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1528INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1529INFO: "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
1530INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1531INFO: "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
1532INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1533INFO: "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
1534INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1535INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1536INFO: "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
1537INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1538INFO: "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
1539INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1540INFO: "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
1541INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1542INFO: analyzing "public.ci_builds_metadata"
1543INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1544INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1545INFO: "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
1546INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1547INFO: "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
1548INFO: analyzing "public.p_ci_builds" inheritance tree
1549INFO: analyzing "public.ci_builds"
1550INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1551INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1552INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1553INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1554INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1555INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1556INFO: analyzing "public.ci_pipeline_variables"
1557INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1558INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1559INFO: analyzing "public.ci_job_artifacts"
1560INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
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
1564INFO: analyzing "public.p_ci_stages" inheritance tree
1565INFO: analyzing "public.ci_stages"
1566INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1567# [RSpecRunTime] Finishing example group spec/migrations/20230809174702_swap_system_note_metadata_note_id_to_bigint_for_self_managed_spec.rb. It took 2 minutes 12.28 seconds. Expected to take 3 minutes 42.96 seconds.
1568# [RSpecRunTime] RSpec elapsed time: 2 minutes 16.11 seconds. Current RSS: ~1101M. load average: 1.03 1.20 0.78 1/266 51888
1569# [RSpecRunTime] Starting example group spec/migrations/20230803125434_add_has_merge_request_on_vulnerability_reads_trigger_spec.rb. Expected to take 2 minutes 31.85 seconds.
1570AddHasMergeRequestOnVulnerabilityReadsTrigger
1571 #up
1572 INSERT trigger
1573main: == [advisory_lock_connection] object_id: 48405700, pg_backend_pid: 150
1574main: == 20230803125434 AddHasMergeRequestOnVulnerabilityReadsTrigger: migrating ====
1575main: -- execute("CREATE OR REPLACE FUNCTION set_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER AS\n$$\nBEGIN\nUPDATE\n vulnerability_reads\nSET\n has_merge_request = true\nWHERE\n vulnerability_id = NEW.vulnerability_id AND has_merge_request IS FALSE;\nRETURN NULL;\n\nEND\n$$ LANGUAGE PLPGSQL\n")
1576main: -> 0.0015s
1577main: -- execute("CREATE OR REPLACE FUNCTION unset_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER\nLANGUAGE plpgsql\nAS $$\nDECLARE\n has_merge_request_links integer;\nBEGIN\n PERFORM 1\n FROM\n vulnerability_reads\n WHERE\n vulnerability_id = OLD.vulnerability_id\n FOR UPDATE;\n\n SELECT 1 INTO has_merge_request_links FROM vulnerability_merge_request_links WHERE vulnerability_id = OLD.vulnerability_id LIMIT 1;\n\n IF (has_merge_request_links = 1) THEN\n RETURN NULL;\n END IF;\n\n UPDATE\n vulnerability_reads\n SET\n has_merge_request = false\n WHERE\n vulnerability_id = OLD.vulnerability_id;\n\n RETURN NULL;\nEND\n$$;\n")
1578main: -> 0.0014s
1579main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_update\nAFTER INSERT ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION set_has_merge_request_on_vulnerability_reads();\n")
1580main: -> 0.0013s
1581main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_delete\nAFTER DELETE ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION unset_has_merge_request_on_vulnerability_reads();\n")
1582main: -> 0.0012s
1583main: == 20230803125434 AddHasMergeRequestOnVulnerabilityReadsTrigger: migrated (0.0151s)
1584main: == [advisory_lock_connection] object_id: 48405700, pg_backend_pid: 150
1585 updates has_merge_request in vulnerability_reads
1586# [RSpecRunTime] RSpec elapsed time: 3 minutes 16.06 seconds. Current RSS: ~1108M. load average: 1.04 1.16 0.80 1/267 51890
1587 DELETE trigger
1588main: == [advisory_lock_connection] object_id: 49151980, pg_backend_pid: 152
1589main: == 20230803125434 AddHasMergeRequestOnVulnerabilityReadsTrigger: migrating ====
1590main: -- execute("CREATE OR REPLACE FUNCTION set_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER AS\n$$\nBEGIN\nUPDATE\n vulnerability_reads\nSET\n has_merge_request = true\nWHERE\n vulnerability_id = NEW.vulnerability_id AND has_merge_request IS FALSE;\nRETURN NULL;\n\nEND\n$$ LANGUAGE PLPGSQL\n")
1591main: -> 0.0015s
1592main: -- execute("CREATE OR REPLACE FUNCTION unset_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER\nLANGUAGE plpgsql\nAS $$\nDECLARE\n has_merge_request_links integer;\nBEGIN\n PERFORM 1\n FROM\n vulnerability_reads\n WHERE\n vulnerability_id = OLD.vulnerability_id\n FOR UPDATE;\n\n SELECT 1 INTO has_merge_request_links FROM vulnerability_merge_request_links WHERE vulnerability_id = OLD.vulnerability_id LIMIT 1;\n\n IF (has_merge_request_links = 1) THEN\n RETURN NULL;\n END IF;\n\n UPDATE\n vulnerability_reads\n SET\n has_merge_request = false\n WHERE\n vulnerability_id = OLD.vulnerability_id;\n\n RETURN NULL;\nEND\n$$;\n")
1593main: -> 0.0013s
1594main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_update\nAFTER INSERT ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION set_has_merge_request_on_vulnerability_reads();\n")
1595main: -> 0.0014s
1596main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_delete\nAFTER DELETE ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION unset_has_merge_request_on_vulnerability_reads();\n")
1597main: -> 0.0012s
1598main: == 20230803125434 AddHasMergeRequestOnVulnerabilityReadsTrigger: migrated (0.0153s)
1599main: == [advisory_lock_connection] object_id: 49151980, pg_backend_pid: 152
1600 does not change has_merge_request when there exists another merge_request
1601main: == [advisory_lock_connection] object_id: 49810060, pg_backend_pid: 154
1602main: == 20230803125434 AddHasMergeRequestOnVulnerabilityReadsTrigger: migrating ====
1603main: -- execute("CREATE OR REPLACE FUNCTION set_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER AS\n$$\nBEGIN\nUPDATE\n vulnerability_reads\nSET\n has_merge_request = true\nWHERE\n vulnerability_id = NEW.vulnerability_id AND has_merge_request IS FALSE;\nRETURN NULL;\n\nEND\n$$ LANGUAGE PLPGSQL\n")
1604main: -> 0.0012s
1605main: -- execute("CREATE OR REPLACE FUNCTION unset_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER\nLANGUAGE plpgsql\nAS $$\nDECLARE\n has_merge_request_links integer;\nBEGIN\n PERFORM 1\n FROM\n vulnerability_reads\n WHERE\n vulnerability_id = OLD.vulnerability_id\n FOR UPDATE;\n\n SELECT 1 INTO has_merge_request_links FROM vulnerability_merge_request_links WHERE vulnerability_id = OLD.vulnerability_id LIMIT 1;\n\n IF (has_merge_request_links = 1) THEN\n RETURN NULL;\n END IF;\n\n UPDATE\n vulnerability_reads\n SET\n has_merge_request = false\n WHERE\n vulnerability_id = OLD.vulnerability_id;\n\n RETURN NULL;\nEND\n$$;\n")
1606main: -> 0.0012s
1607main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_update\nAFTER INSERT ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION set_has_merge_request_on_vulnerability_reads();\n")
1608main: -> 0.0012s
1609main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_delete\nAFTER DELETE ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION unset_has_merge_request_on_vulnerability_reads();\n")
1610main: -> 0.0011s
1611main: == 20230803125434 AddHasMergeRequestOnVulnerabilityReadsTrigger: migrated (0.0134s)
1612main: == [advisory_lock_connection] object_id: 49810060, pg_backend_pid: 154
1613 unsets has_merge_request when all merge_requests are deleted
1614# [RSpecRunTime] RSpec elapsed time: 3 minutes 23.94 seconds. Current RSS: ~1093M. load average: 1.03 1.16 0.80 1/267 51891
1615 #down
1616main: -- execute("CREATE OR REPLACE FUNCTION set_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER AS\n$$\nBEGIN\nUPDATE\n vulnerability_reads\nSET\n has_merge_request = true\nWHERE\n vulnerability_id = NEW.vulnerability_id AND has_merge_request IS FALSE;\nRETURN NULL;\n\nEND\n$$ LANGUAGE PLPGSQL\n")
1617main: -> 0.0016s
1618main: -- execute("CREATE OR REPLACE FUNCTION unset_has_merge_request_on_vulnerability_reads()\nRETURNS TRIGGER\nLANGUAGE plpgsql\nAS $$\nDECLARE\n has_merge_request_links integer;\nBEGIN\n PERFORM 1\n FROM\n vulnerability_reads\n WHERE\n vulnerability_id = OLD.vulnerability_id\n FOR UPDATE;\n\n SELECT 1 INTO has_merge_request_links FROM vulnerability_merge_request_links WHERE vulnerability_id = OLD.vulnerability_id LIMIT 1;\n\n IF (has_merge_request_links = 1) THEN\n RETURN NULL;\n END IF;\n\n UPDATE\n vulnerability_reads\n SET\n has_merge_request = false\n WHERE\n vulnerability_id = OLD.vulnerability_id;\n\n RETURN NULL;\nEND\n$$;\n")
1619main: -> 0.0017s
1620main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_update\nAFTER INSERT ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION set_has_merge_request_on_vulnerability_reads();\n")
1621main: -> 0.0014s
1622main: -- execute("CREATE TRIGGER trigger_update_has_merge_request_on_vulnerability_mr_links_delete\nAFTER DELETE ON vulnerability_merge_request_links\nFOR EACH ROW\nEXECUTE FUNCTION unset_has_merge_request_on_vulnerability_reads();\n")
1623main: -> 0.0014s
1624main: -- execute("DROP TRIGGER IF EXISTS trigger_update_has_merge_request_on_vulnerability_mr_links_update ON vulnerability_merge_request_links")
1625main: -> 0.0013s
1626main: -- execute("DROP FUNCTION IF EXISTS set_has_merge_request_on_vulnerability_reads()")
1627main: -> 0.0095s
1628main: -- execute("DROP TRIGGER IF EXISTS trigger_update_has_merge_request_on_vulnerability_mr_links_delete ON vulnerability_merge_request_links")
1629main: -> 0.0017s
1630main: -- execute("DROP FUNCTION IF EXISTS unset_has_merge_request_on_vulnerability_reads()")
1631main: -> 0.0014s
1632 drops the trigger
1633# [RSpecRunTime] RSpec elapsed time: 3 minutes 27.87 seconds. Current RSS: ~1087M. load average: 1.03 1.16 0.80 1/267 51892
1634INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1635INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1636INFO: "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
1637INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1638INFO: "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
1639INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1640INFO: "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
1641INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1642INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1643INFO: "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
1644INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1645INFO: "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
1646INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1647INFO: "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
1648INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1649INFO: analyzing "public.ci_builds_metadata"
1650INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1651INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1652INFO: "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
1653INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1654INFO: "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
1655INFO: analyzing "public.p_ci_builds" inheritance tree
1656INFO: analyzing "public.ci_builds"
1657INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1658INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1659INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1660INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1661INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1662INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1663INFO: analyzing "public.ci_pipeline_variables"
1664INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1665INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1666INFO: analyzing "public.ci_job_artifacts"
1667INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1668INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1669INFO: analyzing "public.ci_pipeline_variables"
1670INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1671INFO: analyzing "public.p_ci_stages" inheritance tree
1672INFO: analyzing "public.ci_stages"
1673INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1674# [RSpecRunTime] Finishing example group spec/migrations/20230803125434_add_has_merge_request_on_vulnerability_reads_trigger_spec.rb. It took 2 minutes 17.74 seconds. Expected to take 2 minutes 31.85 seconds.
1675# [RSpecRunTime] RSpec elapsed time: 4 minutes 33.89 seconds. Current RSS: ~1106M. load average: 1.01 1.12 0.82 1/267 51893
1676# [RSpecRunTime] Starting example group spec/migrations/20230612232000_queue_backfill_dismissal_reason_in_vulnerability_reads_spec.rb. Expected to take 2 minutes 2.78 seconds.
1677QueueBackfillDismissalReasonInVulnerabilityReads
1678main: == [advisory_lock_connection] object_id: 83808780, pg_backend_pid: 169
1679main: == 20230612232000 QueueBackfillDismissalReasonInVulnerabilityReads: migrating =
1680main: == 20230612232000 QueueBackfillDismissalReasonInVulnerabilityReads: migrated (0.0609s)
1681main: == [advisory_lock_connection] object_id: 83808780, pg_backend_pid: 169
1682 schedules a new batched migration
1683INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1684INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1685INFO: "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
1686INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1687INFO: "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
1688INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1689INFO: "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
1690INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1691INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1692INFO: "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
1693INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1694INFO: "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
1695INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1696INFO: "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
1697INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1698INFO: analyzing "public.ci_builds_metadata"
1699INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1700INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1701INFO: "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
1702INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1703INFO: "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
1704INFO: analyzing "public.p_ci_builds" inheritance tree
1705INFO: analyzing "public.ci_builds"
1706INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1707INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1708INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1709INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1710INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1711INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1712INFO: analyzing "public.ci_pipeline_variables"
1713INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1714INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1715INFO: analyzing "public.ci_job_artifacts"
1716INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1717INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1718INFO: analyzing "public.ci_pipeline_variables"
1719INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1720INFO: analyzing "public.p_ci_stages" inheritance tree
1721INFO: analyzing "public.ci_stages"
1722INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1723# [RSpecRunTime] Finishing example group spec/migrations/20230612232000_queue_backfill_dismissal_reason_in_vulnerability_reads_spec.rb. It took 2 minutes 37.5 seconds. Expected to take 2 minutes 2.78 seconds.
1724# [RSpecRunTime] RSpec elapsed time: 7 minutes 11.43 seconds. Current RSS: ~1108M. load average: 0.98 1.11 0.87 1/265 51894
1725# [RSpecRunTime] Starting example group spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb. Expected to take 1 minute 49.29 seconds.
1726QueueFixAllowDescendantsOverrideDisabledSharedRunners
1727main: == [advisory_lock_connection] object_id: 118726200, pg_backend_pid: 184
1728main: == 20230802085923 QueueFixAllowDescendantsOverrideDisabledSharedRunners: migrating
1729main: == 20230802085923 QueueFixAllowDescendantsOverrideDisabledSharedRunners: migrated (0.0665s)
1730main: == [advisory_lock_connection] object_id: 118726200, pg_backend_pid: 184
1731 schedules a new batched migration
1732INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1733INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1734INFO: "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
1735INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1736INFO: "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
1737INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1738INFO: "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
1739INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1740INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1741INFO: "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
1742INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1743INFO: "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
1744INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1745INFO: "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
1746INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1747INFO: analyzing "public.ci_builds_metadata"
1748INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1749INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1750INFO: "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
1751INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1752INFO: "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
1753INFO: analyzing "public.p_ci_builds" inheritance tree
1754INFO: analyzing "public.ci_builds"
1755INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1756INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1757INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1758INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1759INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1760INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1761INFO: analyzing "public.ci_pipeline_variables"
1762INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1763INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1764INFO: analyzing "public.ci_job_artifacts"
1765INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1766INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1767INFO: analyzing "public.ci_pipeline_variables"
1768INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1769INFO: analyzing "public.p_ci_stages" inheritance tree
1770INFO: analyzing "public.ci_stages"
1771INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1772# [RSpecRunTime] Finishing example group spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb. It took 2 minutes 9.92 seconds. Expected to take 1 minute 49.29 seconds.
1773# [RSpecRunTime] RSpec elapsed time: 9 minutes 21.39 seconds. Current RSS: ~1109M. load average: 0.90 1.04 0.88 1/266 51895
1774# [RSpecRunTime] Starting example group spec/migrations/20230714015909_add_index_for_member_expiring_query_spec.rb. Expected to take 1 minute 37.09 seconds.
1775AddIndexForMemberExpiringQuery
1776main: -- indexes(:members)
1777main: -> 0.0400s
1778main: -- current_schema(nil)
1779main: -> 0.0011s
1780main: == [advisory_lock_connection] object_id: 151200200, pg_backend_pid: 199
1781main: == 20230714015909 AddIndexForMemberExpiringQuery: migrating ===================
1782main: -- view_exists?(:postgres_partitions)
1783main: -> 0.0020s
1784main: -- index_exists?(:members, [:expires_at, :access_level, :id], {:where=>"requested_at IS NULL AND expiry_notified_at IS NULL", :name=>"index_members_on_expiring_at_access_level_id", :algorithm=>:concurrently})
1785main: -> 0.0281s
1786main: -- add_index(:members, [:expires_at, :access_level, :id], {:where=>"requested_at IS NULL AND expiry_notified_at IS NULL", :name=>"index_members_on_expiring_at_access_level_id", :algorithm=>:concurrently})
1787main: -> 0.0026s
1788main: == 20230714015909 AddIndexForMemberExpiringQuery: migrated (0.0647s) ==========
1789main: == [advisory_lock_connection] object_id: 151200200, pg_backend_pid: 199
1790main: -- indexes(:members)
1791main: -> 0.0300s
1792 correctly migrates up and down
1793INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1794INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1795INFO: "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
1796INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1797INFO: "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
1798INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1799INFO: "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
1800INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1801INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1802INFO: "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
1803INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1804INFO: "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
1805INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1806INFO: "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
1807INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1808INFO: analyzing "public.ci_builds_metadata"
1809INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1810INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1811INFO: "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
1812INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1813INFO: "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
1814INFO: analyzing "public.p_ci_builds" inheritance tree
1815INFO: analyzing "public.ci_builds"
1816INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1817INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1818INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1819INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1820INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1821INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1822INFO: analyzing "public.ci_pipeline_variables"
1823INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1824INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1825INFO: analyzing "public.ci_job_artifacts"
1826INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1827INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1828INFO: analyzing "public.ci_pipeline_variables"
1829INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1830INFO: analyzing "public.p_ci_stages" inheritance tree
1831INFO: analyzing "public.ci_stages"
1832INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1833# [RSpecRunTime] Finishing example group spec/migrations/20230714015909_add_index_for_member_expiring_query_spec.rb. It took 2 minutes 37.64 seconds. Expected to take 1 minute 37.09 seconds.
1834# [RSpecRunTime] RSpec elapsed time: 11 minutes 59.07 seconds. Current RSS: ~1112M. load average: 0.99 1.00 0.91 1/266 51896
1835# [RSpecRunTime] Starting example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. Expected to take 1 minute 30.85 seconds.
1836SwapColumnsForCiStagesPipelineIdBigintV2
1837 when pipeline_id sql type is bigint
1838main: == [advisory_lock_connection] object_id: 183079100, pg_backend_pid: 212
1839main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrating =========
1840main: -- columns(:ci_stages)
1841main: -> 0.0051s
1842main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrated (0.0236s)
1843main: == [advisory_lock_connection] object_id: 183079100, pg_backend_pid: 212
1844 does nothing
1845# [RSpecRunTime] RSpec elapsed time: 12 minutes 52.45 seconds. Current RSS: ~1113M. load average: 1.22 1.06 0.93 1/266 51897
1846 when pipeline_id sql type is integer
1847 behaves like swap conversion columns
1848main: == [advisory_lock_connection] object_id: 183896840, pg_backend_pid: 214
1849main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrating =========
1850main: -- columns(:ci_stages)
1851main: -> 0.0047s
1852main: -- execute("LOCK TABLE ci_pipelines, ci_stages IN ACCESS EXCLUSIVE MODE")
1853main: -> 0.0013s
1854main: -- clear_cache!(nil)
1855main: -> 0.0000s
1856main: -- quote_table_name(:ci_stages)
1857main: -> 0.0000s
1858main: -- quote_column_name(:pipeline_id)
1859main: -> 0.0000s
1860main: -- quote_column_name(:temp_name_for_renaming)
1861main: -> 0.0000s
1862main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"pipeline_id\" TO \"temp_name_for_renaming\"\n")
1863main: -> 0.0012s
1864main: -- clear_cache!(nil)
1865main: -> 0.0000s
1866main: -- quote_table_name(:ci_stages)
1867main: -> 0.0000s
1868main: -- quote_column_name(:pipeline_id_convert_to_bigint)
1869main: -> 0.0000s
1870main: -- quote_column_name(:pipeline_id)
1871main: -> 0.0000s
1872main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"pipeline_id_convert_to_bigint\" TO \"pipeline_id\"\n")
1873main: -> 0.0012s
1874main: -- clear_cache!(nil)
1875main: -> 0.0000s
1876main: -- quote_table_name(:ci_stages)
1877main: -> 0.0000s
1878main: -- quote_column_name(:temp_name_for_renaming)
1879main: -> 0.0000s
1880main: -- quote_column_name(:pipeline_id_convert_to_bigint)
1881main: -> 0.0000s
1882main: -- execute("ALTER TABLE \"ci_stages\"\n RENAME COLUMN \"temp_name_for_renaming\" TO \"pipeline_id_convert_to_bigint\"\n")
1883main: -> 0.0012s
1884main: -- quote_table_name(:trigger_07bc3c48f407)
1885main: -> 0.0000s
1886main: -- execute("ALTER FUNCTION \"trigger_07bc3c48f407\" RESET ALL")
1887main: -> 0.0011s
1888main: -- quote_table_name(:ci_stages)
1889main: -> 0.0000s
1890main: -- quote_column_name(:fk_fb57e6cc56)
1891main: -> 0.0001s
1892main: -- quote_column_name(:temp_name_for_renaming)
1893main: -> 0.0001s
1894main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"fk_fb57e6cc56\" TO \"temp_name_for_renaming\"\n")
1895main: -> 0.0012s
1896main: -- quote_table_name(:ci_stages)
1897main: -> 0.0001s
1898main: -- quote_column_name(:fk_c5ddde695f)
1899main: -> 0.0000s
1900main: -- quote_column_name(:fk_fb57e6cc56)
1901main: -> 0.0000s
1902main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"fk_c5ddde695f\" TO \"fk_fb57e6cc56\"\n")
1903main: -> 0.0012s
1904main: -- quote_table_name(:ci_stages)
1905main: -> 0.0000s
1906main: -- quote_column_name(:temp_name_for_renaming)
1907main: -> 0.0000s
1908main: -- quote_column_name(:fk_c5ddde695f)
1909main: -> 0.0000s
1910main: -- execute("ALTER TABLE \"ci_stages\"\nRENAME CONSTRAINT \"temp_name_for_renaming\" TO \"fk_c5ddde695f\"\n")
1911main: -> 0.0012s
1912main: -- quote_table_name("index_ci_stages_on_pipeline_id")
1913main: -> 0.0000s
1914main: -- quote_table_name("temp_5fd4e9a225")
1915main: -> 0.0000s
1916main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id\" RENAME TO \"temp_5fd4e9a225\"")
1917main: -> 0.0012s
1918main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint")
1919main: -> 0.0000s
1920main: -- quote_table_name(:index_ci_stages_on_pipeline_id)
1921main: -> 0.0000s
1922main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint\" RENAME TO \"index_ci_stages_on_pipeline_id\"")
1923main: -> 0.0010s
1924main: -- quote_table_name("temp_5fd4e9a225")
1925main: -> 0.0001s
1926main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint)
1927main: -> 0.0000s
1928main: -- execute("ALTER INDEX \"temp_5fd4e9a225\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint\"")
1929main: -> 0.0013s
1930main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_id")
1931main: -> 0.0000s
1932main: -- quote_table_name("temp_ced8356685")
1933main: -> 0.0001s
1934main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_id\" RENAME TO \"temp_ced8356685\"")
1935main: -> 0.0011s
1936main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_id")
1937main: -> 0.0000s
1938main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_id)
1939main: -> 0.0000s
1940main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_id\" RENAME TO \"index_ci_stages_on_pipeline_id_and_id\"")
1941main: -> 0.0010s
1942main: -- quote_table_name("temp_ced8356685")
1943main: -> 0.0000s
1944main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_id)
1945main: -> 0.0000s
1946main: -- execute("ALTER INDEX \"temp_ced8356685\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_id\"")
1947main: -> 0.0010s
1948main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_name")
1949main: -> 0.0001s
1950main: -- quote_table_name("temp_348df98ef6")
1951main: -> 0.0000s
1952main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_name\" RENAME TO \"temp_348df98ef6\"")
1953main: -> 0.0011s
1954main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_name")
1955main: -> 0.0000s
1956main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_name)
1957main: -> 0.0000s
1958main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_name\" RENAME TO \"index_ci_stages_on_pipeline_id_and_name\"")
1959main: -> 0.0010s
1960main: -- quote_table_name("temp_348df98ef6")
1961main: -> 0.0000s
1962main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_name)
1963main: -> 0.0000s
1964main: -- execute("ALTER INDEX \"temp_348df98ef6\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_name\"")
1965main: -> 0.0011s
1966main: -- quote_table_name("index_ci_stages_on_pipeline_id_and_position")
1967main: -> 0.0000s
1968main: -- quote_table_name("temp_d0b3b501ce")
1969main: -> 0.0001s
1970main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_and_position\" RENAME TO \"temp_d0b3b501ce\"")
1971main: -> 0.0010s
1972main: -- quote_table_name("index_ci_stages_on_pipeline_id_convert_to_bigint_and_position")
1973main: -> 0.0000s
1974main: -- quote_table_name(:index_ci_stages_on_pipeline_id_and_position)
1975main: -> 0.0000s
1976main: -- execute("ALTER INDEX \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_position\" RENAME TO \"index_ci_stages_on_pipeline_id_and_position\"")
1977main: -> 0.0013s
1978main: -- quote_table_name("temp_d0b3b501ce")
1979main: -> 0.0000s
1980main: -- quote_table_name(:index_ci_stages_on_pipeline_id_convert_to_bigint_and_position)
1981main: -> 0.0000s
1982main: -- execute("ALTER INDEX \"temp_d0b3b501ce\" RENAME TO \"index_ci_stages_on_pipeline_id_convert_to_bigint_and_position\"")
1983main: -> 0.0011s
1984main: == 20231019084731 SwapColumnsForCiStagesPipelineIdBigintV2: migrated (0.0599s)
1985main: == [advisory_lock_connection] object_id: 183896840, pg_backend_pid: 214
1986 correctly swaps conversion columns
1987# [RSpecRunTime] RSpec elapsed time: 12 minutes 56.73 seconds. Current RSS: ~1101M. load average: 1.21 1.06 0.93 1/266 51898
1988INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1989INFO: analyzing "public.ci_pipeline_variables"
1990INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1991INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1992INFO: analyzing "public.ci_job_artifacts"
1993INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1994INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1995INFO: analyzing "public.ci_pipeline_variables"
1996INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1997INFO: analyzing "public.p_ci_stages" inheritance tree
1998INFO: analyzing "public.ci_stages"
1999INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2000# [RSpecRunTime] Finishing example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. It took 1 minute 55.5 seconds. Expected to take 1 minute 30.85 seconds.
2001# [RSpecRunTime] RSpec elapsed time: 13 minutes 54.62 seconds. Current RSS: ~1108M. load average: 1.03 1.04 0.94 1/265 51899
2002# [RSpecRunTime] Starting example group spec/migrations/20231129105945_requeue_backfill_finding_id_in_vulnerabilities3_spec.rb. Expected to take 1 minute 16.49 seconds.
2003RequeueBackfillFindingIdInVulnerabilities3
2004main: == [advisory_lock_connection] object_id: 209878100, pg_backend_pid: 228
2005main: == 20231129105945 RequeueBackfillFindingIdInVulnerabilities3: migrating =======
2006main: == 20231129105945 RequeueBackfillFindingIdInVulnerabilities3: migrated (0.0886s)
2007main: == [advisory_lock_connection] object_id: 209878100, pg_backend_pid: 228
2008 schedules a new batched migration
2009INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2010INFO: analyzing "public.ci_pipeline_variables"
2011INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2012INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
2013INFO: analyzing "public.ci_job_artifacts"
2014INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2015INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2016INFO: analyzing "public.ci_pipeline_variables"
2017INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2018INFO: analyzing "public.p_ci_stages" inheritance tree
2019INFO: analyzing "public.ci_stages"
2020INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2021# [RSpecRunTime] Finishing example group spec/migrations/20231129105945_requeue_backfill_finding_id_in_vulnerabilities3_spec.rb. It took 1 minute 33.25 seconds. Expected to take 1 minute 16.49 seconds.
2022# [RSpecRunTime] RSpec elapsed time: 15 minutes 27.91 seconds. Current RSS: ~1110M. load average: 0.86 1.01 0.94 1/265 51900
2023# [RSpecRunTime] Starting example group spec/migrations/20231221223259_queue_drop_vulnerabilities_without_finding_id_spec.rb. Expected to take 59.63 seconds.
2024QueueDropVulnerabilitiesWithoutFindingId
2025main: == [advisory_lock_connection] object_id: 234389740, pg_backend_pid: 241
2026main: == 20231221223259 QueueDropVulnerabilitiesWithoutFindingId: migrating =========
2027main: == 20231221223259 QueueDropVulnerabilitiesWithoutFindingId: migrated (0.0635s)
2028main: == [advisory_lock_connection] object_id: 234389740, pg_backend_pid: 241
2029 schedules a new batched migration
2030INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2031INFO: analyzing "public.ci_pipeline_variables"
2032INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2033INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
2034INFO: analyzing "public.ci_job_artifacts"
2035INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2036INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2037INFO: analyzing "public.ci_pipeline_variables"
2038INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2039INFO: analyzing "public.p_ci_stages" inheritance tree
2040INFO: analyzing "public.ci_stages"
2041INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2042# [RSpecRunTime] Finishing example group spec/migrations/20231221223259_queue_drop_vulnerabilities_without_finding_id_spec.rb. It took 1 minute 21.9 seconds. Expected to take 59.63 seconds.
2043# [RSpecRunTime] RSpec elapsed time: 16 minutes 49.85 seconds. Current RSS: ~1112M. load average: 0.91 1.00 0.95 1/265 51901
2044# [RSpecRunTime] Starting example group spec/migrations/20240108185335_backfill_internal_ids_with_issues_usage_for_epics_spec.rb. Expected to take 48.48 seconds.
2045BackfillInternalIdsWithIssuesUsageForEpics
2046 #up
2047main: == [advisory_lock_connection] object_id: 257054040, pg_backend_pid: 253
2048main: == 20240108185335 BackfillInternalIdsWithIssuesUsageForEpics: migrating =======
2049main: == 20240108185335 BackfillInternalIdsWithIssuesUsageForEpics: migrated (0.0803s)
2050main: == [advisory_lock_connection] object_id: 257054040, pg_backend_pid: 253
2051 backfills internal_ids for epics as group level issues
2052# [RSpecRunTime] RSpec elapsed time: 17 minutes 27.56 seconds. Current RSS: ~1126M. load average: 1.01 1.02 0.96 1/265 51902
2053INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2054INFO: analyzing "public.ci_pipeline_variables"
2055INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2056INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
2057INFO: analyzing "public.ci_job_artifacts"
2058INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2059INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2060INFO: analyzing "public.ci_pipeline_variables"
2061INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2062INFO: analyzing "public.p_ci_stages" inheritance tree
2063INFO: analyzing "public.ci_stages"
2064INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2065# [RSpecRunTime] Finishing example group spec/migrations/20240108185335_backfill_internal_ids_with_issues_usage_for_epics_spec.rb. It took 1 minute 17.88 seconds. Expected to take 48.48 seconds.
2066# [RSpecRunTime] RSpec elapsed time: 18 minutes 7.77 seconds. Current RSS: ~1113M. load average: 0.92 0.99 0.95 1/266 51903
2067# [RSpecRunTime] Starting example group spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb. Expected to take 32.56 seconds.
2068QueueFixCorruptedScannerIdsOfVulnerabilityReads
2069main: == [advisory_lock_connection] object_id: 274085000, pg_backend_pid: 264
2070main: == 20240409023046 QueueFixCorruptedScannerIdsOfVulnerabilityReads: migrating ==
2071main: == 20240409023046 QueueFixCorruptedScannerIdsOfVulnerabilityReads: migrated (0.0736s)
2072main: == [advisory_lock_connection] object_id: 274085000, pg_backend_pid: 264
2073 schedules a new batched migration
2074# [RSpecRunTime] Finishing example group spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb. It took 36.58 seconds. Expected to take 32.56 seconds.
2075# [RSpecRunTime] RSpec elapsed time: 18 minutes 44.39 seconds. Current RSS: ~1112M. load average: 0.88 0.98 0.95 1/266 51904
2076# [RSpecRunTime] Starting example group spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb. Expected to take 26.49 seconds.
2077QueueBackfillWorkItemParentLinksNamespaceId
2078main: == [advisory_lock_connection] object_id: 283646740, pg_backend_pid: 275
2079main: == 20240419035508 QueueBackfillWorkItemParentLinksNamespaceId: migrating ======
2080main: == 20240419035508 QueueBackfillWorkItemParentLinksNamespaceId: migrated (0.0689s)
2081main: == [advisory_lock_connection] object_id: 283646740, pg_backend_pid: 275
2082 schedules a new batched migration
2083# [RSpecRunTime] Finishing example group spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb. It took 29.29 seconds. Expected to take 26.49 seconds.
2084# [RSpecRunTime] RSpec elapsed time: 19 minutes 13.72 seconds. Current RSS: ~1114M. load average: 0.91 0.98 0.96 1/266 51905
2085# [RSpecRunTime] Starting example group spec/migrations/20230616082958_add_unique_index_for_npm_packages_on_project_id_name_version_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20230616082958_add_unique_index_for_npm_packages_on_project_id_name_version_spec.rb.
2086AddUniqueIndexForNpmPackagesOnProjectIdNameVersion
2087main: == [advisory_lock_connection] object_id: 303932020, pg_backend_pid: 289
2088main: == 20230616082958 AddUniqueIndexForNpmPackagesOnProjectIdNameVersion: migrating
2089main: -- view_exists?(:postgres_partitions)
2090main: -> 0.0022s
2091main: -- index_exists?(:packages_packages, [:project_id, :name, :version], {:name=>"idx_packages_on_project_id_name_version_unique_when_npm", :unique=>true, :where=>"package_type = 2 AND status <> 4", :algorithm=>:concurrently})
2092main: -> 0.0348s
2093main: -- add_index(:packages_packages, [:project_id, :name, :version], {:name=>"idx_packages_on_project_id_name_version_unique_when_npm", :unique=>true, :where=>"package_type = 2 AND status <> 4", :algorithm=>:concurrently})
2094main: -> 0.0028s
2095main: == 20230616082958 AddUniqueIndexForNpmPackagesOnProjectIdNameVersion: migrated (0.0762s)
2096main: == [advisory_lock_connection] object_id: 303932020, pg_backend_pid: 289
2097 schedules an index creation
2098INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
2099INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
2100INFO: "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
2101INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
2102INFO: "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
2103INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
2104INFO: "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
2105INFO: analyzing "public.p_ci_job_annotations" inheritance tree
2106INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
2107INFO: "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
2108INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
2109INFO: "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
2110INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
2111INFO: "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
2112INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
2113INFO: analyzing "public.ci_builds_metadata"
2114INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2115INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
2116INFO: "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
2117INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
2118INFO: "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
2119INFO: analyzing "public.p_ci_builds" inheritance tree
2120INFO: analyzing "public.ci_builds"
2121INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2122INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
2123INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2124INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
2125INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2126INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2127INFO: analyzing "public.ci_pipeline_variables"
2128INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2129INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
2130INFO: analyzing "public.ci_job_artifacts"
2131INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2132INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
2133INFO: analyzing "public.ci_pipeline_variables"
2134INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2135INFO: analyzing "public.p_ci_stages" inheritance tree
2136INFO: analyzing "public.ci_stages"
2137INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
2138# [RSpecRunTime] Finishing example group spec/migrations/20230616082958_add_unique_index_for_npm_packages_on_project_id_name_version_spec.rb. It took 2 minutes 55.83 seconds. Missing expected duration from Knapsack report for spec/migrations/20230616082958_add_unique_index_for_npm_packages_on_project_id_name_version_spec.rb.
2139# [RSpecRunTime] RSpec elapsed time: 22 minutes 9.6 seconds. Current RSS: ~1114M. load average: 0.93 0.98 0.97 1/266 51906
2140auto_explain log contains 909 entries for main, writing to /builds/gitlab-community/gitlab/auto_explain/rspec-migration-pg14-single-db-ci-connection-4-15.51840.main.ndjson.gz
2141took 33.990112815
2142auto_explain log contains 910 entries for ci, writing to /builds/gitlab-community/gitlab/auto_explain/rspec-migration-pg14-single-db-ci-connection-4-15.51840.ci.ndjson.gz
2143took 32.955534677
2144[TEST PROF INFO] EventProf results for sql.active_record
2145Total time: 16:13.431 of 22:05.801 (73.42%)
2146Total events: 676003
2147Top 5 slowest suites (by time):
2148AddUniqueInde...tIdNameVersion (./spec/migrations/20230616082958_add_unique_index_for_npm_packages_on_project_id_name_version_spec.rb:6) – 02:13.082 (75807 / 1) of 02:55.874 (75.67%)
2149AddIndexForMemberExpiringQuery (./spec/migrations/20230714015909_add_index_for_member_expiring_query_spec.rb:6) – 01:59.725 (71297 / 1) of 02:37.680 (75.93%)
2150QueueBackfill...erabilityReads (./spec/migrations/20230612232000_queue_backfill_dismissal_reason_in_vulnerability_reads_spec.rb:6) – 01:59.378 (76505 / 1) of 02:37.538 (75.78%)
2151AddHasMergeRe...tyReadsTrigger (./spec/migrations/20230803125434_add_has_merge_request_on_vulnerability_reads_trigger_spec.rb:7) – 01:38.859 (72717 / 4) of 02:17.780 (71.75%)
2152QueueFixAllow...dSharedRunners (./spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb:6) – 01:36.381 (66469 / 1) of 02:09.960 (74.16%)
2153Knapsack report was generated. Preview:
2154{
2155 "spec/migrations/20230809174702_swap_system_note_metadata_note_id_to_bigint_for_self_managed_spec.rb": 70.13869097099996,
2156 "spec/migrations/20230803125434_add_has_merge_request_on_vulnerability_reads_trigger_spec.rb": 71.85552089799967,
2157 "spec/migrations/20230612232000_queue_backfill_dismissal_reason_in_vulnerability_reads_spec.rb": 73.38309517800008,
2158 "spec/migrations/20230802085923_queue_fix_allow_descendants_override_disabled_shared_runners_spec.rb": 61.05568249499993,
2159 "spec/migrations/20230714015909_add_index_for_member_expiring_query_spec.rb": 73.3966412610007,
2160 "spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb": 57.746115915999326,
2161 "spec/migrations/20231129105945_requeue_backfill_finding_id_in_vulnerabilities3_spec.rb": 46.10979007900096,
2162 "spec/migrations/20231221223259_queue_drop_vulnerabilities_without_finding_id_spec.rb": 39.68085120199976,
2163 "spec/migrations/20240108185335_backfill_internal_ids_with_issues_usage_for_epics_spec.rb": 37.796732048000194,
2164 "spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb": 17.796453658000246,
2165 "spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb": 14.242733828999917,
2166 "spec/migrations/20230616082958_add_unique_index_for_npm_packages_on_project_id_name_version_spec.rb": 83.1344626680002
2167}
2168Knapsack global time execution for tests: 10m 46s
2169Finished in 23 minutes 17 seconds (files took 51.44 seconds to load)
217019 examples, 0 failures
2171Randomized with seed 31609
2172[TEST PROF INFO] Time spent in factories: 00:00.230 (0.02% of total time)
2173RSpec exited with 0.
2174No examples to retry, congrats!
2175 Running after_script
2176Running after script...
2177$ source scripts/utils.sh
2178$ bundle exec gem list gitlab_quality-test_tooling
2179gitlab_quality-test_tooling (1.24.0)
2180$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
2181 Report test failures 00:00
2183$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
2184 Report test flakiness 00:00
2186$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
2187 Report test slowness 00:00
2189$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
2190 Report test slowness in MR note 00:04
2212WARNING: after_script failed, but job will continue unaffected: exit code 1
2213 Saving cache for successful job 00:00
2214Not uploading cache ruby-gems-debian-bookworm-ruby-3.2 due to policy
2215 Uploading artifacts for successful job 00:05
2216Uploading artifacts...
2217auto_explain/: found 3 matching artifact files and directories
2218coverage/: found 5 matching artifact files and directories
2219crystalball/: found 2 matching artifact files and directories
2220WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-community/gitlab)
2221knapsack/: found 4 matching artifact files and directories
2222rspec/: found 16 matching artifact files and directories
2223WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-community/gitlab)
2224log/*.log: found 13 matching artifact files and directories
2225WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6972243326/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
2226WARNING: Retrying... context=artifacts-uploader error=request redirected
2227Uploading artifacts as "archive" to coordinator... 201 Created id=6972243326 responseStatus=201 Created token=glcbt-65
2228Uploading artifacts...
2229rspec/rspec-*.xml: found 1 matching artifact files and directories
2230WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6972243326/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
2231WARNING: Retrying... context=artifacts-uploader error=request redirected
2232Uploading artifacts as "junit" to coordinator... 201 Created id=6972243326 responseStatus=201 Created token=glcbt-65
2233 Cleaning up project directory and file based variables 00:01
2234Job succeeded