Skip to content
Snippets Groups Projects

rspec migration pg14 7/26

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
122:06:44Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
222:06:44 on blue-6.private.runners-manager.gitlab.com/gitlab.com/gitlab-org mKJTFBei, system ID: s_90a66139ec06
322:06:44 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:06:44Resolving secrets
5 22:06:44 Preparing the "docker+machine" executor 00:08
622:06:44Using 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:46Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
822:06:46Authenticating with credentials from job payload (GitLab Registry)
922:06:46Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:06:47Using 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:47WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:06:47WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:06:47Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1422:06:47Authenticating with credentials from job payload (GitLab Registry)
1522:06:47Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:06:47Using 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:48Starting service redis:6.2-alpine...
1822:06:48Pulling docker image redis:6.2-alpine ...
1922:06:48Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2022:06:49Waiting for services to be up and running (timeout 30 seconds)...
2122:06:52Authenticating with credentials from job payload (GitLab Registry)
2222:06:52Pulling 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:52Using 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:52 Preparing environment 00:01
2522:06:52Running on runner-mkjtfbei-project-278964-concurrent-0 via runner-mkjtfbei-private-1731517693-d8d55db9...
26 22:06:53 Getting source from Git repository 00:01
2722:06:53Skipping Git repository setup
2822:06:53Skipping Git checkout
2922:06:53Skipping Git submodules setup
30 22:06:54 Restoring cache 00:19
3122:06:54Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
3222:06:54Downloading 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:07Successfully extracted cache
34 22:07:13 Downloading artifacts 00:48
3522:07:14Downloading artifacts for clone-gitlab-repo (8353286583)...
3622:07:18Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286583 responseStatus=200 OK token=glcbt-66
3722:07:18WARNING: Part of .git directory is on the list of files to extract
3822:07:18WARNING: This may introduce unexpected problems
3922:07:30Downloading artifacts for compile-test-assets (8353286630)...
4022:07:34Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286630 responseStatus=200 OK token=glcbt-66
4122:07:40Downloading artifacts for detect-tests (8353286649)...
4222:07:41Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286649 responseStatus=200 OK token=glcbt-66
4322:07:41Downloading artifacts for retrieve-tests-metadata (8353286664)...
4422:07:42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286664 responseStatus=200 OK token=glcbt-66
4522:07:42Downloading artifacts for setup-test-env (8353286635)...
4622:07:48Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353286635 responseStatus=200 OK token=glcbt-66
47 22:08:01 Executing "step_script" stage of the job script
4822:08:01Using 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:02$ echo $FOSS_ONLY
5022:08:02$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5122:08:02$ export GOPATH=$CI_PROJECT_DIR/.go
5222:08:02$ mkdir -p $GOPATH
5322:08:02$ source scripts/utils.sh
5422:08:02$ log_disk_usage "true"
5522:08:02*******************************************************
5622:08:02This runner currently has 72G free disk space.
5722:08:02*******************************************************
58 22:08:02 Disk usage detail 00:04
5922:08:02df -h
6022:08:02Filesystem Size Used Avail Use% Mounted on
6122:08:02overlay 95G 24G 72G 25% /
6222:08:02tmpfs 64M 0 64M 0% /dev
6322:08:02shm 64M 0 64M 0% /dev/shm
6422:08:02/dev/sda1 95G 24G 72G 25% /cache
6522:08:02tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6622:08:02du -h -d 1
6722:08:027.4M ./workhorse
6822:08:02148K ./bin
6922:08:021.2M ./.gitlab
7022:08:02167M ./.git
7122:08:024.0K ./builds
7222:08:0228M ./lib
7322:08:028.0K ./.vscode
7422:08:02560K ./storybook
7522:08:0224K ./log
7622:08:021.8M ./data
7722:08:0211M ./qa
7822:08:023.2M ./.rubocop_todo
7922:08:02856M ./vendor
8022:08:021023M ./public
8122:08:0262M ./doc
8222:08:02484K ./danger
8322:08:0232K ./rspec
8422:08:02556K ./tooling
8522:08:02145M ./ee
8622:08:021.3M ./knapsack
8722:08:02300K ./patches
8822:08:024.3M ./changelogs
8922:08:0220K ./haml_lint
9022:08:0228K ./metrics_server
9122:08:02108K ./keeps
9222:08:0236K ./shared
9322:08:0244K ./generator_templates
9422:08:021.3M ./scripts
9522:08:02763M ./tmp
9622:08:02127M ./spec
9722:08:0220K ./file_hooks
9822:08:0232K ./crystalball
9922:08:0216K ./sidekiq_cluster
10022:08:0217M ./gems
10122:08:021.2M ./fixtures
10222:08:02964K ./rubocop
10322:08:024.0K ./.go
10422:08:0233M ./db
10522:08:0212K ./.github
10622:08:02129M ./locale
10722:08:0220M ./config
10822:08:0282M ./app
10922:08:0220K ./.lefthook
11022:08:023.5G .
11122:08:02$ source scripts/prepare_build.sh
112 22:08:02 Installing gems 00:21
14322:08:23Using decomposed database config (config/database.yml.decomposed-postgresql)
14422:08:23Geo DB won't be set up.
14522:08:23Embedding DB won't be set up.
146 22:08:23 Setting up DBs 01:22
15922:09:45$ source ./scripts/rspec_helpers.sh
16022:09:45$ run_timed_command "gem install knapsack --no-document"
16122:09:45$ gem install knapsack --no-document
16222:09:46Successfully installed knapsack-4.0.0
16322:09:461 gem installed
16422:09:45==> 'gem install knapsack --no-document' succeeded in 1 seconds.
16522:09:46$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
166 22:09:46 Spawning Gitaly 00:05
17022:09:51$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17122:09:51$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17222:09:51$ tooling/bin/create_job_metrics_file || true
17322:09:54[job-metrics] Creating the job metrics file for the CI/CD job.
17422:09:54$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
175 22:09:54 RSpec 05:48
17622:09:54[22:09:54] Starting rspec_parallelized_job
17722:09:46RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
17822:09:54KNAPSACK_GENERATE_REPORT: true
17922:09:54FLAKY_RSPEC_GENERATE_REPORT: true
18022:09:54KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
18122:09:54KNAPSACK_LOG_LEVEL: debug
18222:09:54KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_7_26_278964_report.json
18322:09:54FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18422:09:54FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_7_26_278964_report.json
18522:09:54NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_7_26_278964_report.json
18622:09:54RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8353287032.txt
18722:09:54CRYSTALBALL:
18822:09:54RSPEC_TESTS_MAPPING_ENABLED:
18922:09:54RSPEC_TESTS_FILTER_FILE:
19022:09:54Shell set options (set -o) enabled:
19122:09:54braceexpand on
19222:09:54hashall on
19322:09:54interactive-comments on
19422:09:54pipefail on
19522:10:01Knapsack report generator started!
19622:09:54/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:39DEPRECATION 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:52warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
19922:10:52Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20022:10:56Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
20122:10:56# [RSpecRunTime] Starting RSpec timer...
20222:10:56[TEST PROF INFO] EventProf enabled (sql.active_record)
20322:10:56unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20422:10:56unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20522:11:05Test environment set up in 2.976489734 seconds
20622:11:05# [RSpecRunTime] Starting example group spec/migrations/20241007171237_requeue_backfill_p_ci_runner_machine_builds_project_id_spec.rb. Expected to take 37.89 seconds.
20722:11:05RequeueBackfillPCiRunnerMachineBuildsProjectId
20822:11:32ci: == [advisory_lock_connection] object_id: 6939700, pg_backend_pid: 455
20922:11:32ci: == 20241007171237 RequeueBackfillPCiRunnerMachineBuildsProjectId: migrating ===
21022:11:32ci: == 20241007171237 RequeueBackfillPCiRunnerMachineBuildsProjectId: migrated (0.0268s)
21122:11:32ci: == [advisory_lock_connection] object_id: 6939700, pg_backend_pid: 455
21222:11:38 does not schedule a new batched migration
21322:11:38 when executed on .com
21422:11:39ci: == [advisory_lock_connection] object_id: 7911340, pg_backend_pid: 463
21522:11:39ci: == 20241007171237 RequeueBackfillPCiRunnerMachineBuildsProjectId: migrating ===
21622:11:39ci: == 20241007171237 RequeueBackfillPCiRunnerMachineBuildsProjectId: migrated (0.2603s)
21722:11:39ci: == [advisory_lock_connection] object_id: 7911340, pg_backend_pid: 463
21822:11:45 schedules a new batched migration
21922:11:48I, [2024-11-13T22:11:48.944043 #343] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
22022:11:48I, [2024-11-13T22:11:48.945389 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22122:11:48I, [2024-11-13T22:11:48.946261 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22222:11:48I, [2024-11-13T22:11:48.949988 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
22322:11:48I, [2024-11-13T22:11:48.951141 #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:48I, [2024-11-13T22:11:48.952030 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22522:11:48I, [2024-11-13T22:11:48.955621 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
22622:11:48I, [2024-11-13T22:11:48.956695 #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:48I, [2024-11-13T22:11:48.957495 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22822:11:48I, [2024-11-13T22:11:48.960949 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
22922:11:48I, [2024-11-13T22:11:48.962054 #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:48I, [2024-11-13T22:11:48.962860 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23122:11:50I, [2024-11-13T22:11:50.207986 #343] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
23222:11:50I, [2024-11-13T22:11:50.209635 #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:50I, [2024-11-13T22:11:50.210745 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23422:11:50I, [2024-11-13T22:11:50.215087 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
23522:11:50I, [2024-11-13T22:11:50.216378 #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:50I, [2024-11-13T22:11:50.217276 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23722:11:50I, [2024-11-13T22:11:50.221383 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
23822:11:50I, [2024-11-13T22:11:50.222662 #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:50I, [2024-11-13T22:11:50.223606 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24022:11:50I, [2024-11-13T22:11:50.227611 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
24122:11:50I, [2024-11-13T22:11:50.228939 #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:50I, [2024-11-13T22:11:50.229974 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24322:12:05I, [2024-11-13T22:12:05.604721 #343] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes
24422:12:05I, [2024-11-13T22:12:05.960217 #343] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes
24522:12:06I, [2024-11-13T22:12:06.450849 #343] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignment_versions': Lock Writes
24622:12:06I, [2024-11-13T22:12:06.485596 #343] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes
24722:12:06I, [2024-11-13T22:12:06.646354 #343] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes
24822:12:07I, [2024-11-13T22:12:07.414451 #343] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes
24922:12:07I, [2024-11-13T22:12:07.453704 #343] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes
25022:12:07I, [2024-11-13T22:12:07.802190 #343] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes
25122:12:07I, [2024-11-13T22:12:07.997183 #343] INFO -- : Database: 'ci', Table: 'members_deletion_schedules': Lock Writes
25222:12:08I, [2024-11-13T22:12:08.556420 #343] INFO -- : Database: 'ci', Table: 'group_scim_identities': Lock Writes
25322:12:08I, [2024-11-13T22:12:08.558103 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25422:12:08I, [2024-11-13T22:12:08.558952 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25522:12:08I, [2024-11-13T22:12:08.594530 #343] INFO -- : Database: 'ci', Table: 'group_scim_auth_access_tokens': Lock Writes
25622:12:08I, [2024-11-13T22:12:08.595785 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25722:12:08I, [2024-11-13T22:12:08.596578 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25822:12:08I, [2024-11-13T22:12:08.929282 #343] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes
25922:12:25# [RSpecRunTime] Finishing example group spec/migrations/20241007171237_requeue_backfill_p_ci_runner_machine_builds_project_id_spec.rb. It took 1 minute 19.66 seconds. Expected to take 37.89 seconds.
26022:12:25# [RSpecRunTime] RSpec elapsed time: 1 minute 28.95 seconds. Current RSS: ~1149M. Threads: 1. load average: 0.98 0.78 0.49 1/277 389
26122:12:25.
26222:12:25# [RSpecRunTime] Starting example group spec/migrations/20241008030404_queue_backfill_events_personal_namespace_id_spec.rb. Expected to take 17.07 seconds.
26322:12:25QueueBackfillEventsPersonalNamespaceId
26422:12:36main: == [advisory_lock_connection] object_id: 13746980, pg_backend_pid: 787
26522:12:36main: == 20241008030404 QueueBackfillEventsPersonalNamespaceId: migrating ===========
26622:12:36main: == 20241008030404 QueueBackfillEventsPersonalNamespaceId: migrated (0.2819s) ==
26722:12:36main: == [advisory_lock_connection] object_id: 13746980, pg_backend_pid: 787
26822:12:41 schedules a new batched migration
26922:12:45I, [2024-11-13T22:12:45.114191 #343] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
27022:12:45I, [2024-11-13T22:12:45.115889 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27122:12:45I, [2024-11-13T22:12:45.142234 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27222:12:45I, [2024-11-13T22:12:45.146566 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
27322:12:45I, [2024-11-13T22:12:45.147712 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27422:12:45I, [2024-11-13T22:12:45.148602 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27522:12:45I, [2024-11-13T22:12:45.153687 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
27622:12:45I, [2024-11-13T22:12:45.155092 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27722:12:45I, [2024-11-13T22:12:45.155985 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27822:12:45I, [2024-11-13T22:12:45.159961 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
27922:12:45I, [2024-11-13T22:12:45.161107 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28022:12:45I, [2024-11-13T22:12:45.161955 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28122:12:46I, [2024-11-13T22:12:46.275378 #343] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
28222:12:46I, [2024-11-13T22:12:46.276654 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28322:12:46I, [2024-11-13T22:12:46.277561 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28422:12:46I, [2024-11-13T22:12:46.281436 #343] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
28522:12:46I, [2024-11-13T22:12:46.282586 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28622:12:46I, [2024-11-13T22:12:46.283501 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28722:12:46I, [2024-11-13T22:12:46.287344 #343] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
28822:12:46I, [2024-11-13T22:12:46.288642 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28922:12:46I, [2024-11-13T22:12:46.289512 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29022:12:46I, [2024-11-13T22:12:46.293377 #343] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
29122:12:46I, [2024-11-13T22:12:46.294563 #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:12:46I, [2024-11-13T22:12:46.295435 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29322:13:06# [RSpecRunTime] Finishing example group spec/migrations/20241008030404_queue_backfill_events_personal_namespace_id_spec.rb. It took 40.86 seconds. Expected to take 17.07 seconds.
29422:13:06# [RSpecRunTime] RSpec elapsed time: 2 minutes 9.86 seconds. Current RSS: ~1151M. Threads: 1. load average: 1.02 0.81 0.51 1/274 390
29522:13:06.
29622:13:06# [RSpecRunTime] Starting example group spec/migrations/20241015080747_queue_backfill_dependency_proxy_manifest_states_group_id_spec.rb. Expected to take 11.83 seconds.
29722:13:06QueueBackfillDependencyProxyManifestStatesGroupId
29822:13:12main: == [advisory_lock_connection] object_id: 17138960, pg_backend_pid: 799
29922:13:12main: == 20241015080747 QueueBackfillDependencyProxyManifestStatesGroupId: migrating
30022:13:12main: == 20241015080747 QueueBackfillDependencyProxyManifestStatesGroupId: migrated (0.0868s)
30122:13:12main: == [advisory_lock_connection] object_id: 17138960, pg_backend_pid: 799
30222:13:17 schedules a new batched migration
30322:13:35# [RSpecRunTime] Finishing example group spec/migrations/20241015080747_queue_backfill_dependency_proxy_manifest_states_group_id_spec.rb. It took 29.02 seconds. Expected to take 11.83 seconds.
30422:13:35# [RSpecRunTime] RSpec elapsed time: 2 minutes 38.92 seconds. Current RSS: ~1154M. Threads: 1. load average: 0.94 0.81 0.53 1/275 391
30522:13:35.
30622:13:35# [RSpecRunTime] Starting example group spec/migrations/20230929155123_migrate_disable_merge_trains_value_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20230929155123_migrate_disable_merge_trains_value_spec.rb.
30722:13:35MigrateDisableMergeTrainsValue
30822:13:35 #up
30922:13:35 migrates the flag value into the setting value (PENDING: Skipping because migration MigrateDisableMergeTrainsValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31022:13:35 #down
31122:13:35 reverts the migration (PENDING: Skipping because migration MigrateDisableMergeTrainsValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31222:13:35# [RSpecRunTime] Finishing example group spec/migrations/20230929155123_migrate_disable_merge_trains_value_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20230929155123_migrate_disable_merge_trains_value_spec.rb.
31322:13:35# [RSpecRunTime] Starting example group spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb.
31422:13:35QueueBackfillPartitionIdCiPipelineChatData
31522:13:35 #up
31622:13:35 with migration present
31722:13:35 when migration finished successfully
31822:13:35 does not raise exception (PENDING: Skipping because migration QueueBackfillPartitionIdCiPipelineChatData is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31922:13:35 schedules background jobs for each batch of ci_pipeline_chat_data (PENDING: Skipping because migration QueueBackfillPartitionIdCiPipelineChatData is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32022:13:35 #down
32122:13:35 deletes all batched migration records (PENDING: Skipping because migration QueueBackfillPartitionIdCiPipelineChatData is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32222:13:35# [RSpecRunTime] Finishing example group spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb.
32322:13:35# [RSpecRunTime] Starting example group spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb.
32422:13:35QueueUpdateSbomComponentsNameBasedOnPep503
32522:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueUpdateSbomComponentsNameBasedOnPep503 is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32622:13:35# [RSpecRunTime] Finishing example group spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb.
32722:13:35# [RSpecRunTime] Starting example group spec/migrations/20240502044605_remove_create_empty_embeddings_records_worker_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240502044605_remove_create_empty_embeddings_records_worker_spec.rb.
32822:13:35RemoveCreateEmptyEmbeddingsRecordsWorker
32922:13:35 when cron job exists
33022:13:35 deletes the cron job and enqueued jobs (PENDING: Skipping because migration RemoveCreateEmptyEmbeddingsRecordsWorker 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:35 when cron job does not exist
33222:13:35 no-ops (PENDING: Skipping because migration RemoveCreateEmptyEmbeddingsRecordsWorker 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:35# [RSpecRunTime] Finishing example group spec/migrations/20240502044605_remove_create_empty_embeddings_records_worker_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240502044605_remove_create_empty_embeddings_records_worker_spec.rb.
33422:13:35# [RSpecRunTime] Starting example group spec/migrations/20240529112047_queue_backfill_wiki_page_slugs_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240529112047_queue_backfill_wiki_page_slugs_project_id_spec.rb.
33522:13:35QueueBackfillWikiPageSlugsProjectId
33622:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillWikiPageSlugsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33722:13:35# [RSpecRunTime] Finishing example group spec/migrations/20240529112047_queue_backfill_wiki_page_slugs_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240529112047_queue_backfill_wiki_page_slugs_project_id_spec.rb.
33822:13:35# [RSpecRunTime] Starting example group spec/migrations/20240607105705_queue_backfill_dast_site_validations_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240607105705_queue_backfill_dast_site_validations_project_id_spec.rb.
33922:13:35QueueBackfillDastSiteValidationsProjectId
34022:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillDastSiteValidationsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34122:13:35# [RSpecRunTime] Finishing example group spec/migrations/20240607105705_queue_backfill_dast_site_validations_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240607105705_queue_backfill_dast_site_validations_project_id_spec.rb.
34222:13:35# [RSpecRunTime] Starting example group spec/migrations/20240618123929_queue_backfill_epic_issues_namespace_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240618123929_queue_backfill_epic_issues_namespace_id_spec.rb.
34322:13:35QueueBackfillEpicIssuesNamespaceId
34422:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillEpicIssuesNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34522:13:35# [RSpecRunTime] Finishing example group spec/migrations/20240618123929_queue_backfill_epic_issues_namespace_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240618123929_queue_backfill_epic_issues_namespace_id_spec.rb.
34622:13:35# [RSpecRunTime] Starting example group spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb.
34722:13:35RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync
34822:13:35 #up
34922:13:35 does nothing when not on gitlab.com (PENDING: Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync 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:35 removes the index when on gitlab.com (PENDING: Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35122:13:35 #down
35222:13:35 does nothing when not on gitlab.com (PENDING: Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35322:13:35 re-adds the index when on gitlab.com (PENDING: Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync 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:35# [RSpecRunTime] Finishing example group spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb.
35522:13:35# [RSpecRunTime] Starting example group spec/migrations/20240801195253_queue_backfill_reserved_storage_bytes_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240801195253_queue_backfill_reserved_storage_bytes_spec.rb.
35622:13:35QueueBackfillReservedStorageBytes
35722:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillReservedStorageBytes 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:35# [RSpecRunTime] Finishing example group spec/migrations/20240801195253_queue_backfill_reserved_storage_bytes_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240801195253_queue_backfill_reserved_storage_bytes_spec.rb.
35922:13:35# [RSpecRunTime] Starting example group spec/migrations/20240829160537_queue_backfill_p_ci_runner_machine_builds_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240829160537_queue_backfill_p_ci_runner_machine_builds_project_id_spec.rb.
36022:13:35QueueBackfillPCiRunnerMachineBuildsProjectId
36122:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillPCiRunnerMachineBuildsProjectId 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:35 when executed on .com
36322:13:35 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillPCiRunnerMachineBuildsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36422:13:35# [RSpecRunTime] Finishing example group spec/migrations/20240829160537_queue_backfill_p_ci_runner_machine_builds_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240829160537_queue_backfill_p_ci_runner_machine_builds_project_id_spec.rb.
36522:13:35# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240703134651_finalize_backfill_merge_request_review_llm_summaries_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240703134651_finalize_backfill_merge_request_review_llm_summaries_project_id_spec.rb.
36622:13:35FinalizeBackfillMergeRequestReviewLlmSummariesProjectId
36722:13:35 #up
36822:13:35 ensures the migration is completed for self-managed instances (PENDING: Skipping because migration FinalizeBackfillMergeRequestReviewLlmSummariesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
36922:13:35# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240703134651_finalize_backfill_merge_request_review_llm_summaries_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240703134651_finalize_backfill_merge_request_review_llm_summaries_project_id_spec.rb.
37022:13:38auto_explain log contains 841 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-7-26.343.main.ndjson.gz
37122:13:38took 2.523791744
37222:13:39auto_explain log contains 842 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-7-26.343.ci.ndjson.gz
37322:13:39took 1.447618665
37422:13:39[TEST PROF INFO] EventProf results for sql.active_record
37522:13:39Total time: 01:15.961 of 02:29.881 (50.68%)
37622:13:39Total events: 60263
37722:13:39Top 5 slowest suites (by time):
37822:13:39RequeueBackfi...uildsProjectId (./spec/migrations/20241007171237_requeue_backfill_p_ci_runner_machine_builds_project_id_spec.rb:6) – 00:40.424 (33235 / 2) of 01:19.705 (50.72%)
37922:13:39QueueBackfill...nalNamespaceId (./spec/migrations/20241008030404_queue_backfill_events_personal_namespace_id_spec.rb:6) – 00:22.106 (15395 / 1) of 00:40.907 (54.04%)
38022:13:39QueueBackfill...tStatesGroupId (./spec/migrations/20241015080747_queue_backfill_dependency_proxy_manifest_states_group_id_spec.rb:6) – 00:13.392 (11556 / 1) of 00:29.065 (46.08%)
38122:13:39QueueBackfill...SlugsProjectId (./spec/migrations/20240529112047_queue_backfill_wiki_page_slugs_project_id_spec.rb:6) – 00:00.003 (7 / 1) of 00:00.018 (19.97%)
38222:13:39RemoveIdxProj...dProjectIdSync (./spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb:7) – 00:00.003 (7 / 4) of 00:00.019 (19.3%)
38322:13:39Knapsack report was generated. Preview:
38422:13:39{
38522:13:39 "spec/migrations/20241007171237_requeue_backfill_p_ci_runner_machine_builds_project_id_spec.rb": 40.70575924500008,
38622:13:39 "spec/migrations/20241008030404_queue_backfill_events_personal_namespace_id_spec.rb": 16.728534988997126,
38722:13:39 "spec/migrations/20241015080747_queue_backfill_dependency_proxy_manifest_states_group_id_spec.rb": 11.63103388899981
38822:13:39}
38922:13:39Knapsack global time execution for tests: 01m 09s
39022:13:39Pending: (Failures listed here are expected and do not affect your suite's status)
39122:13:39 1) MigrateDisableMergeTrainsValue#up migrates the flag value into the setting value
39222:13:39 # Skipping because migration MigrateDisableMergeTrainsValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39322:13:39 # ./spec/migrations/20230929155123_migrate_disable_merge_trains_value_spec.rb:61
39422:13:39 2) MigrateDisableMergeTrainsValue#down reverts the migration
39522:13:39 # Skipping because migration MigrateDisableMergeTrainsValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39622:13:39 # ./spec/migrations/20230929155123_migrate_disable_merge_trains_value_spec.rb:72
39722:13:39 3) QueueBackfillPartitionIdCiPipelineChatData#up with migration present when migration finished successfully does not raise exception
39822:13:39 # Skipping because migration QueueBackfillPartitionIdCiPipelineChatData is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39922:13:39 # ./spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb:29
40022:13:39 4) QueueBackfillPartitionIdCiPipelineChatData#up with migration present when migration finished successfully schedules background jobs for each batch of ci_pipeline_chat_data
40122:13:39 # Skipping because migration QueueBackfillPartitionIdCiPipelineChatData is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40222:13:39 # ./spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb:33
40322:13:39 5) QueueBackfillPartitionIdCiPipelineChatData#down deletes all batched migration records
40422:13:39 # Skipping because migration QueueBackfillPartitionIdCiPipelineChatData is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40522:13:39 # ./spec/migrations/20231218092401_queue_backfill_partition_id_ci_pipeline_chat_data_spec.rb:49
40622:13:39 6) QueueUpdateSbomComponentsNameBasedOnPep503 schedules a new batched migration
40722:13:39 # Skipping because migration QueueUpdateSbomComponentsNameBasedOnPep503 is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40822:13:39 # ./spec/migrations/20240223130548_queue_update_sbom_components_name_based_on_pep503_spec.rb:10
40922:13:39 7) RemoveCreateEmptyEmbeddingsRecordsWorker when cron job exists deletes the cron job and enqueued jobs
41022:13:39 # Skipping because migration RemoveCreateEmptyEmbeddingsRecordsWorker is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41122:13:39 # ./spec/migrations/20240502044605_remove_create_empty_embeddings_records_worker_spec.rb:43
41222:13:39 8) RemoveCreateEmptyEmbeddingsRecordsWorker when cron job does not exist no-ops
41322:13:39 # Skipping because migration RemoveCreateEmptyEmbeddingsRecordsWorker is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41422:13:39 # ./spec/migrations/20240502044605_remove_create_empty_embeddings_records_worker_spec.rb:54
41522:13:39 9) QueueBackfillWikiPageSlugsProjectId schedules a new batched migration
41622:13:39 # Skipping because migration QueueBackfillWikiPageSlugsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41722:13:39 # ./spec/migrations/20240529112047_queue_backfill_wiki_page_slugs_project_id_spec.rb:9
41822:13:39 10) QueueBackfillDastSiteValidationsProjectId schedules a new batched migration
41922:13:39 # Skipping because migration QueueBackfillDastSiteValidationsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42022:13:39 # ./spec/migrations/20240607105705_queue_backfill_dast_site_validations_project_id_spec.rb:9
42122:13:39 11) QueueBackfillEpicIssuesNamespaceId schedules a new batched migration
42222:13:39 # Skipping because migration QueueBackfillEpicIssuesNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42322:13:39 # ./spec/migrations/20240618123929_queue_backfill_epic_issues_namespace_id_spec.rb:9
42422:13:39 12) RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync#up does nothing when not on gitlab.com
42522:13:39 # Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42622:13:39 # ./spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb:13
42722:13:39 13) RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync#up removes the index when on gitlab.com
42822:13:39 # Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42922:13:39 # ./spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb:19
43022:13:39 14) RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync#down does nothing when not on gitlab.com
43122:13:39 # Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43222:13:39 # ./spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb:27
43322:13:39 15) RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync#down re-adds the index when on gitlab.com
43422:13:39 # Skipping because migration RemoveIdxProjectStatisticsWikiSizeAndProjectIdSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43522:13:39 # ./spec/migrations/20240708130252_remove_idx_project_statistics_wiki_size_and_project_id_sync_spec.rb:33
43622:13:39 16) QueueBackfillReservedStorageBytes schedules a new batched migration
43722:13:39 # Skipping because migration QueueBackfillReservedStorageBytes is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
43822:13:39 # ./spec/migrations/20240801195253_queue_backfill_reserved_storage_bytes_spec.rb:9
43922:13:39 17) QueueBackfillPCiRunnerMachineBuildsProjectId schedules a new batched migration
44022:13:39 # Skipping because migration QueueBackfillPCiRunnerMachineBuildsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44122:13:39 # ./spec/migrations/20240829160537_queue_backfill_p_ci_runner_machine_builds_project_id_spec.rb:10
44222:13:39 18) QueueBackfillPCiRunnerMachineBuildsProjectId when executed on .com schedules a new batched migration
44322:13:39 # Skipping because migration QueueBackfillPCiRunnerMachineBuildsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44422:13:39 # ./spec/migrations/20240829160537_queue_backfill_p_ci_runner_machine_builds_project_id_spec.rb:35
44522:13:39 19) FinalizeBackfillMergeRequestReviewLlmSummariesProjectId#up ensures the migration is completed for self-managed instances
44622:13:39 # Skipping because migration FinalizeBackfillMergeRequestReviewLlmSummariesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
44722:13:39 # ./spec/migrations/db/post_migrate/20240703134651_finalize_backfill_merge_request_review_llm_summaries_project_id_spec.rb:8
44822:13:39Finished in 2 minutes 43.6 seconds (files took 58.18 seconds to load)
44922:13:3923 examples, 0 failures, 19 pending
45022:13:39Randomized with seed 62229
45122:13:48[TEST PROF INFO] Time spent in factories: 00:00.249 (0.15% of total time)
45222:13:49RSpec exited with 0.
45322:13:49No examples to retry, congrats!
454 22:13:49 Running after_script 00:11
45522:13:50Running after script...
45622:13:50$ source scripts/utils.sh
45722:13:50$ log_disk_usage
45822:13:50*******************************************************
45922:13:50This runner currently has 71G free disk space.
46022:13:50*******************************************************
461 22:13:50 Disk usage detail 00:04
51722:13:54$ bundle exec gem list gitlab_quality-test_tooling
51822:13:56gitlab_quality-test_tooling (2.1.0)
51922:13:56$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
520 22:13:56 Report test failures 00:00
52222:13:56$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
523 22:13:56 Report test flakiness 00:00
52522:13:56$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
526 22:13:56 Report test slowness 00:00
52822:13:56$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
529 22:13:56 Report test slowness in MR note 00:01
53222:13:57$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
533 22:13:57 Report test files close to timing out 00:02
53622:13:59$ tooling/bin/push_job_metrics || true
53722:14:00[job-metrics] Pushing job metrics file for the CI/CD job.
53822:14:00[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
539 22:14:00 Saving cache for successful job 00:01
54022:14:01Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
541 22:14:01 Uploading artifacts for successful job 00:06
54222:14:02Uploading artifacts...
54322:14:02auto_explain/: found 3 matching artifact files and directories
54422:14:02coverage/: found 5 matching artifact files and directories
54522:14:02crystalball/: found 2 matching artifact files and directories
54622:14:02WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
54722:14:02knapsack/: found 4 matching artifact files and directories
54822:14:02rspec/: found 16 matching artifact files and directories
54922:14:02WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
55022:14:02log/*.log: found 13 matching artifact files and directories
55122:14:03WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353287032/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
55222:14:03WARNING: Retrying... context=artifacts-uploader error=request redirected
55322:14:05Uploading artifacts as "archive" to coordinator... 201 Created id=8353287032 responseStatus=201 Created token=glcbt-66
55422:14:05Uploading artifacts...
55522:14:05rspec/rspec-*.xml: found 1 matching artifact files and directories
55622:14:05WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353287032/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
55722:14:05WARNING: Retrying... context=artifacts-uploader error=request redirected
55822:14:07Uploading artifacts as "junit" to coordinator... 201 Created id=8353287032 responseStatus=201 Created token=glcbt-66
559 22:14:07 Cleaning up project directory and file based variables 00:00
56022:14:15Job succeeded