rspec-ee migration pg14 single-db 2/2
Passed Started
by
@rkadam3

Rajendra Kadam
1Running with gitlab-runner 16.9.1 (782c6ecb)2 on green-5.private.runners-manager.gitlab.com/gitlab.com/gitlab-org Zi_8oswQ, system ID: s_8a4f217c24b63 feature flags: FF_NETWORK_PER_BUILD:true, FF_USE_IMPROVED_URL_MASKING:true6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 ...7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...8Authenticating with credentials from job payload (GitLab Registry)9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...10Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...14Authenticating with credentials from job payload (GitLab Registry)15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...16Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...17Starting service redis:6.2-alpine ...18Pulling docker image redis:6.2-alpine ...19Using docker image sha256:b6c412dc298714b5c26feca1330d8f743b48caf0b1feeadfd554d7d446f985a5 for redis:6.2-alpine with digest redis@sha256:51d6c56749a4243096327e3fb964a48ed92254357108449cb6e23999c37773c5 ...20WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.7 is already created. Ignoring.21WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.7 is already created. Ignoring.22Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.7 ...23Authenticating with credentials from job payload (GitLab Registry)24Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.7 ...25Using docker image sha256:eee5f6d07e4f2f80f030d31b1c9844c6057211b1a1cad05dff01b339defbce64 for registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.7 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:60d4e4e44378e9c60ceacbe66e86d63ef49f8036d3b20ed60452348c7739ec5e ...26Starting service elasticsearch:7.17.6 ...27Pulling docker image elasticsearch:7.17.6 ...28Using docker image sha256:5fad10241ffd65d817ed0ddfaf6e87eee1f7dc2a7db33db1047835560ea71fda for elasticsearch:7.17.6 with digest elasticsearch@sha256:6c128de5d01c0c130a806022d6bd99b3e4c27a9af5bfc33b6b81861ae117d028 ...29Waiting for services to be up and running (timeout 30 seconds)...30Authenticating with credentials from job payload (GitLab Registry)31Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 ...32Using docker image sha256:fc7435a87025ef82fcd7c3a7df1e52121e8a88ab05430c94ba7ad5920fecc0d0 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14@sha256:c129fa1214137f01be7f1412c5233ac179604d32d9d852cbe06113596fb24504 ...34Running on runner-zi8oswq-project-278964-concurrent-0 via runner-zi8oswq-private-1709554348-840b7016...36Skipping Git repository setup37Skipping Git checkout38Skipping Git submodules setup40Checking cache for ruby-gems-debian-bookworm-ruby-3.1-17...41cache.zip is up to date 42Successfully extracted cache44Downloading artifacts for clone-gitlab-repo (6311410718)...45Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410718 responseStatus=200 OK token=glcbt-6546WARNING: Part of .git directory is on the list of files to extract 47WARNING: This may introduce unexpected problems 48Downloading artifacts for compile-test-assets (6311410765)...49Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410765 responseStatus=200 OK token=glcbt-6550Downloading artifacts for detect-tests (6311410787)...51Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410787 responseStatus=200 OK token=glcbt-6552Downloading artifacts for retrieve-tests-metadata (6311410799)...53Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410799 responseStatus=200 OK token=glcbt-6554Downloading artifacts for setup-test-env (6311410773)...55Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410773 responseStatus=200 OK token=glcbt-6557Using docker image sha256:fc7435a87025ef82fcd7c3a7df1e52121e8a88ab05430c94ba7ad5920fecc0d0 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14@sha256:c129fa1214137f01be7f1412c5233ac179604d32d9d852cbe06113596fb24504 ...58$ echo $FOSS_ONLY59$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb60$ export GOPATH=$CI_PROJECT_DIR/.go61$ mkdir -p $GOPATH62$ source scripts/utils.sh63$ source scripts/prepare_build.sh741Using two connections, single database config (config/database.yml.postgresql)742Disabling ci connection in config/database.yml743Geo DB will be set up.744Embedding DB will be set up.769$ source ./scripts/rspec_helpers.sh770$ run_timed_command "gem install knapsack --no-document"771$ gem install knapsack --no-document772Successfully installed knapsack-4.0.07731 gem installed774==> 'gem install knapsack --no-document' succeeded in 1 seconds.775$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"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 || true783[job-metrics] Creating the job metrics file for the CI/CD job.784$ rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"785[13:31:45] Starting rspec_parallelized_job786RETRY_FAILED_TESTS_IN_NEW_PROCESS: true787KNAPSACK_GENERATE_REPORT: true788FLAKY_RSPEC_GENERATE_REPORT: true789KNAPSACK_TEST_FILE_PATTERN: {ee/}spec/{migrations}{,/**/}*_spec.rb790KNAPSACK_LOG_LEVEL: debug791KNAPSACK_REPORT_PATH: knapsack/rspec-ee_migration_pg14_single-db_2_2_278964_report.json792FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json793FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec-ee_migration_pg14_single-db_2_2_278964_report.json794NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec-ee_migration_pg14_single-db_2_2_278964_report.json795RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6311412148.txt796CRYSTALBALL: 797RSPEC_TESTS_MAPPING_ENABLED: 798RSPEC_TESTS_FILTER_FILE: 799Shell set options (set -o) enabled:800braceexpand on801hashall on802interactive-comments on803pipefail on804Knapsack report generator started!805 ██ ██ █████ ██████ ███ ██ ██ ███ ██ ██████ 806 ██ ██ ██ ██ ██ ██ ████ ██ ██ ████ ██ ██ 807 ██ █ ██ ███████ ██████ ██ ██ ██ ██ ██ ██ ██ ██ ███ 808 ██ ███ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ 809 ███ ███ ██ ██ ██ ██ ██ ████ ██ ██ ████ ██████ 810******************************************************************************811 Your database has a single connection, and single connections were812 deprecated in GitLab 15.9 https://docs.gitlab.com/ee/update/deprecations.html#single-database-connection-is-deprecated.813 Please add a :ci section to your database, following these instructions:815******************************************************************************816 ██ ██ █████ ██████ ███ ██ ██ ███ ██ ██████ 817 ██ ██ ██ ██ ██ ██ ████ ██ ██ ████ ██ ██ 818 ██ █ ██ ███████ ██████ ██ ██ ██ ██ ██ ██ ██ ██ ███ 819 ██ ███ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ ██ 820 ███ ███ ██ ██ ██ ██ ██ ████ ██ ██ ████ ██████ 821******************************************************************************822 Your database has a single connection, and single connections were823 deprecated in GitLab 15.9 https://docs.gitlab.com/ee/update/deprecations.html#single-database-connection-is-deprecated.824 Please add a :ci section to your database, following these instructions:826******************************************************************************827Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}828# [RSpecRunTime] Starting RSpec timer...829[TEST PROF INFO] EventProf enabled (sql.active_record)830unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.831Test environment set up in 3.609053777 seconds832# [RSpecRunTime] Starting example group ee/spec/migrations/cleanup_orphan_software_licenses_spec.rb. Expected to take 1 minute 19.11 seconds.833CleanupOrphanSoftwareLicenses834 #up835 with orphan software licenses836main: == [advisory_lock_connection] object_id: 11233880, pg_backend_pid: 166837main: == 20230313185145 CleanupOrphanSoftwareLicenses: migrating ====================838main: == 20230313185145 CleanupOrphanSoftwareLicenses: migrated (0.0175s) ===========839main: == [advisory_lock_connection] object_id: 11233880, pg_backend_pid: 166840 deletes only orphan software licenses841 without orphan licenses842main: == [advisory_lock_connection] object_id: 12068520, pg_backend_pid: 168843main: == 20230313185145 CleanupOrphanSoftwareLicenses: migrating ====================844main: == 20230313185145 CleanupOrphanSoftwareLicenses: migrated (0.0146s) ===========845main: == [advisory_lock_connection] object_id: 12068520, pg_backend_pid: 168846 does not delete any software license847# [RSpecRunTime] RSpec elapsed time: 1 minute 22.18 seconds. Current RSS: ~1326M. load average: 0.96 1.29 1.27 1/366 407848 with licenses without spdx_identifier that belong to a project849main: == [advisory_lock_connection] object_id: 12568440, pg_backend_pid: 170850main: == 20230313185145 CleanupOrphanSoftwareLicenses: migrating ====================851main: == 20230313185145 CleanupOrphanSoftwareLicenses: migrated (0.0122s) ===========852main: == [advisory_lock_connection] object_id: 12568440, pg_backend_pid: 170853 deletes only software_licenses without spdx_identifier: nil that does not belong to any projects.854# [RSpecRunTime] RSpec elapsed time: 1 minute 25.32 seconds. Current RSS: ~1325M. load average: 0.96 1.29 1.27 1/366 408855INFO: analyzing "public.p_ci_builds" inheritance tree856INFO: analyzing "public.ci_builds"857INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows858INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree859INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"860INFO: "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 rows861INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"862INFO: "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 rows863INFO: analyzing "public.p_ci_job_annotations" inheritance tree864INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"865INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows866INFO: analyzing "public.p_ci_builds_metadata" inheritance tree867INFO: analyzing "public.ci_builds_metadata"868INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows869INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"870INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows871INFO: analyzing "public.p_ci_builds" inheritance tree872INFO: analyzing "public.ci_builds"873INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows874INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree875INFO: analyzing "public.ci_pipeline_variables"876INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows877INFO: analyzing "public.p_ci_job_artifacts" inheritance tree878INFO: analyzing "public.ci_job_artifacts"879INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows880INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree881INFO: analyzing "public.ci_pipeline_variables"882INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows883INFO: analyzing "public.p_ci_stages" inheritance tree884INFO: analyzing "public.ci_stages"885INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows886# [RSpecRunTime] Finishing example group ee/spec/migrations/cleanup_orphan_software_licenses_spec.rb. It took 2 minutes 38.74 seconds. Expected to take 1 minute 19.11 seconds.887# [RSpecRunTime] RSpec elapsed time: 2 minutes 44.36 seconds. Current RSS: ~1318M. load average: 0.97 1.21 1.24 1/367 409888# [RSpecRunTime] Starting example group ee/spec/migrations/20230310213308_sync_security_policy_rule_schedules_that_may_have_been_deleted_by_a_bug_spec.rb. Expected to take 1 minute 9.72 seconds.889SyncSecurityPolicyRuleSchedulesThatMayHaveBeenDeletedByABug890 #up891main: == [advisory_lock_connection] object_id: 40730040, pg_backend_pid: 195892main: == 20230310213308 SyncSecurityPolicyRuleSchedulesThatMayHaveBeenDeletedByABug: migrating 893main: == 20230310213308 SyncSecurityPolicyRuleSchedulesThatMayHaveBeenDeletedByABug: migrated (0.0126s) 894main: == [advisory_lock_connection] object_id: 40730040, pg_backend_pid: 195895 bulk enqueues one SyncScanPoliciesWorker for each unique policy configuration id896# [RSpecRunTime] RSpec elapsed time: 3 minutes 51.08 seconds. Current RSS: ~1331M. load average: 0.98 1.18 1.23 1/367 410897INFO: analyzing "public.p_ci_builds" inheritance tree898INFO: analyzing "public.ci_builds"899INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows900INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree901INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"902INFO: "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 rows903INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"904INFO: "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 rows905INFO: analyzing "public.p_ci_job_annotations" inheritance tree906INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"907INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows908INFO: analyzing "public.p_ci_builds_metadata" inheritance tree909INFO: analyzing "public.ci_builds_metadata"910INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows911INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"912INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows913INFO: analyzing "public.p_ci_builds" inheritance tree914INFO: analyzing "public.ci_builds"915INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows916INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree917INFO: analyzing "public.ci_pipeline_variables"918INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows919INFO: analyzing "public.p_ci_job_artifacts" inheritance tree920INFO: analyzing "public.ci_job_artifacts"921INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows922INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree923INFO: analyzing "public.ci_pipeline_variables"924INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows925INFO: analyzing "public.p_ci_stages" inheritance tree926INFO: analyzing "public.ci_stages"927INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows928# [RSpecRunTime] Finishing example group ee/spec/migrations/20230310213308_sync_security_policy_rule_schedules_that_may_have_been_deleted_by_a_bug_spec.rb. It took 2 minutes 29.87 seconds. Expected to take 1 minute 9.72 seconds.929# [RSpecRunTime] RSpec elapsed time: 5 minutes 14.28 seconds. Current RSS: ~1310M. load average: 0.87 1.07 1.18 1/366 411930# [RSpecRunTime] Starting example group ee/spec/migrations/20230403221928_resync_scan_result_policies_for_namespaces_spec.rb. Expected to take 1 minute 7.49 seconds.931ResyncScanResultPoliciesForNamespaces932 #up933main: == [advisory_lock_connection] object_id: 68788300, pg_backend_pid: 220934main: == 20230403221928 ResyncScanResultPoliciesForNamespaces: migrating ============935main: == 20230403221928 ResyncScanResultPoliciesForNamespaces: migrated (0.0896s) ===936main: == [advisory_lock_connection] object_id: 68788300, pg_backend_pid: 220937 enqueues ProcessScanResultPolicyWorker for each project of policy configuration namespace938# [RSpecRunTime] RSpec elapsed time: 6 minutes 22.81 seconds. Current RSS: ~1326M. load average: 0.96 1.07 1.17 1/366 412939INFO: analyzing "public.p_ci_builds" inheritance tree940INFO: analyzing "public.ci_builds"941INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows942INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree943INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"944INFO: "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 rows945INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"946INFO: "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 rows947INFO: analyzing "public.p_ci_job_annotations" inheritance tree948INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"949INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows950INFO: analyzing "public.p_ci_builds_metadata" inheritance tree951INFO: analyzing "public.ci_builds_metadata"952INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows953INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"954INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows955INFO: analyzing "public.p_ci_builds" inheritance tree956INFO: analyzing "public.ci_builds"957INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows958INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree959INFO: analyzing "public.ci_pipeline_variables"960INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows961INFO: analyzing "public.p_ci_job_artifacts" inheritance tree962INFO: analyzing "public.ci_job_artifacts"963INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows964INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree965INFO: analyzing "public.ci_pipeline_variables"966INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows967INFO: analyzing "public.p_ci_stages" inheritance tree968INFO: analyzing "public.ci_stages"969INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows970# [RSpecRunTime] Finishing example group ee/spec/migrations/20230403221928_resync_scan_result_policies_for_namespaces_spec.rb. It took 2 minutes 28.45 seconds. Expected to take 1 minute 7.49 seconds.971# [RSpecRunTime] RSpec elapsed time: 7 minutes 42.77 seconds. Current RSS: ~1304M. load average: 0.95 1.03 1.15 1/366 413972# [RSpecRunTime] Starting example group ee/spec/migrations/20230901120000_queue_update_workspaces_config_version_spec.rb. Expected to take 31.81 seconds.973QueueUpdateWorkspacesConfigVersion974main: == [advisory_lock_connection] object_id: 90107620, pg_backend_pid: 242975main: == 20230910120000 QueueUpdateWorkspacesConfigVersion: migrating ===============976main: == 20230910120000 QueueUpdateWorkspacesConfigVersion: migrated (0.0569s) ======977main: == [advisory_lock_connection] object_id: 90107620, pg_backend_pid: 242978 schedules a new batched migration979INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree980INFO: analyzing "public.ci_pipeline_variables"981INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows982INFO: analyzing "public.p_ci_job_artifacts" inheritance tree983INFO: analyzing "public.ci_job_artifacts"984INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows985INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree986INFO: analyzing "public.ci_pipeline_variables"987INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows988INFO: analyzing "public.p_ci_stages" inheritance tree989INFO: analyzing "public.ci_stages"990INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows991# [RSpecRunTime] Finishing example group ee/spec/migrations/20230901120000_queue_update_workspaces_config_version_spec.rb. It took 1 minute 5.25 seconds. Expected to take 31.81 seconds.992# [RSpecRunTime] RSpec elapsed time: 8 minutes 48.06 seconds. Current RSS: ~1304M. load average: 0.94 1.01 1.13 1/367 414993# [RSpecRunTime] Starting example group ee/spec/migrations/20230909120000_queue_backfill_workspace_personal_access_token_spec.rb. Expected to take 31.04 seconds.994QueueBackfillWorkspacePersonalAccessToken995main: == [advisory_lock_connection] object_id: 102284360, pg_backend_pid: 262996main: == 20230909120000 QueueBackfillWorkspacePersonalAccessToken: migrating ========997main: == 20230909120000 QueueBackfillWorkspacePersonalAccessToken: migrated (0.0664s) 998main: == [advisory_lock_connection] object_id: 102284360, pg_backend_pid: 262999 schedules a new batched migration1000INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1001INFO: analyzing "public.ci_pipeline_variables"1002INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1003INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1004INFO: analyzing "public.ci_job_artifacts"1005INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1006INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1007INFO: analyzing "public.ci_pipeline_variables"1008INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1009INFO: analyzing "public.p_ci_stages" inheritance tree1010INFO: analyzing "public.ci_stages"1011INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1012# [RSpecRunTime] Finishing example group ee/spec/migrations/20230909120000_queue_backfill_workspace_personal_access_token_spec.rb. It took 1 minute 4.21 seconds. Expected to take 31.04 seconds.1013# [RSpecRunTime] RSpec elapsed time: 9 minutes 52.32 seconds. Current RSS: ~1298M. load average: 0.98 1.00 1.12 1/367 4151014# [RSpecRunTime] Starting example group ee/spec/migrations/geo/resync_direct_upload_job_artifact_registry_spec.rb. Expected to take 29.33 seconds.1015ResyncDirectUploadJobArtifactRegistry1016 #up1017 when direct upload is enabled for job artifacts1018 when the site is a Geo secondary1019 when sync_object_storage is enabled for the current Geo site1020 with job artifact registry rows1021geo: == [advisory_lock_connection] object_id: 109613480, pg_backend_pid: 2851022geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrating ============1023geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker jobs to mark artifacts pending1025geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker for range 1..21026geo: -- Enqueued 1 Geo::ResyncDirectUploadJobArtifactRegistryWorker job1027geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrated (0.1221s) ===1028geo: == [advisory_lock_connection] object_id: 109613480, pg_backend_pid: 2851029 marks pending if synced between 2023-06-22 and 2024-02-031030geo: == [advisory_lock_connection] object_id: 109981960, pg_backend_pid: 2911031geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrating ============1032geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker jobs to mark artifacts pending1034geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker for range 3..41035geo: -- Enqueued 1 Geo::ResyncDirectUploadJobArtifactRegistryWorker job1036geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrated (0.0649s) ===1037geo: == [advisory_lock_connection] object_id: 109981960, pg_backend_pid: 2911038 does not update if not synced1039geo: == [advisory_lock_connection] object_id: 110006020, pg_backend_pid: 2971040geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrating ============1041geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker jobs to mark artifacts pending1043geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker for range 5..51044geo: -- Enqueued 1 Geo::ResyncDirectUploadJobArtifactRegistryWorker job1045geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrated (0.0564s) ===1046geo: == [advisory_lock_connection] object_id: 110006020, pg_backend_pid: 2971047 does not update if synced before 2023-06-221048geo: == [advisory_lock_connection] object_id: 110471560, pg_backend_pid: 3031049geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrating ============1050geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker jobs to mark artifacts pending1052geo: -- Enqueuing Geo::ResyncDirectUploadJobArtifactRegistryWorker for range 6..61053geo: -- Enqueued 1 Geo::ResyncDirectUploadJobArtifactRegistryWorker job1054geo: == 20230808031211 ResyncDirectUploadJobArtifactRegistry: migrated (0.0654s) ===1055geo: == [advisory_lock_connection] object_id: 110471560, pg_backend_pid: 3031056 does not update if synced after 2024-02-031057# [RSpecRunTime] RSpec elapsed time: 10 minutes 2.02 seconds. Current RSS: ~1352M. load average: 1.06 1.02 1.12 1/367 4161058 when sync_object_storage is disabled for the current Geo site1059 does not update job artifact registry1060# [RSpecRunTime] RSpec elapsed time: 10 minutes 4.35 seconds. Current RSS: ~1354M. load average: 1.06 1.02 1.12 1/367 4171061 when the site is a Geo primary1062 does not update job artifact registry1063# [RSpecRunTime] RSpec elapsed time: 10 minutes 6.68 seconds. Current RSS: ~1356M. load average: 1.06 1.02 1.12 1/367 4181064 when direct upload is disabled for job artifacts1065 does not update job artifact registry1066# [RSpecRunTime] RSpec elapsed time: 10 minutes 8.98 seconds. Current RSS: ~1361M. load average: 1.05 1.02 1.12 1/367 4191067 when object storage is disabled for job artifacts1068 does not update job artifact registry1069# [RSpecRunTime] RSpec elapsed time: 10 minutes 11.3 seconds. Current RSS: ~1355M. load average: 1.05 1.02 1.12 1/367 4201070# [RSpecRunTime] Finishing example group ee/spec/migrations/geo/resync_direct_upload_job_artifact_registry_spec.rb. It took 20.96 seconds. Expected to take 29.33 seconds.1071# [RSpecRunTime] RSpec elapsed time: 10 minutes 13.32 seconds. Current RSS: ~1350M. load average: 0.97 1.00 1.12 1/369 4211072# [RSpecRunTime] Starting example group ee/spec/migrations/geo/migrate_lfs_objects_to_separate_registry_spec.rb. Expected to take 18.66 seconds.1073MigrateLfsObjectsToSeparateRegistry1074 #up1075geo: == [advisory_lock_connection] object_id: 113097860, pg_backend_pid: 3511076== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrating ==============1077-- execute("LOCK TABLE file_registry IN EXCLUSIVE MODE")1078 -> 0.0018s1079-- execute("INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\nSELECT created_at, retry_at, file_id, bytes, retry_count, missing_on_primary, success, sha256::bytea\nFROM file_registry WHERE file_type = 'lfs'\n")1080 -> 0.0028s1081-- execute("CREATE OR REPLACE FUNCTION replicate_lfs_object_registry()\nRETURNS trigger AS\n$BODY$\nBEGIN\n IF (TG_OP = 'UPDATE') THEN\n UPDATE lfs_object_registry\n SET (retry_at, bytes, retry_count, missing_on_primary, success, sha256) =\n (NEW.retry_at, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea)\n WHERE lfs_object_id = NEW.file_id;\n ELSEIF (TG_OP = 'INSERT') THEN\n INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\n VALUES (NEW.created_at, NEW.retry_at, NEW.file_id, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea);\nEND IF;\nRETURN NEW;\nEND;\n$BODY$\nLANGUAGE 'plpgsql'\nVOLATILE;\n")1082 -> 0.0023s1083-- execute("CREATE TRIGGER replicate_lfs_object_registry\nAFTER INSERT OR UPDATE ON file_registry\nFOR EACH ROW WHEN (NEW.file_type = 'lfs') EXECUTE PROCEDURE replicate_lfs_object_registry();\n")1084 -> 0.0014s1085== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrated (0.0216s) =====1086geo: == [advisory_lock_connection] object_id: 113097860, pg_backend_pid: 3511087 migrates all file registries for LFS objects to its own data table1088geo: == [advisory_lock_connection] object_id: 113524600, pg_backend_pid: 3581089== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrating ==============1090-- execute("LOCK TABLE file_registry IN EXCLUSIVE MODE")1091 -> 0.0017s1092-- execute("INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\nSELECT created_at, retry_at, file_id, bytes, retry_count, missing_on_primary, success, sha256::bytea\nFROM file_registry WHERE file_type = 'lfs'\n")1093 -> 0.0026s1094-- execute("CREATE OR REPLACE FUNCTION replicate_lfs_object_registry()\nRETURNS trigger AS\n$BODY$\nBEGIN\n IF (TG_OP = 'UPDATE') THEN\n UPDATE lfs_object_registry\n SET (retry_at, bytes, retry_count, missing_on_primary, success, sha256) =\n (NEW.retry_at, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea)\n WHERE lfs_object_id = NEW.file_id;\n ELSEIF (TG_OP = 'INSERT') THEN\n INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\n VALUES (NEW.created_at, NEW.retry_at, NEW.file_id, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea);\nEND IF;\nRETURN NEW;\nEND;\n$BODY$\nLANGUAGE 'plpgsql'\nVOLATILE;\n")1095 -> 0.0021s1096-- execute("CREATE TRIGGER replicate_lfs_object_registry\nAFTER INSERT OR UPDATE ON file_registry\nFOR EACH ROW WHEN (NEW.file_type = 'lfs') EXECUTE PROCEDURE replicate_lfs_object_registry();\n")1097 -> 0.0012s1098== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrated (0.0206s) =====1099geo: == [advisory_lock_connection] object_id: 113524600, pg_backend_pid: 3581100 creates a new lfs object registry with the trigger1101geo: == [advisory_lock_connection] object_id: 113667620, pg_backend_pid: 3641102== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrating ==============1103-- execute("LOCK TABLE file_registry IN EXCLUSIVE MODE")1104 -> 0.0020s1105-- execute("INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\nSELECT created_at, retry_at, file_id, bytes, retry_count, missing_on_primary, success, sha256::bytea\nFROM file_registry WHERE file_type = 'lfs'\n")1106 -> 0.0025s1107-- execute("CREATE OR REPLACE FUNCTION replicate_lfs_object_registry()\nRETURNS trigger AS\n$BODY$\nBEGIN\n IF (TG_OP = 'UPDATE') THEN\n UPDATE lfs_object_registry\n SET (retry_at, bytes, retry_count, missing_on_primary, success, sha256) =\n (NEW.retry_at, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea)\n WHERE lfs_object_id = NEW.file_id;\n ELSEIF (TG_OP = 'INSERT') THEN\n INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\n VALUES (NEW.created_at, NEW.retry_at, NEW.file_id, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea);\nEND IF;\nRETURN NEW;\nEND;\n$BODY$\nLANGUAGE 'plpgsql'\nVOLATILE;\n")1108 -> 0.0022s1109-- execute("CREATE TRIGGER replicate_lfs_object_registry\nAFTER INSERT OR UPDATE ON file_registry\nFOR EACH ROW WHEN (NEW.file_type = 'lfs') EXECUTE PROCEDURE replicate_lfs_object_registry();\n")1110 -> 0.0014s1111== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrated (0.0222s) =====1112geo: == [advisory_lock_connection] object_id: 113667620, pg_backend_pid: 3641113 updates a new lfs object with the trigger1114geo: == [advisory_lock_connection] object_id: 114100020, pg_backend_pid: 3701115== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrating ==============1116-- execute("LOCK TABLE file_registry IN EXCLUSIVE MODE")1117 -> 0.0027s1118-- execute("INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\nSELECT created_at, retry_at, file_id, bytes, retry_count, missing_on_primary, success, sha256::bytea\nFROM file_registry WHERE file_type = 'lfs'\n")1119 -> 0.0029s1120-- execute("CREATE OR REPLACE FUNCTION replicate_lfs_object_registry()\nRETURNS trigger AS\n$BODY$\nBEGIN\n IF (TG_OP = 'UPDATE') THEN\n UPDATE lfs_object_registry\n SET (retry_at, bytes, retry_count, missing_on_primary, success, sha256) =\n (NEW.retry_at, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea)\n WHERE lfs_object_id = NEW.file_id;\n ELSEIF (TG_OP = 'INSERT') THEN\n INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\n VALUES (NEW.created_at, NEW.retry_at, NEW.file_id, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea);\nEND IF;\nRETURN NEW;\nEND;\n$BODY$\nLANGUAGE 'plpgsql'\nVOLATILE;\n")1121 -> 0.0023s1122-- execute("CREATE TRIGGER replicate_lfs_object_registry\nAFTER INSERT OR UPDATE ON file_registry\nFOR EACH ROW WHEN (NEW.file_type = 'lfs') EXECUTE PROCEDURE replicate_lfs_object_registry();\n")1123 -> 0.0013s1124== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrated (0.0262s) =====1125geo: == [advisory_lock_connection] object_id: 114100020, pg_backend_pid: 3701126 creates a new lfs object using the next ID1127# [RSpecRunTime] RSpec elapsed time: 10 minutes 23.62 seconds. Current RSS: ~1343M. load average: 0.89 0.98 1.11 1/369 4221128 #down1129geo: == [advisory_lock_connection] object_id: 114548920, pg_backend_pid: 3761130== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrating ==============1131-- execute("LOCK TABLE file_registry IN EXCLUSIVE MODE")1132 -> 0.0018s1133-- execute("INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\nSELECT created_at, retry_at, file_id, bytes, retry_count, missing_on_primary, success, sha256::bytea\nFROM file_registry WHERE file_type = 'lfs'\n")1134 -> 0.0030s1135-- execute("CREATE OR REPLACE FUNCTION replicate_lfs_object_registry()\nRETURNS trigger AS\n$BODY$\nBEGIN\n IF (TG_OP = 'UPDATE') THEN\n UPDATE lfs_object_registry\n SET (retry_at, bytes, retry_count, missing_on_primary, success, sha256) =\n (NEW.retry_at, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea)\n WHERE lfs_object_id = NEW.file_id;\n ELSEIF (TG_OP = 'INSERT') THEN\n INSERT INTO lfs_object_registry (created_at, retry_at, lfs_object_id, bytes, retry_count, missing_on_primary, success, sha256)\n VALUES (NEW.created_at, NEW.retry_at, NEW.file_id, NEW.bytes, NEW.retry_count, NEW.missing_on_primary, NEW.success, NEW.sha256::bytea);\nEND IF;\nRETURN NEW;\nEND;\n$BODY$\nLANGUAGE 'plpgsql'\nVOLATILE;\n")1136 -> 0.0022s1137-- execute("CREATE TRIGGER replicate_lfs_object_registry\nAFTER INSERT OR UPDATE ON file_registry\nFOR EACH ROW WHEN (NEW.file_type = 'lfs') EXECUTE PROCEDURE replicate_lfs_object_registry();\n")1138 -> 0.0012s1139== 20191010204941 MigrateLfsObjectsToSeparateRegistry: migrated (0.0225s) =====1140geo: == [advisory_lock_connection] object_id: 114548920, pg_backend_pid: 3761141 rolls back data properly1142# [RSpecRunTime] RSpec elapsed time: 10 minutes 26.13 seconds. Current RSS: ~1342M. load average: 0.89 0.98 1.11 1/369 4231143# [RSpecRunTime] Finishing example group ee/spec/migrations/geo/migrate_lfs_objects_to_separate_registry_spec.rb. It took 15.68 seconds. Expected to take 18.66 seconds.1144# [RSpecRunTime] RSpec elapsed time: 10 minutes 29.06 seconds. Current RSS: ~1342M. load average: 0.90 0.98 1.11 1/369 4241145# [RSpecRunTime] Starting example group ee/spec/migrations/20240104085448_queue_update_workspaces_config_version3_spec.rb. Expected to take 14.22 seconds.1146QueueUpdateWorkspacesConfigVersion31147main: == [advisory_lock_connection] object_id: 117114860, pg_backend_pid: 4021148main: == 20240104085448 QueueUpdateWorkspacesConfigVersion3: migrating ==============1149main: == 20240104085448 QueueUpdateWorkspacesConfigVersion3: migrated (0.0618s) =====1150main: == [advisory_lock_connection] object_id: 117114860, pg_backend_pid: 4021151 schedules a new batched migration1152INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1153INFO: analyzing "public.ci_pipeline_variables"1154INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1155INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1156INFO: analyzing "public.ci_job_artifacts"1157INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1158INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1159INFO: analyzing "public.ci_pipeline_variables"1160INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1161INFO: analyzing "public.p_ci_stages" inheritance tree1162INFO: analyzing "public.ci_stages"1163INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1164# [RSpecRunTime] Finishing example group ee/spec/migrations/20240104085448_queue_update_workspaces_config_version3_spec.rb. It took 28.21 seconds. Expected to take 14.22 seconds.1165# [RSpecRunTime] RSpec elapsed time: 10 minutes 57.33 seconds. Current RSS: ~1292M. load average: 1.13 1.03 1.12 1/369 4251166auto_explain log contains 932 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-migration-pg14-single-db-2-2.339.main.ndjson.gz1167took 14.7012546891168[TEST PROF INFO] EventProf results for sql.active_record1169Total time: 08:01.526 of 10:51.726 (73.88%)1170Total events: 2861581171Top 5 slowest suites (by time):1172ResyncScanRes...sForNamespaces (./ee/spec/migrations/20230403221928_resync_scan_result_policies_for_namespaces_spec.rb:6) – 01:56.257 (59111 / 1) of 02:28.497 (78.29%)1173SyncSecurityP...nDeletedByABug (./ee/spec/migrations/20230310213308_sync_security_policy_rule_schedules_that_may_have_been_deleted_by_a_bug_spec.rb:6) – 01:54.603 (62856 / 1) of 02:29.911 (76.45%)1174CleanupOrphanSoftwareLicenses (./ee/spec/migrations/cleanup_orphan_software_licenses_spec.rb:6) – 01:54.560 (65530 / 3) of 02:38.767 (72.16%)1175QueueUpdateWo...sConfigVersion (./ee/spec/migrations/20230901120000_queue_update_workspaces_config_version_spec.rb:6) – 00:49.151 (29324 / 1) of 01:05.287 (75.28%)1176QueueBackfill...nalAccessToken (./ee/spec/migrations/20230909120000_queue_backfill_workspace_personal_access_token_spec.rb:6) – 00:48.318 (29348 / 1) of 01:04.253 (75.2%)1177Knapsack report was generated. Preview:1178{1179 "ee/spec/migrations/cleanup_orphan_software_licenses_spec.rb": 79.76763829899937,1180 "ee/spec/migrations/20230310213308_sync_security_policy_rule_schedules_that_may_have_been_deleted_by_a_bug_spec.rb": 66.75584881099985,1181 "ee/spec/migrations/20230403221928_resync_scan_result_policies_for_namespaces_spec.rb": 68.57469761199991,1182 "ee/spec/migrations/20230901120000_queue_update_workspaces_config_version_spec.rb": 31.20613047400002,1183 "ee/spec/migrations/20230909120000_queue_backfill_workspace_personal_access_token_spec.rb": 30.660895325999263,1184 "ee/spec/migrations/geo/resync_direct_upload_job_artifact_registry_spec.rb": 19.083399320000353,1185 "ee/spec/migrations/geo/migrate_lfs_objects_to_separate_registry_spec.rb": 12.88576336999995,1186 "ee/spec/migrations/20240104085448_queue_update_workspaces_config_version3_spec.rb": 13.3309299400007151187}1188Knapsack global time execution for tests: 05m 22s1189Finished in 11 minutes 12 seconds (files took 1 minute 55.1 seconds to load)119021 examples, 0 failures1191Randomized with seed 305031192[TEST PROF INFO] Time spent in factories: 00:00.087 (0.01% of total time)1193RSpec exited with 0.1194No examples to retry, congrats! 1195 Running after_script 1196Running after script...1197$ echo -e "\e[0Ksection_start:`date +%s`:report_results_section[collapsed=true]\r\e[0KReport results"1206WARNING: after_script failed, but job will continue unaffected: exit code 11208Not uploading cache ruby-gems-debian-bookworm-ruby-3.1-17 due to policy1210Uploading artifacts...1211auto_explain/: found 2 matching artifact files and directories 1212coverage/: found 5 matching artifact files and directories 1213crystalball/: found 2 matching artifact files and directories 1214WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1215knapsack/: found 4 matching artifact files and directories 1216rspec/: found 16 matching artifact files and directories 1217WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1218log/*.log: found 13 matching artifact files and directories 1219WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6311412148/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com1220WARNING: Retrying... context=artifacts-uploader error=request redirected1221Uploading artifacts as "archive" to coordinator... 201 Created id=6311412148 responseStatus=201 Created token=glcbt-651222Uploading artifacts...1223rspec/rspec-*.xml: found 1 matching artifact files and directories 1224WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6311412148/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com1225WARNING: Retrying... context=artifacts-uploader error=request redirected1226Uploading artifacts as "junit" to coordinator... 201 Created id=6311412148 responseStatus=201 Created token=glcbt-651228Job succeeded