Skip to content
Snippets Groups Projects

rspec migration pg14 13/26

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
122:11:56Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
222:11:56 on blue-4.private.runners-manager.gitlab.com/gitlab.com/gitlab-org tDjA3Sp3, system ID: s_96a8ae3d1050
322:11:56 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:11:56Resolving secrets
5 22:11:56 Preparing the "docker+machine" executor 01:29
622:11:56Using 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:12:00Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
822:12:00Authenticating with credentials from job payload (GitLab Registry)
922:12:00Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:12:07Using 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:12:11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:12:11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:12:11Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1422:12:11Authenticating with credentials from job payload (GitLab Registry)
1522:12:11Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:12:15Using 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:12:16Starting service redis:6.2-alpine...
1822:12:16Pulling docker image redis:6.2-alpine ...
1922:12:19Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2022:12:20Waiting for services to be up and running (timeout 30 seconds)...
2122:12:22Authenticating with credentials from job payload (GitLab Registry)
2222:12:22Pulling 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:13:25Using 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:13:25 Preparing environment 00:01
2522:13:26Running on runner-tdja3sp3-project-13083-concurrent-0 via runner-tdja3sp3-private-1731535872-0241b623...
26 22:13:26 Getting source from Git repository 00:00
2722:13:26Skipping Git repository setup
2822:13:26Skipping Git checkout
2922:13:26Skipping Git submodules setup
30 22:13:26 Restoring cache 00:16
3122:13:27Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-3...
3222:13:27Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/13083/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-3 ETag="2f01fc71854036e0b450342e02ca5726"
3322:13:37Successfully extracted cache
34 22:13:42 Downloading artifacts 00:39
3522:13:43Downloading artifacts for clone-gitlab-repo (8353300418)...
3622:13:47Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300418 responseStatus=200 OK token=glcbt-66
3722:13:47WARNING: Part of .git directory is on the list of files to extract
3822:13:47WARNING: This may introduce unexpected problems
3922:13:56Downloading artifacts for compile-test-assets (8353300423)...
4022:13:59Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300423 responseStatus=200 OK token=glcbt-66
4122:14:05Downloading artifacts for detect-tests (8353300433)...
4222:14:05Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300433 responseStatus=200 OK token=glcbt-66
4322:14:05Downloading artifacts for retrieve-tests-metadata (8353300434)...
4422:14:06Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300434 responseStatus=200 OK token=glcbt-66
4522:14:06Downloading artifacts for setup-test-env (8353300427)...
4622:14:11Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300427 responseStatus=200 OK token=glcbt-66
47 22:14:21 Executing "step_script" stage of the job script
4822:14:21Using 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:14:22$ echo $FOSS_ONLY
5022:14:22$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5122:14:22$ export GOPATH=$CI_PROJECT_DIR/.go
5222:14:22$ mkdir -p $GOPATH
5322:14:22$ source scripts/utils.sh
5422:14:22$ log_disk_usage "true"
5522:14:23*******************************************************
5622:14:23This runner currently has 80G free disk space.
5722:14:23*******************************************************
58 22:14:23 Disk usage detail 00:01
5922:14:23df -h
6022:14:23Filesystem Size Used Avail Use% Mounted on
6122:14:23overlay 95G 15G 80G 16% /
6222:14:23tmpfs 64M 0 64M 0% /dev
6322:14:23shm 64M 0 64M 0% /dev/shm
6422:14:23/dev/sda1 95G 15G 80G 16% /cache
6522:14:23tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6622:14:23du -h -d 1
6722:14:237.4M ./workhorse
6822:14:23148K ./bin
6922:14:231.2M ./.gitlab
7022:14:23121M ./.git
7122:14:234.0K ./builds
7222:14:2328M ./lib
7322:14:23560K ./storybook
7422:14:2324K ./log
7522:14:231.8M ./data
7622:14:237.1M ./qa
7722:14:233.2M ./.rubocop_todo
7822:14:23856M ./vendor
7922:14:23858M ./public
8022:14:2362M ./doc
8122:14:23484K ./danger
8222:14:2332K ./rspec
8322:14:23556K ./tooling
8422:14:231.3M ./knapsack
8522:14:23300K ./patches
8622:14:233.9M ./changelogs
8722:14:2320K ./haml_lint
8822:14:2328K ./metrics_server
8922:14:23108K ./keeps
9022:14:2336K ./shared
9122:14:2344K ./generator_templates
9222:14:231.3M ./scripts
9322:14:23641M ./tmp
9422:14:23127M ./spec
9522:14:2320K ./file_hooks
9622:14:2332K ./crystalball
9722:14:2316K ./sidekiq_cluster
9822:14:2317M ./gems
9922:14:231.2M ./fixtures
10022:14:23964K ./rubocop
10122:14:234.0K ./.go
10222:14:2333M ./db
10322:14:2312K ./.github
10422:14:23129M ./locale
10522:14:2320M ./config
10622:14:2382M ./app
10722:14:2320K ./.lefthook
10822:14:233.0G .
10922:14:23$ source scripts/prepare_build.sh
110 22:14:23 Installing gems 00:37
15122:15:00Using decomposed database config (config/database.yml.decomposed-postgresql)
15222:15:00Geo DB won't be set up.
15322:15:00Embedding DB won't be set up.
154 22:15:00 Setting up DBs 01:19
16722:16:19$ source ./scripts/rspec_helpers.sh
16822:16:19$ run_timed_command "gem install knapsack --no-document"
16922:16:19$ gem install knapsack --no-document
17022:16:20Successfully installed knapsack-4.0.0
17122:16:201 gem installed
17222:16:19==> 'gem install knapsack --no-document' succeeded in 1 seconds.
17322:16:20$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
174 22:16:20 Spawning Gitaly 00:07
17822:16:27$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17922:16:27$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
18022:16:27$ tooling/bin/create_job_metrics_file || true
18122:16:30[job-metrics] Feature disabled because CI_JOB_METRICS_ENABLED is not set to true.
18222:16:30$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
183 22:16:30 RSpec 06:09
18422:16:30[22:16:30] Starting rspec_parallelized_job
18522:16:20RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
18622:16:30KNAPSACK_GENERATE_REPORT: true
18722:16:30FLAKY_RSPEC_GENERATE_REPORT: true
18822:16:30KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
18922:16:30KNAPSACK_LOG_LEVEL: debug
19022:16:30KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_13_26_13083_report.json
19122:16:30FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
19222:16:30FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_13_26_13083_report.json
19322:16:30NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_13_26_13083_report.json
19422:16:30RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8353300889.txt
19522:16:30CRYSTALBALL:
19622:16:30RSPEC_TESTS_MAPPING_ENABLED:
19722:16:30RSPEC_TESTS_FILTER_FILE:
19822:16:30Shell set options (set -o) enabled:
19922:16:30braceexpand on
20022:16:30hashall on
20122:16:30interactive-comments on
20222:16:30pipefail on
20322:16:35Knapsack report generator started!
20422:16:30/builds/gitlab-org/gitlab-foss/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!
20522:17:11DEPRECATION 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-foss/config/environment.rb:7)
20622:17:17warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
20722:17:17Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20822:17:26/builds/gitlab-org/gitlab-foss/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!
20922:17:37DEPRECATION 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 <main> at /builds/gitlab-org/gitlab-foss/config/environment.rb:7)
21022:18:04Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
21122:18:04# [RSpecRunTime] Starting RSpec timer...
21222:18:04[TEST PROF INFO] EventProf enabled (sql.active_record)
21322:18:04unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
21422:18:04unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
21522:18:11Test environment set up in 1.942229231 seconds
21622:18:12# [RSpecRunTime] Starting example group spec/migrations/20241003181432_queue_backfill_ci_pipeline_schedule_variables_project_id_spec.rb. Expected to take 33.88 seconds.
21722:18:12QueueBackfillCiPipelineScheduleVariablesProjectId
21822:18:36ci: == [advisory_lock_connection] object_id: 6690100, pg_backend_pid: 482
21922:18:36ci: == 20241003181431 QueueBackfillCiPipelineScheduleVariablesProjectId: migrating
22022:18:36ci: == 20241003181431 QueueBackfillCiPipelineScheduleVariablesProjectId: migrated (0.0971s)
22122:18:36ci: == [advisory_lock_connection] object_id: 6690100, pg_backend_pid: 482
22222:18:41 schedules a new batched migration
22322:18:43I, [2024-11-13T22:18:43.888663 #348] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
22422:18:43I, [2024-11-13T22:18:43.889982 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22522:18:43I, [2024-11-13T22:18:43.890833 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22622:18:43I, [2024-11-13T22:18:43.894180 #348] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
22722:18:43I, [2024-11-13T22:18:43.895339 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22822:18:43I, [2024-11-13T22:18:43.896193 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22922:18:43I, [2024-11-13T22:18:43.900101 #348] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
23022:18:43I, [2024-11-13T22:18:43.901254 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23122:18:43I, [2024-11-13T22:18:43.902055 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23222:18:43I, [2024-11-13T22:18:43.905585 #348] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
23322:18:43I, [2024-11-13T22:18:43.906749 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23422:18:43I, [2024-11-13T22:18:43.907555 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23522:18:44I, [2024-11-13T22:18:44.977305 #348] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
23622:18:44I, [2024-11-13T22:18:44.978515 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23722:18:44I, [2024-11-13T22:18:44.979348 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23822:18:44I, [2024-11-13T22:18:44.982897 #348] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
23922:18:44I, [2024-11-13T22:18:44.984038 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24022:18:44I, [2024-11-13T22:18:44.984805 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24122:18:44I, [2024-11-13T22:18:44.988476 #348] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
24222:18:44I, [2024-11-13T22:18:44.989680 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24322:18:44I, [2024-11-13T22:18:44.990460 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24422:18:44I, [2024-11-13T22:18:44.993763 #348] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
24522:18:44I, [2024-11-13T22:18:44.994886 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24622:18:44I, [2024-11-13T22:18:44.995681 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24722:18:59I, [2024-11-13T22:18:59.700656 #348] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes
24822:19:00I, [2024-11-13T22:19:00.058140 #348] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes
24922:19:00I, [2024-11-13T22:19:00.496665 #348] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignment_versions': Lock Writes
25022:19:00I, [2024-11-13T22:19:00.528513 #348] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes
25122:19:00I, [2024-11-13T22:19:00.679839 #348] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes
25222:19:01I, [2024-11-13T22:19:01.388760 #348] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes
25322:19:01I, [2024-11-13T22:19:01.425827 #348] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes
25422:19:01I, [2024-11-13T22:19:01.749469 #348] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes
25522:19:01I, [2024-11-13T22:19:01.914033 #348] INFO -- : Database: 'ci', Table: 'members_deletion_schedules': Lock Writes
25622:19:02I, [2024-11-13T22:19:02.212054 #348] INFO -- : Database: 'ci', Table: 'group_scim_identities': Lock Writes
25722:19:02I, [2024-11-13T22:19:02.213294 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25822:19:02I, [2024-11-13T22:19:02.214034 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25922:19:02I, [2024-11-13T22:19:02.247521 #348] INFO -- : Database: 'ci', Table: 'group_scim_auth_access_tokens': Lock Writes
26022:19:02I, [2024-11-13T22:19:02.248956 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26122:19:02I, [2024-11-13T22:19:02.249737 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26222:19:02I, [2024-11-13T22:19:02.565565 #348] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes
26322:19:17# [RSpecRunTime] Finishing example group spec/migrations/20241003181432_queue_backfill_ci_pipeline_schedule_variables_project_id_spec.rb. It took 1 minute 5.48 seconds. Expected to take 33.88 seconds.
26422:19:17# [RSpecRunTime] Starting example group spec/migrations/20241001115912_queue_set_total_number_of_vulnerabilities_for_existing_projects_spec.rb. Expected to take 18.46 seconds.
26522:19:17QueueSetTotalNumberOfVulnerabilitiesForExistingProjects
26622:19:27main: == [advisory_lock_connection] object_id: 13435260, pg_backend_pid: 816
26722:19:27main: == 20241001115912 QueueSetTotalNumberOfVulnerabilitiesForExistingProjects: migrating
26822:19:27main: == 20241001115912 QueueSetTotalNumberOfVulnerabilitiesForExistingProjects: migrated (0.0969s)
26922:19:27main: == [advisory_lock_connection] object_id: 13435260, pg_backend_pid: 816
27022:19:32 schedules a new batched migration
27122:19:35I, [2024-11-13T22:19:35.073747 #348] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
27222:19:35I, [2024-11-13T22:19:35.074997 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27322:19:35I, [2024-11-13T22:19:35.075921 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27422:19:35I, [2024-11-13T22:19:35.079171 #348] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
27522:19:35I, [2024-11-13T22:19:35.080243 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27622:19:35I, [2024-11-13T22:19:35.081161 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27722:19:35I, [2024-11-13T22:19:35.084443 #348] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
27822:19:35I, [2024-11-13T22:19:35.085504 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27922:19:35I, [2024-11-13T22:19:35.086281 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28022:19:35I, [2024-11-13T22:19:35.089527 #348] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
28122:19:35I, [2024-11-13T22:19:35.090757 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28222:19:35I, [2024-11-13T22:19:35.091536 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28322:19:36I, [2024-11-13T22:19:36.187859 #348] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
28422:19:36I, [2024-11-13T22:19:36.189063 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28522:19:36I, [2024-11-13T22:19:36.189910 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28622:19:36I, [2024-11-13T22:19:36.193414 #348] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
28722:19:36I, [2024-11-13T22:19:36.194982 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28822:19:36I, [2024-11-13T22:19:36.195806 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28922:19:36I, [2024-11-13T22:19:36.199543 #348] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
29022:19:36I, [2024-11-13T22:19:36.200688 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29122:19:36I, [2024-11-13T22:19:36.201505 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29222:19:36I, [2024-11-13T22:19:36.204835 #348] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
29322:19:36I, [2024-11-13T22:19:36.205937 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29422:19:36I, [2024-11-13T22:19:36.206759 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29522:19:53# [RSpecRunTime] Finishing example group spec/migrations/20241001115912_queue_set_total_number_of_vulnerabilities_for_existing_projects_spec.rb. It took 35.53 seconds. Expected to take 18.46 seconds.
29622:19:53# [RSpecRunTime] RSpec elapsed time: 1 minute 49.17 seconds. Current RSS: ~929M. Threads: 1. load average: 1.24 1.17 0.70 1/263 415
29722:19:53.
29822:19:53# [RSpecRunTime] Starting example group spec/migrations/20241008140250_add_email_participants_widget_definition_spec.rb. Expected to take 12.6 seconds.
29922:19:53AddEmailParticipantsWidgetDefinition
30022:20:01 behaves like migration that adds widget to work items definitions
30122:20:01 #up
30222:20:02main: == [advisory_lock_connection] object_id: 17609460, pg_backend_pid: 828
30322:20:02main: == 20241008140250 AddEmailParticipantsWidgetDefinition: migrating =============
30422:20:02main: == 20241008140250 AddEmailParticipantsWidgetDefinition: migrated (0.0390s) ====
30522:20:02main: == [advisory_lock_connection] object_id: 17609460, pg_backend_pid: 828
30622:20:02 creates widget definition in all types
30722:20:02 when the widget already exists
30822:20:03main: == [advisory_lock_connection] object_id: 17631220, pg_backend_pid: 830
30922:20:03main: == 20241008140250 AddEmailParticipantsWidgetDefinition: migrating =============
31022:20:03main: == 20241008140250 AddEmailParticipantsWidgetDefinition: migrated (0.0289s) ====
31122:20:03main: == [advisory_lock_connection] object_id: 17631220, pg_backend_pid: 830
31222:20:03 upserts the widget definitions and raises no error
31322:20:03# [RSpecRunTime] RSpec elapsed time: 1 minute 59.04 seconds. Current RSS: ~928M. Threads: 1. load average: 1.20 1.17 0.70 1/262 416
31422:20:03.
31522:20:03 #down
31622:20:04main: == [advisory_lock_connection] object_id: 17696660, pg_backend_pid: 832
31722:20:04main: == 20241008140250 AddEmailParticipantsWidgetDefinition: migrating =============
31822:20:04main: == 20241008140250 AddEmailParticipantsWidgetDefinition: migrated (0.0488s) ====
31922:20:04main: == [advisory_lock_connection] object_id: 17696660, pg_backend_pid: 832
32022:20:04 removes definitions for widget
32122:20:04# [RSpecRunTime] RSpec elapsed time: 2 minutes 0.12 second. Current RSS: ~928M. Threads: 1. load average: 1.20 1.17 0.70 1/262 417
32222:20:04.
32322:20:05I, [2024-11-13T22:20:05.646836 #348] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
32422:20:05I, [2024-11-13T22:20:05.648268 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32522:20:05I, [2024-11-13T22:20:05.649264 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32622:20:05I, [2024-11-13T22:20:05.653765 #348] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
32722:20:05I, [2024-11-13T22:20:05.655032 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32822:20:05I, [2024-11-13T22:20:05.655972 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32922:20:05I, [2024-11-13T22:20:05.659991 #348] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
33022:20:05I, [2024-11-13T22:20:05.661293 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33122:20:05I, [2024-11-13T22:20:05.662266 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33222:20:05I, [2024-11-13T22:20:05.666172 #348] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
33322:20:05I, [2024-11-13T22:20:05.667509 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33422:20:05I, [2024-11-13T22:20:05.668478 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33522:20:06I, [2024-11-13T22:20:06.798505 #348] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
33622:20:06I, [2024-11-13T22:20:06.800000 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33722:20:06I, [2024-11-13T22:20:06.800994 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33822:20:06I, [2024-11-13T22:20:06.805458 #348] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
33922:20:06I, [2024-11-13T22:20:06.806803 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34022:20:06I, [2024-11-13T22:20:06.807757 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34122:20:06I, [2024-11-13T22:20:06.811769 #348] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
34222:20:06I, [2024-11-13T22:20:06.812982 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34322:20:06I, [2024-11-13T22:20:06.813898 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34422:20:06I, [2024-11-13T22:20:06.817678 #348] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
34522:20:06I, [2024-11-13T22:20:06.818843 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34622:20:06I, [2024-11-13T22:20:06.819732 #348] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34722:20:24# [RSpecRunTime] Finishing example group spec/migrations/20241008140250_add_email_participants_widget_definition_spec.rb. It took 30.79 seconds. Expected to take 12.6 seconds.
34822:20:24# [RSpecRunTime] RSpec elapsed time: 2 minutes 20.4 seconds. Current RSS: ~926M. Threads: 1. load average: 1.14 1.15 0.71 1/262 418
34922:20:24.
35022:20:24# [RSpecRunTime] Starting example group spec/migrations/20231016194943_queue_delete_invalid_protected_tag_create_access_levels_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20231016194943_queue_delete_invalid_protected_tag_create_access_levels_spec.rb.
35122:20:24QueueDeleteInvalidProtectedTagCreateAccessLevels
35222:20:24 schedules a new batched migration (PENDING: Skipping because migration QueueDeleteInvalidProtectedTagCreateAccessLevels is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35322:20:24# [RSpecRunTime] Finishing example group spec/migrations/20231016194943_queue_delete_invalid_protected_tag_create_access_levels_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20231016194943_queue_delete_invalid_protected_tag_create_access_levels_spec.rb.
35422:20:24# [RSpecRunTime] Starting example group spec/migrations/20240108121335_copy_internal_ids_for_epics_and_issues_usage_on_groups_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240108121335_copy_internal_ids_for_epics_and_issues_usage_on_groups_spec.rb.
35522:20:24CopyInternalIdsForEpicsAndIssuesUsageOnGroups
35622:20:24 #on_update
35722:20:24 updates corresponding usage record between epics and issues (PENDING: Skipping because migration CopyInternalIdsForEpicsAndIssuesUsageOnGroups is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35822:20:24 #on_insert
35922:20:24 inserts corresponding usage record between epics and issues (PENDING: Skipping because migration CopyInternalIdsForEpicsAndIssuesUsageOnGroups is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36022:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240108121335_copy_internal_ids_for_epics_and_issues_usage_on_groups_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240108121335_copy_internal_ids_for_epics_and_issues_usage_on_groups_spec.rb.
36122:20:24# [RSpecRunTime] Starting example group spec/migrations/20240307051421_update_occupies_seat_on_member_roles_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240307051421_update_occupies_seat_on_member_roles_spec.rb.
36222:20:24UpdateOccupiesSeatOnMemberRoles
36322:20:24 #up
36422:20:24 updates occupies_seat to true for guest+ member roles (PENDING: Skipping because migration UpdateOccupiesSeatOnMemberRoles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36522:20:24 #down
36622:20:24 updates occupies_seat to false for all member roles (PENDING: Skipping because migration UpdateOccupiesSeatOnMemberRoles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36722:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240307051421_update_occupies_seat_on_member_roles_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240307051421_update_occupies_seat_on_member_roles_spec.rb.
36822:20:24# [RSpecRunTime] Starting example group spec/migrations/20240510113340_finalize_backfill_epic_basic_fields_to_work_item_record_corrected_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240510113340_finalize_backfill_epic_basic_fields_to_work_item_record_corrected_spec.rb.
36922:20:24FinalizeBackfillEpicBasicFieldsToWorkItemRecordCorrected
37022:20:24 #up
37122:20:24 ensures the migration is completed for self-managed instances (PENDING: Skipping because migration FinalizeBackfillEpicBasicFieldsToWorkItemRecordCorrected is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
37222:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240510113340_finalize_backfill_epic_basic_fields_to_work_item_record_corrected_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240510113340_finalize_backfill_epic_basic_fields_to_work_item_record_corrected_spec.rb.
37322:20:24# [RSpecRunTime] Starting example group spec/migrations/20240531113402_queue_backfill_epic_dates_to_work_item_dates_sources_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240531113402_queue_backfill_epic_dates_to_work_item_dates_sources_spec.rb.
37422:20:24QueueBackfillEpicDatesToWorkItemDatesSources
37522:20:24 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillEpicDatesToWorkItemDatesSources is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
37622:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240531113402_queue_backfill_epic_dates_to_work_item_dates_sources_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240531113402_queue_backfill_epic_dates_to_work_item_dates_sources_spec.rb.
37722:20:24# [RSpecRunTime] Starting example group spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb.
37822:20:24RemoveProjectStatisticsWikiSizeAndProjectIdIndex
37922:20:24 #up
38022:20:24 does nothing when not on gitlab.com (PENDING: Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
38122:20:24 prepares async index removal when on gitlab.com (PENDING: Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
38222:20:24 #down
38322:20:24 does nothing when not on gitlab.com (PENDING: Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
38422:20:24 unprepares async index removal when on gitlab.com (PENDING: Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
38522:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb.
38622:20:24# [RSpecRunTime] Starting example group spec/migrations/20240624142014_queue_backfill_merge_requests_closing_issues_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240624142014_queue_backfill_merge_requests_closing_issues_project_id_spec.rb.
38722:20:24QueueBackfillMergeRequestsClosingIssuesProjectId
38822:20:24 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillMergeRequestsClosingIssuesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
38922:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240624142014_queue_backfill_merge_requests_closing_issues_project_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240624142014_queue_backfill_merge_requests_closing_issues_project_id_spec.rb.
39022:20:24# [RSpecRunTime] Starting example group spec/migrations/20240716135032_queue_backfill_approval_group_rules_protected_branches_group_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240716135032_queue_backfill_approval_group_rules_protected_branches_group_id_spec.rb.
39122:20:24QueueBackfillApprovalGroupRulesProtectedBranchesGroupId
39222:20:24 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillApprovalGroupRulesProtectedBranchesGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
39322:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240716135032_queue_backfill_approval_group_rules_protected_branches_group_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240716135032_queue_backfill_approval_group_rules_protected_branches_group_id_spec.rb.
39422:20:24# [RSpecRunTime] Starting example group spec/migrations/20240808125149_queue_deduplicate_lfs_objects_projects_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240808125149_queue_deduplicate_lfs_objects_projects_spec.rb.
39522:20:24QueueDeduplicateLfsObjectsProjects
39622:20:24 schedules a new batched migration (PENDING: Skipping because migration QueueDeduplicateLfsObjectsProjects is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
39722:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240808125149_queue_deduplicate_lfs_objects_projects_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240808125149_queue_deduplicate_lfs_objects_projects_spec.rb.
39822:20:24# [RSpecRunTime] Starting example group spec/migrations/20240906131415_queue_backfill_ml_candidate_params_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240906131415_queue_backfill_ml_candidate_params_project_id_spec.rb.
39922:20:24QueueBackfillMlCandidateParamsProjectId
40022:20:24 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillMlCandidateParamsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
40122:20:24# [RSpecRunTime] Finishing example group spec/migrations/20240906131415_queue_backfill_ml_candidate_params_project_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240906131415_queue_backfill_ml_candidate_params_project_id_spec.rb.
40222:20:24# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240902114825_truncate_ci_finished_build_ch_sync_events_if_click_house_not_configured_spec.rb. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240902114825_truncate_ci_finished_build_ch_sync_events_if_click_house_not_configured_spec.rb.
40322:20:24TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured
40422:20:24 when ClickHouse is not configured
40522:20:24 truncates p_ci_finished_build_ch_sync_events table (PENDING: Skipping because migration TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
40622:20:24 when ClickHouse is configured
40722:20:24 does not truncate p_ci_finished_build_ch_sync_events table (PENDING: Skipping because migration TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
40822:20:24# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240902114825_truncate_ci_finished_build_ch_sync_events_if_click_house_not_configured_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240902114825_truncate_ci_finished_build_ch_sync_events_if_click_house_not_configured_spec.rb.
40922:20:24[TEST PROF INFO] EventProf results for sql.active_record
41022:20:24Total time: 01:06.957 of 02:12.295 (50.61%)
41122:20:24Total events: 60455
41222:20:24Top 5 slowest suites (by time):
41322:20:24QueueBackfill...ablesProjectId (./spec/migrations/20241003181432_queue_backfill_ci_pipeline_schedule_variables_project_id_spec.rb:6) – 00:33.947 (31690 / 1) of 01:05.476 (51.85%)
41422:20:24QueueSetTotal...istingProjects (./spec/migrations/20241001115912_queue_set_total_number_of_vulnerabilities_for_existing_projects_spec.rb:6) – 00:17.804 (16174 / 1) of 00:35.964 (49.51%)
41522:20:24AddEmailParti...dgetDefinition (./spec/migrations/20241008140250_add_email_participants_widget_definition_spec.rb:6) – 00:15.206 (12591 / 3) of 00:30.828 (49.32%)
41622:20:24Knapsack report was generated. Preview:
41722:20:24{
41822:20:24 "spec/migrations/20241003181432_queue_backfill_ci_pipeline_schedule_variables_project_id_spec.rb": 29.290679202999968,
41922:20:24 "spec/migrations/20241001115912_queue_set_total_number_of_vulnerabilities_for_existing_projects_spec.rb": 14.330921681000007,
42022:20:24 "spec/migrations/20241008140250_add_email_participants_widget_definition_spec.rb": 10.54786276499999
42122:20:24}
42222:20:24Knapsack global time execution for tests: 54s
42322:20:24Pending: (Failures listed here are expected and do not affect your suite's status)
42422:20:24 1) QueueDeleteInvalidProtectedTagCreateAccessLevels schedules a new batched migration
42522:20:24 # Skipping because migration QueueDeleteInvalidProtectedTagCreateAccessLevels is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42622:20:24 # ./spec/migrations/20231016194943_queue_delete_invalid_protected_tag_create_access_levels_spec.rb:9
42722:20:24 2) CopyInternalIdsForEpicsAndIssuesUsageOnGroups#on_update updates corresponding usage record between epics and issues
42822:20:24 # Skipping because migration CopyInternalIdsForEpicsAndIssuesUsageOnGroups is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42922:20:24 # ./spec/migrations/20240108121335_copy_internal_ids_for_epics_and_issues_usage_on_groups_spec.rb:21
43022:20:24 3) CopyInternalIdsForEpicsAndIssuesUsageOnGroups#on_insert inserts corresponding usage record between epics and issues
43122:20:24 # Skipping because migration CopyInternalIdsForEpicsAndIssuesUsageOnGroups is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43222:20:24 # ./spec/migrations/20240108121335_copy_internal_ids_for_epics_and_issues_usage_on_groups_spec.rb:44
43322:20:24 4) UpdateOccupiesSeatOnMemberRoles#up updates occupies_seat to true for guest+ member roles
43422:20:24 # Skipping because migration UpdateOccupiesSeatOnMemberRoles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43522:20:24 # ./spec/migrations/20240307051421_update_occupies_seat_on_member_roles_spec.rb:34
43622:20:24 5) UpdateOccupiesSeatOnMemberRoles#down updates occupies_seat to false for all member roles
43722:20:24 # Skipping because migration UpdateOccupiesSeatOnMemberRoles is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43822:20:24 # ./spec/migrations/20240307051421_update_occupies_seat_on_member_roles_spec.rb:46
43922:20:24 6) FinalizeBackfillEpicBasicFieldsToWorkItemRecordCorrected#up ensures the migration is completed for self-managed instances
44022:20:24 # Skipping because migration FinalizeBackfillEpicBasicFieldsToWorkItemRecordCorrected is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44122:20:24 # ./spec/migrations/20240510113340_finalize_backfill_epic_basic_fields_to_work_item_record_corrected_spec.rb:18
44222:20:24 7) QueueBackfillEpicDatesToWorkItemDatesSources schedules a new batched migration
44322:20:24 # Skipping because migration QueueBackfillEpicDatesToWorkItemDatesSources is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44422:20:24 # ./spec/migrations/20240531113402_queue_backfill_epic_dates_to_work_item_dates_sources_spec.rb:9
44522:20:24 8) RemoveProjectStatisticsWikiSizeAndProjectIdIndex#up does nothing when not on gitlab.com
44622:20:24 # Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44722:20:24 # ./spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb:16
44822:20:24 9) RemoveProjectStatisticsWikiSizeAndProjectIdIndex#up prepares async index removal when on gitlab.com
44922:20:24 # Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
45022:20:24 # ./spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb:20
45122:20:24 10) RemoveProjectStatisticsWikiSizeAndProjectIdIndex#down does nothing when not on gitlab.com
45222:20:24 # Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
45322:20:24 # ./spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb:36
45422:20:24 11) RemoveProjectStatisticsWikiSizeAndProjectIdIndex#down unprepares async index removal when on gitlab.com
45522:20:24 # Skipping because migration RemoveProjectStatisticsWikiSizeAndProjectIdIndex is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
45622:20:24 # ./spec/migrations/20240611122408_remove_project_statistics_wiki_size_and_project_id_index_spec.rb:40
45722:20:24 12) QueueBackfillMergeRequestsClosingIssuesProjectId schedules a new batched migration
45822:20:24 # Skipping because migration QueueBackfillMergeRequestsClosingIssuesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
45922:20:24 # ./spec/migrations/20240624142014_queue_backfill_merge_requests_closing_issues_project_id_spec.rb:10
46022:20:24 13) QueueBackfillApprovalGroupRulesProtectedBranchesGroupId schedules a new batched migration
46122:20:24 # Skipping because migration QueueBackfillApprovalGroupRulesProtectedBranchesGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
46222:20:24 # ./spec/migrations/20240716135032_queue_backfill_approval_group_rules_protected_branches_group_id_spec.rb:9
46322:20:24 14) QueueDeduplicateLfsObjectsProjects schedules a new batched migration
46422:20:24 # Skipping because migration QueueDeduplicateLfsObjectsProjects is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
46522:20:24 # ./spec/migrations/20240808125149_queue_deduplicate_lfs_objects_projects_spec.rb:9
46622:20:24 15) QueueBackfillMlCandidateParamsProjectId schedules a new batched migration
46722:20:24 # Skipping because migration QueueBackfillMlCandidateParamsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
46822:20:24 # ./spec/migrations/20240906131415_queue_backfill_ml_candidate_params_project_id_spec.rb:9
46922:20:24 16) TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured when ClickHouse is not configured truncates p_ci_finished_build_ch_sync_events table
47022:20:24 # Skipping because migration TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
47122:20:24 # ./spec/migrations/db/post_migrate/20240902114825_truncate_ci_finished_build_ch_sync_events_if_click_house_not_configured_spec.rb:41
47222:20:24 17) TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured when ClickHouse is configured does not truncate p_ci_finished_build_ch_sync_events table
47322:20:24 # Skipping because migration TruncateCiFinishedBuildChSyncEventsIfClickHouseNotConfigured is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
47422:20:24 # ./spec/migrations/db/post_migrate/20240902114825_truncate_ci_finished_build_ch_sync_events_if_click_house_not_configured_spec.rb:52
47522:20:24Finished in 2 minutes 20.5 seconds (files took 1 minute 32.39 seconds to load)
47622:20:2422 examples, 0 failures, 17 pending
47722:20:24Randomized with seed 45987
47822:20:29RSpec exited with 0.
47922:20:29No examples to retry, congrats!
480 22:20:30 Running after_script 00:03
48122:20:30Running after script...
48222:20:30$ source scripts/utils.sh
48322:20:30$ log_disk_usage
48422:20:30*******************************************************
48522:20:30This runner currently has 79G free disk space.
48622:20:30*******************************************************
487 22:20:30 Disk usage detail 00:01
54022:20:31$ bundle exec gem list gitlab_quality-test_tooling
54122:20:32gitlab_quality-test_tooling (2.1.0)
54222:20:32$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
543 22:20:32 Report test failures 00:00
54522:20:32$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
546 22:20:32 Report test flakiness 00:00
54822:20:32$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
549 22:20:32 Report test slowness 00:00
55122:20:32$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
552 22:20:32 Report test slowness in MR note 00:00
55422:20:32$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
555 22:20:32 Report test files close to timing out 00:00
55722:20:32$ tooling/bin/push_job_metrics || true
55822:20:33[job-metrics] Feature disabled because CI_JOB_METRICS_ENABLED is not set to true.
559 22:20:33 Saving cache for successful job 00:01
56022:20:34Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-3 due to policy
561 22:20:34 Uploading artifacts for successful job 00:05
56222:20:34Uploading artifacts...
56322:20:35WARNING: auto_explain/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss)
56422:20:35coverage/: found 5 matching artifact files and directories
56522:20:35crystalball/: found 2 matching artifact files and directories
56622:20:35WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss)
56722:20:35knapsack/: found 4 matching artifact files and directories
56822:20:35rspec/: found 16 matching artifact files and directories
56922:20:35WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss)
57022:20:35log/*.log: found 13 matching artifact files and directories
57122:20:35WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353300889/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
57222:20:35WARNING: Retrying... context=artifacts-uploader error=request redirected
57322:20:37Uploading artifacts as "archive" to coordinator... 201 Created id=8353300889 responseStatus=201 Created token=glcbt-66
57422:20:37Uploading artifacts...
57522:20:37rspec/rspec-*.xml: found 1 matching artifact files and directories
57622:20:37WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353300889/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
57722:20:37WARNING: Retrying... context=artifacts-uploader error=request redirected
57822:20:38Uploading artifacts as "junit" to coordinator... 201 Created id=8353300889 responseStatus=201 Created token=glcbt-66
579 22:20:39 Cleaning up project directory and file based variables 00:00
58022:20:46Job succeeded