Skip to content
Snippets Groups Projects

rspec migration pg14 single-db 15/26

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
122:06:50Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
222:06:50 on blue-2.private.runners-manager.gitlab.com/gitlab.com/gitlab-org QvBVRoXM, system ID: s_49b872f8ccf6
322:06:50 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:06:50Resolving secrets
5 22:06:50 Preparing the "docker+machine" executor 00:09
622:06:50Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
722:06:52Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
822:06:52Authenticating with credentials from job payload (GitLab Registry)
922:06:52Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:06:52Using 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 ...
1122:06:53WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:06:53WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:06:53Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1422:06:53Authenticating with credentials from job payload (GitLab Registry)
1522:06:53Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:06:53Using 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 ...
1722:06:53Starting service redis:6.2-alpine...
1822:06:53Pulling docker image redis:6.2-alpine ...
1922:06:54Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2022:06:54Waiting for services to be up and running (timeout 30 seconds)...
2122:06:59Authenticating with credentials from job payload (GitLab Registry)
2222:06:59Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
2322:06:59Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-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.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
24 22:06:59 Preparing environment 00:01
2522:07:00Running on runner-qvbvroxm-project-278964-concurrent-0 via runner-qvbvroxm-private-1731517989-c73a9dc0...
26 22:07:00 Getting source from Git repository 00:01
2722:07:00Skipping Git repository setup
2822:07:00Skipping Git checkout
2922:07:00Skipping Git submodules setup
30 22:07:01 Restoring cache 00:20
3122:07:01Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
3222:07:01Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 ETag="ba67364f761343c8f313528473af941a"
3322:07:14Successfully extracted cache
34 22:07:21 Downloading artifacts 00:46
3522:07:21Downloading artifacts for clone-gitlab-repo (8353286583)...
3622:07:26Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286583 responseStatus=200 OK token=glcbt-66
3722:07:26WARNING: Part of .git directory is on the list of files to extract
3822:07:26WARNING: This may introduce unexpected problems
3922:07:38Downloading artifacts for compile-test-assets (8353286630)...
4022:07:43Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286630 responseStatus=200 OK token=glcbt-66
4122:07:49Downloading artifacts for detect-tests (8353286649)...
4222:07:49Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286649 responseStatus=200 OK token=glcbt-66
4322:07:49Downloading artifacts for retrieve-tests-metadata (8353286664)...
4422:07:50Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286664 responseStatus=200 OK token=glcbt-66
4522:07:50Downloading artifacts for setup-test-env (8353286635)...
4622:07:55Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286635 responseStatus=200 OK token=glcbt-66
47 22:08:07 Executing "step_script" stage of the job script
4822:08:07Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-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.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
4922:08:08$ echo $FOSS_ONLY
5022:08:08$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5122:08:08$ export GOPATH=$CI_PROJECT_DIR/.go
5222:08:08$ mkdir -p $GOPATH
5322:08:08$ source scripts/utils.sh
5422:08:08$ log_disk_usage "true"
5522:08:08*******************************************************
5622:08:08This runner currently has 70G free disk space.
5722:08:08*******************************************************
58 22:08:08 Disk usage detail 00:05
5922:08:08df -h
6022:08:08Filesystem Size Used Avail Use% Mounted on
6122:08:08overlay 95G 26G 70G 27% /
6222:08:08tmpfs 64M 0 64M 0% /dev
6322:08:08shm 64M 0 64M 0% /dev/shm
6422:08:08/dev/sda1 95G 26G 70G 27% /cache
6522:08:08tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6622:08:08du -h -d 1
6722:08:087.4M ./workhorse
6822:08:08148K ./bin
6922:08:081.2M ./.gitlab
7022:08:08167M ./.git
7122:08:084.0K ./builds
7222:08:0828M ./lib
7322:08:088.0K ./.vscode
7422:08:08560K ./storybook
7522:08:0824K ./log
7622:08:081.8M ./data
7722:08:0811M ./qa
7822:08:083.2M ./.rubocop_todo
7922:08:08856M ./vendor
8022:08:081023M ./public
8122:08:0862M ./doc
8222:08:08484K ./danger
8322:08:0832K ./rspec
8422:08:08556K ./tooling
8522:08:08145M ./ee
8622:08:081.3M ./knapsack
8722:08:08300K ./patches
8822:08:084.3M ./changelogs
8922:08:0820K ./haml_lint
9022:08:0828K ./metrics_server
9122:08:08108K ./keeps
9222:08:0836K ./shared
9322:08:0844K ./generator_templates
9422:08:081.3M ./scripts
9522:08:08763M ./tmp
9622:08:08127M ./spec
9722:08:0820K ./file_hooks
9822:08:0832K ./crystalball
9922:08:0816K ./sidekiq_cluster
10022:08:0817M ./gems
10122:08:081.2M ./fixtures
10222:08:08964K ./rubocop
10322:08:084.0K ./.go
10422:08:0833M ./db
10522:08:0812K ./.github
10622:08:08129M ./locale
10722:08:0820M ./config
10822:08:0882M ./app
10922:08:0820K ./.lefthook
11022:08:083.5G .
11122:08:08$ source scripts/prepare_build.sh
112 22:08:08 Installing gems 00:17
14322:08:25Using two connections, single database config (config/database.yml.postgresql)
14422:08:25Disabling ci connection in config/database.yml
14522:08:25Geo DB won't be set up.
14622:08:25Embedding DB won't be set up.
147 22:08:25 Setting up DBs 00:55
17222:09:20$ source ./scripts/rspec_helpers.sh
17322:09:20$ run_timed_command "gem install knapsack --no-document"
17422:09:20$ gem install knapsack --no-document
17522:09:21Successfully installed knapsack-4.0.0
17622:09:211 gem installed
17722:09:20==> 'gem install knapsack --no-document' succeeded in 1 seconds.
17822:09:21$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
179 22:09:21 Spawning Gitaly 00:04
18322:09:25$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
18422:09:25$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
18522:09:25$ tooling/bin/create_job_metrics_file || true
18622:09:28[job-metrics] Creating the job metrics file for the CI/CD job.
18722:09:28$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
188 22:09:28 RSpec 03:59
18922:09:28[22:09:28] Starting rspec_parallelized_job
19022:09:21RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
19122:09:28KNAPSACK_GENERATE_REPORT: true
19222:09:28FLAKY_RSPEC_GENERATE_REPORT: true
19322:09:28KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
19422:09:28KNAPSACK_LOG_LEVEL: debug
19522:09:28KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_single-db_15_26_278964_report.json
19622:09:28FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
19722:09:28FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_single-db_15_26_278964_report.json
19822:09:28NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_single-db_15_26_278964_report.json
19922:09:28RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8353287162.txt
20022:09:28CRYSTALBALL:
20122:09:28RSPEC_TESTS_MAPPING_ENABLED:
20222:09:28RSPEC_TESTS_FILTER_FILE:
20322:09:28Shell set options (set -o) enabled:
20422:09:28braceexpand on
20522:09:28hashall on
20622:09:28interactive-comments on
20722:09:28pipefail on
20822:09:34Knapsack report generator started!
20922:09:28/builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
21022:09:43 ██  ██  █████  ██████  ███  ██ ██ ███  ██  ██████ 
21122:09:43 ██  ██ ██   ██ ██   ██ ████  ██ ██ ████  ██ ██      
21222:09:43 ██  █  ██ ███████ ██████  ██ ██  ██ ██ ██ ██  ██ ██  ███ 
21322:09:43 ██ ███ ██ ██   ██ ██   ██ ██  ██ ██ ██ ██  ██ ██ ██  ██ 
21422:09:43  ███ ███  ██  ██ ██  ██ ██   ████ ██ ██   ████  ██████  
21522:09:43******************************************************************************
21622:09:43 Your database has a single connection, and single connections were
21722:09:43 deprecated in GitLab 15.9 https://docs.gitlab.com/ee/update/deprecations.html#single-database-connection-is-deprecated.
21822:09:43 In GitLab 17.0 and later, you must have the two main: and ci: sections in your database.yml.
21922:09:43 Please add a :ci section to your database, following these instructions:
22022:09:43 https://docs.gitlab.com/ee/install/installation.html#configure-gitlab-db-settings.
22122:09:43******************************************************************************
22222:10:13DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <top (required)> at /builds/gitlab-org/gitlab/config/environment.rb:7)
22322:10:23warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
22422:10:23Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
22522:10:28Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
22622:10:28# [RSpecRunTime] Starting RSpec timer...
22722:10:28[TEST PROF INFO] EventProf enabled (sql.active_record)
22822:10:28unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
22922:10:35Test environment set up in 3.203187147 seconds
23022:10:35# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20241003225057_backfill_null_project_on_ci_stages_records_spec.rb. Expected to take 31.53 seconds.
23122:10:35BackfillNullProjectOnCiStagesRecords
23222:10:41 #up
23322:10:42main: == [advisory_lock_connection] object_id: 1906040, pg_backend_pid: 126
23422:10:42main: == 20241003225057 BackfillNullProjectOnCiStagesRecords: migrating =============
23522:10:42main: == 20241003225057 BackfillNullProjectOnCiStagesRecords: migrated (0.0340s) ====
23622:10:42main: == [advisory_lock_connection] object_id: 1906040, pg_backend_pid: 126
23722:10:45 backfills applicable records
23822:10:46main: == [advisory_lock_connection] object_id: 2196760, pg_backend_pid: 128
23922:10:46main: == 20241003225057 BackfillNullProjectOnCiStagesRecords: migrating =============
24022:10:46main: == 20241003225057 BackfillNullProjectOnCiStagesRecords: migrated (0.0286s) ====
24122:10:46main: == [advisory_lock_connection] object_id: 2196760, pg_backend_pid: 128
24222:10:49 deletes orphaned records
24322:11:02# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20241003225057_backfill_null_project_on_ci_stages_records_spec.rb. It took 27.12 seconds. Expected to take 31.53 seconds.
24422:11:02# [RSpecRunTime] RSpec elapsed time: 34.67 seconds. Current RSS: ~1194M. Threads: 1. load average: 1.13 0.81 0.58 1/279 387
24522:11:02.
24622:11:02# [RSpecRunTime] Starting example group spec/migrations/20240930123732_queue_backfill_pages_domain_acme_orders_project_id_spec.rb. Expected to take 18.84 seconds.
24722:11:02QueueBackfillPagesDomainAcmeOrdersProjectId
24822:11:13main: == [advisory_lock_connection] object_id: 5970900, pg_backend_pid: 134
24922:11:13main: == 20240930123732 QueueBackfillPagesDomainAcmeOrdersProjectId: migrating ======
25022:11:13main: == 20240930123732 QueueBackfillPagesDomainAcmeOrdersProjectId: migrated (0.0776s)
25122:11:13main: == [advisory_lock_connection] object_id: 5970900, pg_backend_pid: 134
25222:11:17 schedules a new batched migration
25322:11:34# [RSpecRunTime] Finishing example group spec/migrations/20240930123732_queue_backfill_pages_domain_acme_orders_project_id_spec.rb. It took 31.98 seconds. Expected to take 18.84 seconds.
25422:11:34# [RSpecRunTime] RSpec elapsed time: 1 minute 6.76 seconds. Current RSS: ~1204M. Threads: 1. load average: 1.11 0.84 0.60 1/281 389
25522:11:34.
25622:11:34# [RSpecRunTime] Starting example group spec/migrations/20241014114624_queue_backfill_security_findings_project_id_spec.rb. Expected to take 12.95 seconds.
25722:11:34QueueBackfillSecurityFindingsProjectId
25822:11:41main: == [advisory_lock_connection] object_id: 9805340, pg_backend_pid: 141
25922:11:41main: == 20241014114624 QueueBackfillSecurityFindingsProjectId: migrating ===========
26022:11:41main: == 20241014114624 QueueBackfillSecurityFindingsProjectId: migrated (0.0097s) ==
26122:11:41main: == [advisory_lock_connection] object_id: 9805340, pg_backend_pid: 141
26222:11:45 does not schedules a new batched migration
26322:11:56# [RSpecRunTime] Finishing example group spec/migrations/20241014114624_queue_backfill_security_findings_project_id_spec.rb. It took 21.46 seconds. Expected to take 12.95 seconds.
26422:11:56# [RSpecRunTime] RSpec elapsed time: 1 minute 28.26 seconds. Current RSS: ~1200M. Threads: 1. load average: 1.07 0.86 0.61 1/281 390
26522:11:56.
26622:11:56# [RSpecRunTime] Starting example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb.
26722:11:56SwapColumnsForCiStagesPipelineIdBigintV2
26822:11:56 when pipeline_id sql type is integer
26922:11:56 behaves like swap conversion columns
27022:11:56 correctly swaps conversion columns (PENDING: Skipping because migration SwapColumnsForCiStagesPipelineIdBigintV2 is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
27122:11:56 when pipeline_id sql type is bigint
27222:11:56 does nothing (PENDING: Skipping because migration SwapColumnsForCiStagesPipelineIdBigintV2 is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
27322:11:56# [RSpecRunTime] Finishing example group spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb.
27422:11:56# [RSpecRunTime] Starting example group spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb.
27522:11:56QueueBackfillPartitionIdCiPipelineArtifact
27622:11:56 #up
27722:11:56 with migration present
27822:11:56 when migration finished successfully
27922:11:56 does not raise exception (PENDING: Skipping because migration QueueBackfillPartitionIdCiPipelineArtifact is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
28022:11:56 schedules background jobs for each batch of ci_pipeline_artifacts (PENDING: Skipping because migration QueueBackfillPartitionIdCiPipelineArtifact is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
28122:11:56 #down
28222:11:56 deletes all batched migration records (PENDING: Skipping because migration QueueBackfillPartitionIdCiPipelineArtifact is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
28322:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb.
28422:11:56# [RSpecRunTime] Starting example group spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb.
28522:11:56MoveSelfManagedCrToInstance
28622:11:56 #up
28722:11:56 when on self managed
28822:11:56 sets namespace_id to nil and updates name (PENDING: Skipping because migration MoveSelfManagedCrToInstance is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
28922:11:56 when on SaaS
29022:11:56 does not update the custom roles (PENDING: Skipping because migration MoveSelfManagedCrToInstance is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29122:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb.
29222:11:56# [RSpecRunTime] Starting example group spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb.
29322:11:56QueueBackfillAuditEventsStreamingHeadersGroupId
29422:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillAuditEventsStreamingHeadersGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29522:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb.
29622:11:56# [RSpecRunTime] Starting example group spec/migrations/20240603121642_queue_backfill_dast_pre_scan_verifications_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240603121642_queue_backfill_dast_pre_scan_verifications_project_id_spec.rb.
29722:11:56QueueBackfillDastPreScanVerificationsProjectId
29822:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillDastPreScanVerificationsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29922:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240603121642_queue_backfill_dast_pre_scan_verifications_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240603121642_queue_backfill_dast_pre_scan_verifications_project_id_spec.rb.
30022:11:56# [RSpecRunTime] Starting example group spec/migrations/20240611142352_queue_backfill_status_check_responses_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240611142352_queue_backfill_status_check_responses_project_id_spec.rb.
30122:11:56QueueBackfillStatusCheckResponsesProjectId
30222:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillStatusCheckResponsesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30322:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240611142352_queue_backfill_status_check_responses_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240611142352_queue_backfill_status_check_responses_project_id_spec.rb.
30422:11:56# [RSpecRunTime] Starting example group spec/migrations/20240626060552_set_internal_bots_to_have_private_profiles_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240626060552_set_internal_bots_to_have_private_profiles_spec.rb.
30522:11:56SetInternalBotsToHavePrivateProfiles
30622:11:56 #up
30722:11:56 sets confirmed_at and private_profile for all internal bots (PENDING: Skipping because migration SetInternalBotsToHavePrivateProfiles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30822:11:56 does not update existing confirmed_at (PENDING: Skipping because migration SetInternalBotsToHavePrivateProfiles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30922:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240626060552_set_internal_bots_to_have_private_profiles_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240626060552_set_internal_bots_to_have_private_profiles_spec.rb.
31022:11:56# [RSpecRunTime] Starting example group spec/migrations/20240716191121_queue_backfill_user_details_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240716191121_queue_backfill_user_details_spec.rb.
31122:11:56QueueBackfillUserDetails
31222:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillUserDetails is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31322:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240716191121_queue_backfill_user_details_spec.rb. It took 0.01 second. Missing expected duration from Knapsack report for spec/migrations/20240716191121_queue_backfill_user_details_spec.rb.
31422:11:56# [RSpecRunTime] Starting example group spec/migrations/20240814025044_queue_delete_orphaned_deploy_tokens_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240814025044_queue_delete_orphaned_deploy_tokens_spec.rb.
31522:11:56QueueDeleteOrphanedDeployTokens
31622:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueDeleteOrphanedDeployTokens is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31722:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240814025044_queue_delete_orphaned_deploy_tokens_spec.rb. It took 0.01 second. Missing expected duration from Knapsack report for spec/migrations/20240814025044_queue_delete_orphaned_deploy_tokens_spec.rb.
31822:11:56# [RSpecRunTime] Starting example group spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb.
31922:11:56QueueDeleteOrphanedBuildRecords
32022:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueDeleteOrphanedBuildRecords is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32122:11:56 when executed on .com
32222:11:56 schedules a new batched migration (PENDING: Skipping because migration QueueDeleteOrphanedBuildRecords is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32322:11:56# [RSpecRunTime] Finishing example group spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb.
32422:11:56# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240906133341_finalize_nullify_organization_id_for_snippets_spec.rb. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240906133341_finalize_nullify_organization_id_for_snippets_spec.rb.
32522:11:56FinalizeNullifyOrganizationIdForSnippets
32622:11:56 #up
32722:11:56 ensures the migration is completed for self-managed instances (PENDING: Skipping because migration FinalizeNullifyOrganizationIdForSnippets is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32822:11:56# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240906133341_finalize_nullify_organization_id_for_snippets_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240906133341_finalize_nullify_organization_id_for_snippets_spec.rb.
32922:11:58auto_explain log contains 845 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-single-db-15-26.342.main.ndjson.gz
33022:11:58took 1.470523819
33122:11:58[TEST PROF INFO] EventProf results for sql.active_record
33222:11:58Total time: 00:39.066 of 01:20.924 (48.28%)
33322:11:58Total events: 31337
33422:11:58Top 5 slowest suites (by time):
33522:11:58QueueBackfill...rdersProjectId (./spec/migrations/20240930123732_queue_backfill_pages_domain_acme_orders_project_id_spec.rb:6) – 00:16.365 (12770 / 1) of 00:32.021 (51.11%)
33622:11:58BackfillNullP...iStagesRecords (./spec/migrations/db/post_migrate/20241003225057_backfill_null_project_on_ci_stages_records_spec.rb:6) – 00:12.325 (10525 / 2) of 00:27.229 (45.26%)
33722:11:58QueueBackfill...dingsProjectId (./spec/migrations/20241014114624_queue_backfill_security_findings_project_id_spec.rb:6) – 00:10.346 (7965 / 1) of 00:21.500 (48.12%)
33822:11:58SwapColumnsFo...lineIdBigintV2 (./spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb:6) – 00:00.003 (7 / 2) of 00:00.017 (17.64%)
33922:11:58QueueDeleteOr...edBuildRecords (./spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb:6) – 00:00.002 (7 / 2) of 00:00.015 (18.22%)
34022:11:58Knapsack report was generated. Preview:
34122:11:58{
34222:11:58 "spec/migrations/db/post_migrate/20241003225057_backfill_null_project_on_ci_stages_records_spec.rb": 14.789324408000539,
34322:11:58 "spec/migrations/20240930123732_queue_backfill_pages_domain_acme_orders_project_id_spec.rb": 14.269908427999326,
34422:11:58 "spec/migrations/20241014114624_queue_backfill_security_findings_project_id_spec.rb": 10.563176289000694
34522:11:58}
34622:11:58Knapsack global time execution for tests: 39s
34722:11:58Pending: (Failures listed here are expected and do not affect your suite's status)
34822:11:58 1) SwapColumnsForCiStagesPipelineIdBigintV2 when pipeline_id sql type is integer behaves like swap conversion columns correctly swaps conversion columns
34922:11:58 # Skipping because migration SwapColumnsForCiStagesPipelineIdBigintV2 is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35022:11:58 # ./spec/support/shared_examples/migrations/swap_conversion_columns_shared_examples.rb:26
35122:11:58 2) SwapColumnsForCiStagesPipelineIdBigintV2 when pipeline_id sql type is bigint does nothing
35222:11:58 # Skipping because migration SwapColumnsForCiStagesPipelineIdBigintV2 is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35322:11:58 # ./spec/migrations/20231019084731_swap_columns_for_ci_stages_pipeline_id_bigint_v2_spec.rb:31
35422:11:58 3) QueueBackfillPartitionIdCiPipelineArtifact#up with migration present when migration finished successfully does not raise exception
35522:11:58 # Skipping because migration QueueBackfillPartitionIdCiPipelineArtifact is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35622:11:58 # ./spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb:29
35722:11:58 4) QueueBackfillPartitionIdCiPipelineArtifact#up with migration present when migration finished successfully schedules background jobs for each batch of ci_pipeline_artifacts
35822:11:58 # Skipping because migration QueueBackfillPartitionIdCiPipelineArtifact is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35922:11:58 # ./spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb:33
36022:11:58 5) QueueBackfillPartitionIdCiPipelineArtifact#down deletes all batched migration records
36122:11:58 # Skipping because migration QueueBackfillPartitionIdCiPipelineArtifact is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
36222:11:58 # ./spec/migrations/20240109090354_queue_backfill_partition_id_ci_pipeline_artifact_spec.rb:49
36322:11:58 6) MoveSelfManagedCrToInstance#up when on self managed sets namespace_id to nil and updates name
36422:11:58 # Skipping because migration MoveSelfManagedCrToInstance is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
36522:11:58 # ./spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb:25
36622:11:58 7) MoveSelfManagedCrToInstance#up when on SaaS does not update the custom roles
36722:11:58 # Skipping because migration MoveSelfManagedCrToInstance is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
36822:11:58 # ./spec/migrations/20240325131114_move_self_managed_cr_to_instance_spec.rb:42
36922:11:58 8) QueueBackfillAuditEventsStreamingHeadersGroupId schedules a new batched migration
37022:11:58 # Skipping because migration QueueBackfillAuditEventsStreamingHeadersGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
37122:11:58 # ./spec/migrations/20240514142206_queue_backfill_audit_events_streaming_headers_group_id_spec.rb:9
37222:11:58 9) QueueBackfillDastPreScanVerificationsProjectId schedules a new batched migration
37322:11:58 # Skipping because migration QueueBackfillDastPreScanVerificationsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
37422:11:58 # ./spec/migrations/20240603121642_queue_backfill_dast_pre_scan_verifications_project_id_spec.rb:10
37522:11:58 10) QueueBackfillStatusCheckResponsesProjectId schedules a new batched migration
37622:11:58 # Skipping because migration QueueBackfillStatusCheckResponsesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
37722:11:58 # ./spec/migrations/20240611142352_queue_backfill_status_check_responses_project_id_spec.rb:10
37822:11:58 11) SetInternalBotsToHavePrivateProfiles#up sets confirmed_at and private_profile for all internal bots
37922:11:58 # Skipping because migration SetInternalBotsToHavePrivateProfiles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38022:11:58 # ./spec/migrations/20240626060552_set_internal_bots_to_have_private_profiles_spec.rb:113
38122:11:58 12) SetInternalBotsToHavePrivateProfiles#up does not update existing confirmed_at
38222:11:58 # Skipping because migration SetInternalBotsToHavePrivateProfiles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38322:11:58 # ./spec/migrations/20240626060552_set_internal_bots_to_have_private_profiles_spec.rb:122
38422:11:58 13) QueueBackfillUserDetails schedules a new batched migration
38522:11:58 # Skipping because migration QueueBackfillUserDetails is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38622:11:58 # ./spec/migrations/20240716191121_queue_backfill_user_details_spec.rb:9
38722:11:58 14) QueueDeleteOrphanedDeployTokens schedules a new batched migration
38822:11:58 # Skipping because migration QueueDeleteOrphanedDeployTokens is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38922:11:58 # ./spec/migrations/20240814025044_queue_delete_orphaned_deploy_tokens_spec.rb:9
39022:11:58 15) QueueDeleteOrphanedBuildRecords schedules a new batched migration
39122:11:58 # Skipping because migration QueueDeleteOrphanedBuildRecords is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39222:11:58 # ./spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb:9
39322:11:58 16) QueueDeleteOrphanedBuildRecords when executed on .com schedules a new batched migration
39422:11:58 # Skipping because migration QueueDeleteOrphanedBuildRecords is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39522:11:58 # ./spec/migrations/20240910154923_queue_delete_orphaned_build_records_spec.rb:33
39622:11:58 17) FinalizeNullifyOrganizationIdForSnippets#up ensures the migration is completed for self-managed instances
39722:11:58 # Skipping because migration FinalizeNullifyOrganizationIdForSnippets is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39822:11:58 # ./spec/migrations/db/post_migrate/20240906133341_finalize_nullify_organization_id_for_snippets_spec.rb:8
39922:11:58Finished in 1 minute 29.97 seconds (files took 56.35 seconds to load)
40022:11:5821 examples, 0 failures, 17 pending
40122:11:58Randomized with seed 53672
40222:12:06[TEST PROF INFO] Time spent in factories: 00:00.181 (0.19% of total time)
40322:12:06RSpec exited with 0.
40422:12:06No examples to retry, congrats!
405 22:12:06 Running after_script 00:12
40622:12:07Running after script...
40722:12:07$ source scripts/utils.sh
40822:12:07$ log_disk_usage
40922:12:07*******************************************************
41022:12:07This runner currently has 69G free disk space.
41122:12:07*******************************************************
412 22:12:07 Disk usage detail 00:05
46822:12:12$ bundle exec gem list gitlab_quality-test_tooling
46922:12:14gitlab_quality-test_tooling (2.1.0)
47022:12:14$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
471 22:12:14 Report test failures 00:00
47322:12:14$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
474 22:12:14 Report test flakiness 00:00
47622:12:14$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
477 22:12:14 Report test slowness 00:00
47922:12:14$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
480 22:12:14 Report test slowness in MR note 00:01
48322:12:15$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
484 22:12:15 Report test files close to timing out 00:02
48722:12:17$ tooling/bin/push_job_metrics || true
48822:12:18[job-metrics] Pushing job metrics file for the CI/CD job.
48922:12:18[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
490 22:12:18 Saving cache for successful job 00:00
49122:12:19Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
492 22:12:19 Uploading artifacts for successful job 00:06
49322:12:20Uploading artifacts...
49422:12:20auto_explain/: found 2 matching artifact files and directories
49522:12:20coverage/: found 5 matching artifact files and directories
49622:12:20crystalball/: found 2 matching artifact files and directories
49722:12:20WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
49822:12:20knapsack/: found 4 matching artifact files and directories
49922:12:20rspec/: found 16 matching artifact files and directories
50022:12:20WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
50122:12:20log/*.log: found 13 matching artifact files and directories
50222:12:21WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353287162/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
50322:12:21WARNING: Retrying... context=artifacts-uploader error=request redirected
50422:12:23Uploading artifacts as "archive" to coordinator... 201 Created id=8353287162 responseStatus=201 Created token=glcbt-66
50522:12:23Uploading artifacts...
50622:12:23rspec/rspec-*.xml: found 1 matching artifact files and directories
50722:12:23WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353287162/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
50822:12:23WARNING: Retrying... context=artifacts-uploader error=request redirected
50922:12:24Uploading artifacts as "junit" to coordinator... 201 Created id=8353287162 responseStatus=201 Created token=glcbt-66
510 22:12:25 Cleaning up project directory and file based variables 00:00
51122:12:33Job succeeded