Skip to content
Snippets Groups Projects

rspec migration pg14 19/26

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
122:06:45Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
222:06:45 on blue-1.private.runners-manager.gitlab.com/gitlab.com/gitlab-org 1zzGUpzq, system ID: s_fc31da7ae46e
322:06:45 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:06:45Resolving secrets
5 22:06:45 Preparing the "docker+machine" executor 00:13
622:06:46Using 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:48Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
822:06:48Authenticating with credentials from job payload (GitLab Registry)
922:06:48Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:06:48Using 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:49WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:06:49WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:06:49Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1422:06:49Authenticating with credentials from job payload (GitLab Registry)
1522:06:49Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:06:50Using 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:50Starting service redis:6.2-alpine...
1822:06:50Pulling docker image redis:6.2-alpine ...
1922:06:51Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2022:06:52Waiting for services to be up and running (timeout 30 seconds)...
2122:06:58Authenticating with credentials from job payload (GitLab Registry)
2222:06:58Pulling 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:58Using 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:58 Preparing environment 00:01
2522:06:58Running on runner-1zzgupzq-project-278964-concurrent-0 via runner-1zzgupzq-private-1731517110-e24c779e...
26 22:06:59 Getting source from Git repository 00:01
2722:06:59Skipping Git repository setup
2822:06:59Skipping Git checkout
2922:06:59Skipping Git submodules setup
30 22:07:00 Restoring cache 00:19
3122:07:00Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
3222:07:00Downloading 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:13Successfully extracted cache
34 22:07:19 Downloading artifacts 00:48
3522:07:20Downloading artifacts for clone-gitlab-repo (8353286583)...
3622:07:25Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286583 responseStatus=200 OK token=glcbt-66
3722:07:25WARNING: Part of .git directory is on the list of files to extract
3822:07:25WARNING: This may introduce unexpected problems
3922:07:37Downloading artifacts for compile-test-assets (8353286630)...
4022:07:41Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286630 responseStatus=200 OK token=glcbt-66
4122:07:47Downloading artifacts for detect-tests (8353286649)...
4222:07:48Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286649 responseStatus=200 OK token=glcbt-66
4322:07:48Downloading artifacts for retrieve-tests-metadata (8353286664)...
4422:07:48Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286664 responseStatus=200 OK token=glcbt-66
4522:07:48Downloading 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:07$ echo $FOSS_ONLY
5022:08:07$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5122:08:07$ export GOPATH=$CI_PROJECT_DIR/.go
5222:08:07$ mkdir -p $GOPATH
5322:08:07$ source scripts/utils.sh
5422:08:07$ log_disk_usage "true"
5522:08:07*******************************************************
5622:08:07This runner currently has 72G free disk space.
5722:08:07*******************************************************
58 22:08:07 Disk usage detail 00:08
5922:08:07df -h
6022:08:07Filesystem Size Used Avail Use% Mounted on
6122:08:07overlay 95G 23G 72G 24% /
6222:08:07tmpfs 64M 0 64M 0% /dev
6322:08:07shm 64M 0 64M 0% /dev/shm
6422:08:07/dev/sda1 95G 23G 72G 24% /cache
6522:08:07tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6622:08:07du -h -d 1
6722:08:077.4M ./workhorse
6822:08:07148K ./bin
6922:08:071.2M ./.gitlab
7022:08:07167M ./.git
7122:08:074.0K ./builds
7222:08:0728M ./lib
7322:08:078.0K ./.vscode
7422:08:07560K ./storybook
7522:08:0724K ./log
7622:08:071.8M ./data
7722:08:0711M ./qa
7822:08:073.2M ./.rubocop_todo
7922:08:07856M ./vendor
8022:08:071023M ./public
8122:08:0762M ./doc
8222:08:07484K ./danger
8322:08:0732K ./rspec
8422:08:07556K ./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:26
14322:08:34Using decomposed database config (config/database.yml.decomposed-postgresql)
14422:08:34Geo DB won't be set up.
14522:08:34Embedding DB won't be set up.
146 22:08:34 Setting up DBs 01:18
15922:09:52$ source ./scripts/rspec_helpers.sh
16022:09:52$ run_timed_command "gem install knapsack --no-document"
16122:09:52$ gem install knapsack --no-document
16222:09:54Successfully installed knapsack-4.0.0
16322:09:541 gem installed
16422:09:52==> 'gem install knapsack --no-document' succeeded in 2 seconds.
16522:09:54$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
166 22:09:54 Spawning Gitaly 00:04
17022:09:58$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17122:09:58$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17222:09:58$ tooling/bin/create_job_metrics_file || true
17322:10:01[job-metrics] Creating the job metrics file for the CI/CD job.
17422:10:01$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
175 22:10:01 RSpec 05:32
17622:10:01[22:10:01] Starting rspec_parallelized_job
17722:09:54RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
17822:10:01KNAPSACK_GENERATE_REPORT: true
17922:10:01FLAKY_RSPEC_GENERATE_REPORT: true
18022:10:01KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
18122:10:01KNAPSACK_LOG_LEVEL: debug
18222:10:01KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_19_26_278964_report.json
18322:10:01FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18422:10:01FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_19_26_278964_report.json
18522:10:01NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_19_26_278964_report.json
18622:10:01RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8353287064.txt
18722:10:01CRYSTALBALL:
18822:10:01RSPEC_TESTS_MAPPING_ENABLED:
18922:10:01RSPEC_TESTS_FILTER_FILE:
19022:10:01Shell set options (set -o) enabled:
19122:10:01braceexpand on
19222:10:01hashall on
19322:10:01interactive-comments on
19422:10:01pipefail on
19522:10:07Knapsack report generator started!
19622:10:01/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!
19722:10:44DEPRECATION 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)
19822:10:54warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
19922:10:54Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20022:11:01Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
20122:11:01# [RSpecRunTime] Starting RSpec timer...
20222:11:01[TEST PROF INFO] EventProf enabled (sql.active_record)
20322:11:01unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20422:11:01unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20522:11:11Test environment set up in 1.229713441 seconds
20622:11:12# [RSpecRunTime] Starting example group spec/migrations/20240920072729_remove_dangling_cron_worker_spec.rb. Expected to take 27.9 seconds.
20722:11:12RemoveDanglingCronWorker
20822:11:22 when cron job does not exist
20922:11:23main: == [advisory_lock_connection] object_id: 2312940, pg_backend_pid: 144
21022:11:23main: == 20240920072729 RemoveDanglingCronWorker: migrating =========================
21122:11:23main: == 20240920072729 RemoveDanglingCronWorker: migrated (0.1157s) ================
21222:11:23main: == [advisory_lock_connection] object_id: 2312940, pg_backend_pid: 144
21322:11:30 no-ops
21422:11:30 when cron job exists
21522:11:31main: == [advisory_lock_connection] object_id: 3279340, pg_backend_pid: 146
21622:11:31main: == 20240920072729 RemoveDanglingCronWorker: migrating =========================
21722:11:31main: == 20240920072729 RemoveDanglingCronWorker: migrated (0.0288s) ================
21822:11:31main: == [advisory_lock_connection] object_id: 3279340, pg_backend_pid: 146
21922:11:36 deletes the cron job and enqueued jobs
22022:11:36# [RSpecRunTime] RSpec elapsed time: 34.96 seconds. Current RSS: ~1215M. Threads: 1. load average: 1.13 0.78 0.53 1/281 388
22122:11:36.
22222:11:44I, [2024-11-13T22:11:44.838338 #343] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
22322:11:44I, [2024-11-13T22:11:44.839822 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22422:11:44I, [2024-11-13T22:11:44.858645 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22522:11:44I, [2024-11-13T22:11:44.863475 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
22622:11:44I, [2024-11-13T22:11:44.864673 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22722:11:44I, [2024-11-13T22:11:44.865572 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22822:11:44I, [2024-11-13T22:11:44.869201 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
22922:11:44I, [2024-11-13T22:11:44.870325 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23022:11:44I, [2024-11-13T22:11:44.871153 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23122:11:44I, [2024-11-13T22:11:44.874682 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
23222:11:44I, [2024-11-13T22:11:44.875740 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23322:11:44I, [2024-11-13T22:11:44.876512 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23422:11:46I, [2024-11-13T22:11:46.003942 #343] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
23522:11:46I, [2024-11-13T22:11:46.005163 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23622:11:46I, [2024-11-13T22:11:46.006049 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23722:11:46I, [2024-11-13T22:11:46.009677 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
23822:11:46I, [2024-11-13T22:11:46.011311 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23922:11:46I, [2024-11-13T22:11:46.012226 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24022:11:46I, [2024-11-13T22:11:46.015937 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
24122:11:46I, [2024-11-13T22:11:46.017110 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24222:11:46I, [2024-11-13T22:11:46.018037 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24322:11:46I, [2024-11-13T22:11:46.022055 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
24422:11:46I, [2024-11-13T22:11:46.023229 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24522:11:46I, [2024-11-13T22:11:46.024108 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24622:12:05# [RSpecRunTime] Finishing example group spec/migrations/20240920072729_remove_dangling_cron_worker_spec.rb. It took 53.87 seconds. Expected to take 27.9 seconds.
24722:12:05# [RSpecRunTime] RSpec elapsed time: 1 minute 4.79 seconds. Current RSS: ~1204M. Threads: 1. load average: 1.01 0.78 0.54 1/281 390
24822:12:05.
24922:12:05# [RSpecRunTime] Starting example group spec/migrations/20240927202948_reenqueue_deduplicate_lfs_objects_projects_with_null_repository_types_spec.rb. Expected to take 20.4 seconds.
25022:12:05ReenqueueDeduplicateLfsObjectsProjectsWithNullRepositoryTypes
25122:12:16main: == [advisory_lock_connection] object_id: 8029320, pg_backend_pid: 157
25222:12:16main: == 20240927202948 ReenqueueDeduplicateLfsObjectsProjectsWithNullRepositoryTypes: migrating
25322:12:16main: == 20240927202948 ReenqueueDeduplicateLfsObjectsProjectsWithNullRepositoryTypes: migrated (0.0844s)
25422:12:16main: == [advisory_lock_connection] object_id: 8029320, pg_backend_pid: 157
25522:12:24 schedules a new batched migration
25622:12:29I, [2024-11-13T22:12:29.397735 #343] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
25722:12:29I, [2024-11-13T22:12:29.399020 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25822:12:29I, [2024-11-13T22:12:29.399888 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25922:12:29I, [2024-11-13T22:12:29.403291 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
26022:12:29I, [2024-11-13T22:12:29.404337 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26122:12:29I, [2024-11-13T22:12:29.405093 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26222:12:29I, [2024-11-13T22:12:29.408309 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
26322:12:29I, [2024-11-13T22:12:29.409339 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26422:12:29I, [2024-11-13T22:12:29.410095 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26522:12:29I, [2024-11-13T22:12:29.413592 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
26622:12:29I, [2024-11-13T22:12:29.414575 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26722:12:29I, [2024-11-13T22:12:29.415353 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26822:12:30I, [2024-11-13T22:12:30.492335 #343] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
26922:12:30I, [2024-11-13T22:12:30.493520 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27022:12:30I, [2024-11-13T22:12:30.494368 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27122:12:30I, [2024-11-13T22:12:30.497950 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
27222:12:30I, [2024-11-13T22:12:30.499014 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27322:12:30I, [2024-11-13T22:12:30.499836 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27422:12:30I, [2024-11-13T22:12:30.503139 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
27522:12:30I, [2024-11-13T22:12:30.504235 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27622:12:30I, [2024-11-13T22:12:30.505242 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27722:12:30I, [2024-11-13T22:12:30.508791 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
27822:12:30I, [2024-11-13T22:12:30.509834 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27922:12:30I, [2024-11-13T22:12:30.510637 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28022:12:48# [RSpecRunTime] Finishing example group spec/migrations/20240927202948_reenqueue_deduplicate_lfs_objects_projects_with_null_repository_types_spec.rb. It took 42.39 seconds. Expected to take 20.4 seconds.
28122:12:48# [RSpecRunTime] RSpec elapsed time: 1 minute 47.22 seconds. Current RSS: ~1212M. Threads: 1. load average: 0.93 0.79 0.55 1/280 391
28222:12:48.
28322:12:48# [RSpecRunTime] Starting example group spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb. Expected to take 14.47 seconds.
28422:12:48SetCorrectIdToExistingWorkItemTypes
28522:12:59main: == [advisory_lock_connection] object_id: 13405360, pg_backend_pid: 169
28622:12:59main: == 20240918204510 SetCorrectIdToExistingWorkItemTypes: migrating ==============
28722:12:59main: == 20240918204510 SetCorrectIdToExistingWorkItemTypes: migrated (0.0362s) =====
28822:12:59main: == [advisory_lock_connection] object_id: 13405360, pg_backend_pid: 169
28922:13:00 sets the correct id for every record in the table
29022:13:06I, [2024-11-13T22:13:06.247304 #343] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
29122:13:06I, [2024-11-13T22:13:06.248453 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29222:13:06I, [2024-11-13T22:13:06.249283 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29322:13:06I, [2024-11-13T22:13:06.252710 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
29422:13:06I, [2024-11-13T22:13:06.253842 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29522:13:06I, [2024-11-13T22:13:06.254665 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29622:13:06I, [2024-11-13T22:13:06.258119 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
29722:13:06I, [2024-11-13T22:13:06.259433 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29822:13:06I, [2024-11-13T22:13:06.260203 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29922:13:06I, [2024-11-13T22:13:06.263491 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
30022:13:06I, [2024-11-13T22:13:06.264528 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30122:13:06I, [2024-11-13T22:13:06.265328 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30222:13:07I, [2024-11-13T22:13:07.264913 #343] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
30322:13:07I, [2024-11-13T22:13:07.266083 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30422:13:07I, [2024-11-13T22:13:07.266891 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30522:13:07I, [2024-11-13T22:13:07.270465 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
30622:13:07I, [2024-11-13T22:13:07.271691 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30722:13:07I, [2024-11-13T22:13:07.272585 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30822:13:07I, [2024-11-13T22:13:07.276262 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
30922:13:07I, [2024-11-13T22:13:07.277414 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
31022:13:07I, [2024-11-13T22:13:07.278299 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
31122:13:07I, [2024-11-13T22:13:07.281996 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
31222:13:07I, [2024-11-13T22:13:07.283581 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
31322:13:07I, [2024-11-13T22:13:07.284496 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
31422:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb. It took 37.99 seconds. Expected to take 14.47 seconds.
31522:13:26# [RSpecRunTime] RSpec elapsed time: 2 minutes 25.27 seconds. Current RSS: ~1218M. Threads: 1. load average: 0.90 0.80 0.57 1/277 392
31622:13:26.
31722:13:26# [RSpecRunTime] Starting example group spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb.
31822:13:26SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost
31922:13:26 behaves like swap conversion columns
32022:13:26 correctly swaps conversion columns (PENDING: Skipping because migration SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32122:13:26 when foreign key names are different
32222:13:26 swaps the foreign key properly (PENDING: Skipping because migration SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32322:13:26 when foreign key is missing
32422:13:26 raises error (PENDING: Skipping because migration SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32522:13:26# [RSpecRunTime] Finishing example group spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb.
32622:13:26# [RSpecRunTime] Starting example group spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb.
32722:13:26UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom
32822:13:26 #up
32922:13:26 does nothing when not in gitlab.com (PENDING: Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33022:13:26 updates elasticsearch_worker_number_of_shards when gitlab.com (PENDING: Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33122:13:26 #down
33222:13:26 does nothing when not in gitlab.com (PENDING: Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33322:13:26 updates elasticsearch_worker_number_of_shards when gitlab.com (PENDING: Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33422:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb.
33522:13:26# [RSpecRunTime] Starting example group spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb.
33622:13:26QueueFixCorruptedScannerIdsOfVulnerabilityReads
33722:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueFixCorruptedScannerIdsOfVulnerabilityReads is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33822:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb.
33922:13:26# [RSpecRunTime] Starting example group spec/migrations/20240515155723_queue_backfill_design_management_repositories_namespace_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240515155723_queue_backfill_design_management_repositories_namespace_id_spec.rb.
34022:13:26QueueBackfillDesignManagementRepositoriesNamespaceId
34122:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillDesignManagementRepositoriesNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34222:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240515155723_queue_backfill_design_management_repositories_namespace_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240515155723_queue_backfill_design_management_repositories_namespace_id_spec.rb.
34322:13:26# [RSpecRunTime] Starting example group spec/migrations/20240604145223_queue_backfill_dast_profiles_tags_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240604145223_queue_backfill_dast_profiles_tags_project_id_spec.rb.
34422:13:26QueueBackfillDastProfilesTagsProjectId
34522:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillDastProfilesTagsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34622:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240604145223_queue_backfill_dast_profiles_tags_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240604145223_queue_backfill_dast_profiles_tags_project_id_spec.rb.
34722:13:26# [RSpecRunTime] Starting example group spec/migrations/20240612073059_queue_backfill_operations_strategies_user_lists_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240612073059_queue_backfill_operations_strategies_user_lists_project_id_spec.rb.
34822:13:26QueueBackfillOperationsStrategiesUserListsProjectId
34922:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillOperationsStrategiesUserListsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35022:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240612073059_queue_backfill_operations_strategies_user_lists_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240612073059_queue_backfill_operations_strategies_user_lists_project_id_spec.rb.
35122:13:26# [RSpecRunTime] Starting example group spec/migrations/20240627133840_queue_backfill_packages_debian_project_components_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240627133840_queue_backfill_packages_debian_project_components_project_id_spec.rb.
35222:13:26QueueBackfillPackagesDebianProjectComponentsProjectId
35322:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillPackagesDebianProjectComponentsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35422:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240627133840_queue_backfill_packages_debian_project_components_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240627133840_queue_backfill_packages_debian_project_components_project_id_spec.rb.
35522:13:26# [RSpecRunTime] Starting example group spec/migrations/20240722095915_queue_backfill_compliance_framework_security_policies_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240722095915_queue_backfill_compliance_framework_security_policies_project_id_spec.rb.
35622:13:26QueueBackfillComplianceFrameworkSecurityPoliciesProjectId
35722:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillComplianceFrameworkSecurityPoliciesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35822:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240722095915_queue_backfill_compliance_framework_security_policies_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240722095915_queue_backfill_compliance_framework_security_policies_project_id_spec.rb.
35922:13:26# [RSpecRunTime] Starting example group spec/migrations/20240814104154_queue_backfill_protected_env_approval_rules_protected_env_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240814104154_queue_backfill_protected_env_approval_rules_protected_env_project_id_spec.rb.
36022:13:26QueueBackfillProtectedEnvApprovalRulesProtectedEnvProjectId
36122:13:26 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillProtectedEnvApprovalRulesProtectedEnvProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36222:13:26# [RSpecRunTime] Finishing example group spec/migrations/20240814104154_queue_backfill_protected_env_approval_rules_protected_env_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240814104154_queue_backfill_protected_env_approval_rules_protected_env_project_id_spec.rb.
36322:13:26# [RSpecRunTime] Starting example group spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb. Missing expected duration from Knapsack report for spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb.
36422:13:26AddWolfiPurlTypeToPackageMetadataPurlTypes
36522:13:26 #up
36622:13:26 updates setting (PENDING: Skipping because migration AddWolfiPurlTypeToPackageMetadataPurlTypes is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36722:13:26 #down
36822:13:26 with default value
36922:13:26 updates setting (PENDING: Skipping because migration AddWolfiPurlTypeToPackageMetadataPurlTypes is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
37022:13:26# [RSpecRunTime] Finishing example group spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb.
37122:13:26# [RSpecRunTime] Starting example group spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb. Missing expected duration from Knapsack report for spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb.
37222:13:26FixSequenceOwnersForCiBuilds
37322:13:26 #up
37422:13:26 fixes sequence ownership (PENDING: Skipping because migration FixSequenceOwnersForCiBuilds is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
37522:13:26 does not modify sequences not owned by ci_builds or p_ci_builds (PENDING: Skipping because migration FixSequenceOwnersForCiBuilds is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
37622:13:26 ignores unknown sequences (PENDING: Skipping because migration FixSequenceOwnersForCiBuilds is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
37722:13:26# [RSpecRunTime] Finishing example group spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb.
37822:13:30auto_explain log contains 847 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-19-26.343.main.ndjson.gz
37922:13:30took 3.986024901
38022:13:33auto_explain log contains 848 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-19-26.343.ci.ndjson.gz
38122:13:33took 1.997132306
38222:13:33[TEST PROF INFO] EventProf results for sql.active_record
38322:13:33Total time: 01:12.674 of 02:14.585 (54.0%)
38422:13:33Total events: 54722
38522:13:33Top 5 slowest suites (by time):
38622:13:33RemoveDanglingCronWorker (./spec/migrations/20240920072729_remove_dangling_cron_worker_spec.rb:6) – 00:29.403 (20972 / 2) of 00:53.922 (54.53%)
38722:13:33ReenqueueDedu...epositoryTypes (./spec/migrations/20240927202948_reenqueue_deduplicate_lfs_objects_projects_with_null_repository_types_spec.rb:6) – 00:23.027 (17359 / 1) of 00:42.437 (54.26%)
38822:13:33SetCorrectIdT...gWorkItemTypes (./spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb:6) – 00:20.211 (16314 / 1) of 00:38.042 (53.13%)
38922:13:33SwapColumnsFo...intForSelfHost (./spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb:6) – 00:00.003 (7 / 3) of 00:00.020 (15.99%)
39022:13:33AddWolfiPurlT...adataPurlTypes (./spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb:6) – 00:00.003 (7 / 2) of 00:00.016 (19.46%)
39122:13:33Knapsack report was generated. Preview:
39222:13:33{
39322:13:33 "spec/migrations/20240920072729_remove_dangling_cron_worker_spec.rb": 24.804458730999613,
39422:13:33 "spec/migrations/20240927202948_reenqueue_deduplicate_lfs_objects_projects_with_null_repository_types_spec.rb": 18.414256754000235,
39522:13:33 "spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb": 12.183463335997658
39622:13:33}
39722:13:33Knapsack global time execution for tests: 55s
39822:13:33Pending: (Failures listed here are expected and do not affect your suite's status)
39922:13:33 1) SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost behaves like swap conversion columns correctly swaps conversion columns
40022:13:33 # Skipping because migration SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40122:13:33 # ./spec/support/shared_examples/migrations/swap_conversion_columns_shared_examples.rb:26
40222:13:33 2) SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost when foreign key names are different swaps the foreign key properly
40322:13:33 # Skipping because migration SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40422:13:33 # ./spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb:50
40522:13:33 3) SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost when foreign key is missing raises error
40622:13:33 # Skipping because migration SwapColumnsForCiPipelinesPipelineIdBigintForSelfHost is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40722:13:33 # ./spec/migrations/20231025025733_swap_columns_for_ci_pipelines_pipeline_id_bigint_for_self_host_spec.rb:83
40822:13:33 4) UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom#up does nothing when not in gitlab.com
40922:13:33 # Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41022:13:33 # ./spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb:12
41122:13:33 5) UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom#up updates elasticsearch_worker_number_of_shards when gitlab.com
41222:13:33 # Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41322:13:33 # ./spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb:18
41422:13:33 6) UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom#down does nothing when not in gitlab.com
41522:13:33 # Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41622:13:33 # ./spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb:30
41722:13:33 7) UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom#down updates elasticsearch_worker_number_of_shards when gitlab.com
41822:13:33 # Skipping because migration UpdateMaxCodeIndexingConcurrencyInApplicationSettingsForGitlabCom is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41922:13:33 # ./spec/migrations/20240116211138_update_max_code_indexing_concurrency_in_application_settings_for_gitlab_com_spec.rb:38
42022:13:33 8) QueueFixCorruptedScannerIdsOfVulnerabilityReads schedules a new batched migration
42122:13:33 # Skipping because migration QueueFixCorruptedScannerIdsOfVulnerabilityReads is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42222:13:33 # ./spec/migrations/20240409023046_queue_fix_corrupted_scanner_ids_of_vulnerability_reads_spec.rb:9
42322:13:33 9) QueueBackfillDesignManagementRepositoriesNamespaceId schedules a new batched migration
42422:13:33 # Skipping because migration QueueBackfillDesignManagementRepositoriesNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42522:13:33 # ./spec/migrations/20240515155723_queue_backfill_design_management_repositories_namespace_id_spec.rb:9
42622:13:33 10) QueueBackfillDastProfilesTagsProjectId schedules a new batched migration
42722:13:33 # Skipping because migration QueueBackfillDastProfilesTagsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42822:13:33 # ./spec/migrations/20240604145223_queue_backfill_dast_profiles_tags_project_id_spec.rb:10
42922:13:33 11) QueueBackfillOperationsStrategiesUserListsProjectId schedules a new batched migration
43022:13:33 # Skipping because migration QueueBackfillOperationsStrategiesUserListsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43122:13:33 # ./spec/migrations/20240612073059_queue_backfill_operations_strategies_user_lists_project_id_spec.rb:10
43222:13:33 12) QueueBackfillPackagesDebianProjectComponentsProjectId schedules a new batched migration
43322:13:33 # Skipping because migration QueueBackfillPackagesDebianProjectComponentsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43422:13:33 # ./spec/migrations/20240627133840_queue_backfill_packages_debian_project_components_project_id_spec.rb:10
43522:13:33 13) QueueBackfillComplianceFrameworkSecurityPoliciesProjectId schedules a new batched migration
43622:13:33 # Skipping because migration QueueBackfillComplianceFrameworkSecurityPoliciesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43722:13:33 # ./spec/migrations/20240722095915_queue_backfill_compliance_framework_security_policies_project_id_spec.rb:9
43822:13:33 14) QueueBackfillProtectedEnvApprovalRulesProtectedEnvProjectId schedules a new batched migration
43922:13:33 # Skipping because migration QueueBackfillProtectedEnvApprovalRulesProtectedEnvProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44022:13:33 # ./spec/migrations/20240814104154_queue_backfill_protected_env_approval_rules_protected_env_project_id_spec.rb:9
44122:13:33 15) AddWolfiPurlTypeToPackageMetadataPurlTypes#up updates setting
44222:13:33 # Skipping because migration AddWolfiPurlTypeToPackageMetadataPurlTypes is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44322:13:33 # ./spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb:10
44422:13:33 16) AddWolfiPurlTypeToPackageMetadataPurlTypes#down with default value updates setting
44522:13:33 # Skipping because migration AddWolfiPurlTypeToPackageMetadataPurlTypes is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44622:13:33 # ./spec/migrations/db/migrate/20231103162825_add_wolfi_purl_type_to_package_metadata_purl_types_spec.rb:23
44722:13:33 17) FixSequenceOwnersForCiBuilds#up fixes sequence ownership
44822:13:33 # Skipping because migration FixSequenceOwnersForCiBuilds is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44922:13:33 # ./spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb:27
45022:13:33 18) FixSequenceOwnersForCiBuilds#up does not modify sequences not owned by ci_builds or p_ci_builds
45122:13:33 # Skipping because migration FixSequenceOwnersForCiBuilds is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
45222:13:33 # ./spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb:39
45322:13:33 19) FixSequenceOwnersForCiBuilds#up ignores unknown sequences
45422:13:33 # Skipping because migration FixSequenceOwnersForCiBuilds is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
45522:13:33 # ./spec/migrations/fix_sequence_owners_for_ci_builds_spec.rb:52
45622:13:33Finished in 2 minutes 32.6 seconds (files took 56.39 seconds to load)
45722:13:3323 examples, 0 failures, 19 pending
45822:13:33Randomized with seed 39953
45922:13:38[TEST PROF INFO] Time spent in factories: 00:00.236 (0.15% of total time)
46022:13:38RSpec exited with 0.
46122:13:38No examples to retry, congrats!
462 22:13:39 Running after_script 00:14
46322:13:40Running after script...
46422:13:40$ source scripts/utils.sh
46522:13:40$ log_disk_usage
46622:13:40*******************************************************
46722:13:40This runner currently has 71G free disk space.
46822:13:40*******************************************************
469 22:13:40 Disk usage detail 00:08
52522:13:48$ bundle exec gem list gitlab_quality-test_tooling
52622:13:49gitlab_quality-test_tooling (2.1.0)
52722:13:49$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
528 22:13:49 Report test failures 00:00
53022:13:49$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
531 22:13:49 Report test flakiness 00:00
53322:13:49$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
534 22:13:49 Report test slowness 00:00
53622:13:49$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
537 22:13:49 Report test slowness in MR note 00:01
54022:13:50$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
541 22:13:50 Report test files close to timing out 00:02
54422:13:52$ tooling/bin/push_job_metrics || true
54522:13:53[job-metrics] Pushing job metrics file for the CI/CD job.
54622:13:53[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
547 22:13:53 Saving cache for successful job 00:02
54822:13:54Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
549 22:13:55 Uploading artifacts for successful job 00:05
55022:13:56Uploading artifacts...
55122:13:56auto_explain/: found 3 matching artifact files and directories
55222:13:56coverage/: found 5 matching artifact files and directories
55322:13:56crystalball/: found 2 matching artifact files and directories
55422:13:56WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
55522:13:56knapsack/: found 4 matching artifact files and directories
55622:13:56rspec/: found 16 matching artifact files and directories
55722:13:56WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
55822:13:56log/*.log: found 13 matching artifact files and directories
55922:13:56WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353287064/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
56022:13:56WARNING: Retrying... context=artifacts-uploader error=request redirected
56122:13:59Uploading artifacts as "archive" to coordinator... 201 Created id=8353287064 responseStatus=201 Created token=glcbt-66
56222:13:59Uploading artifacts...
56322:13:59rspec/rspec-*.xml: found 1 matching artifact files and directories
56422:13:59WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353287064/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
56522:13:59WARNING: Retrying... context=artifacts-uploader error=request redirected
56622:14:00Uploading artifacts as "junit" to coordinator... 201 Created id=8353287064 responseStatus=201 Created token=glcbt-66
567 22:14:00 Cleaning up project directory and file based variables 00:01
56822:14:10Job succeeded