Skip to content
Snippets Groups Projects

rspec migration pg14 26/26

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
121:21:38Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
221:21:38 on blue-2.shared-gitlab-org.runners-manager.gitlab.com/default NL4gfoBe, system ID: s_74c3e1316164
321:21:38 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
421:21:38Resolving secrets
5 21:21:38 Preparing the "docker+machine" executor 01:30
621:21:38Using 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 ...
721:21:43Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
821:21:43Authenticating with credentials from job payload (GitLab Registry)
921:21:43Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1021:21:50Using 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 ...
1121:21:54WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1221:21:54WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1321:21:54Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1421:21:54Authenticating with credentials from job payload (GitLab Registry)
1521:21:54Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1621:21:57Using 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 ...
1721:21:59Starting service redis:6.2-alpine...
1821:21:59Pulling docker image redis:6.2-alpine ...
1921:22:02Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2021:22:03Waiting for services to be up and running (timeout 30 seconds)...
2121:22:04Authenticating with credentials from job payload (GitLab Registry)
2221:22:04Pulling 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 ...
2321:23:08Using 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 21:23:08 Preparing environment 00:01
2521:23:09Running on runner-nl4gfobe-project-278964-concurrent-0 via runner-nl4gfobe-shared-gitlab-org-1731619251-2cb728d8...
26 21:23:09 Getting source from Git repository 00:01
2721:23:09Skipping Git repository setup
2821:23:09Skipping Git checkout
2921:23:09Skipping Git submodules setup
30 21:23:10 Restoring cache 00:17
3121:23:10Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
3221:23:10Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 ETag="19021ff660774ac886a027bf8fc2c15e"
3321:23:23Successfully extracted cache
34 21:23:27 Downloading artifacts 00:52
3521:23:28Downloading artifacts for clone-gitlab-repo (8368443091)...
3621:23:33Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443091 responseStatus=200 OK token=glcbt-66
3721:23:33WARNING: Part of .git directory is on the list of files to extract
3821:23:33WARNING: This may introduce unexpected problems
3921:23:49Downloading artifacts for compile-test-assets (8368443337)...
4021:23:53Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443337 responseStatus=200 OK token=glcbt-66
4121:24:00Downloading artifacts for detect-tests (8368443460)...
4221:24:00Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443460 responseStatus=200 OK token=glcbt-66
4321:24:00Downloading artifacts for retrieve-tests-metadata (8368443480)...
4421:24:01Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443480 responseStatus=200 OK token=glcbt-66
4521:24:01Downloading artifacts for setup-test-env (8368443411)...
4621:24:06Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443411 responseStatus=200 OK token=glcbt-66
47 21:24:19 Executing "step_script" stage of the job script
4821:24:19Using 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 ...
4921:24:20$ echo $FOSS_ONLY
5021:24:20$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5121:24:20$ export GOPATH=$CI_PROJECT_DIR/.go
5221:24:20$ mkdir -p $GOPATH
5321:24:20$ source scripts/utils.sh
5421:24:20$ log_disk_usage "true"
5521:24:20*******************************************************
5621:24:20This runner currently has 31G free disk space.
5721:24:20*******************************************************
58 21:24:21 Disk usage detail 00:01
5921:24:21df -h
6021:24:21Filesystem Size Used Avail Use% Mounted on
6121:24:21overlay 46G 15G 31G 34% /
6221:24:21tmpfs 64M 0 64M 0% /dev
6321:24:21shm 256M 0 256M 0% /dev/shm
6421:24:21/dev/sda1 46G 15G 31G 34% /cache
6521:24:21tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6621:24:21tmpfs 3.9G 0 3.9G 0% /proc/acpi
6721:24:21tmpfs 3.9G 0 3.9G 0% /proc/scsi
6821:24:21tmpfs 3.9G 0 3.9G 0% /sys/firmware
6921:24:21du -h -d 1
7021:24:217.4M ./workhorse
7121:24:21148K ./bin
7221:24:211.2M ./.gitlab
7321:24:21177M ./.git
7421:24:214.0K ./builds
7521:24:2128M ./lib
7621:24:218.0K ./.vscode
7721:24:21560K ./storybook
7821:24:2124K ./log
7921:24:211.8M ./data
8021:24:2111M ./qa
8121:24:213.2M ./.rubocop_todo
8221:24:21856M ./vendor
8321:24:211023M ./public
8421:24:2162M ./doc
8521:24:21484K ./danger
8621:24:2132K ./rspec
8721:24:21556K ./tooling
8821:24:21146M ./ee
8921:24:211.3M ./knapsack
9021:24:21300K ./patches
9121:24:214.3M ./changelogs
9221:24:2120K ./haml_lint
9321:24:2128K ./metrics_server
9421:24:21108K ./keeps
9521:24:2136K ./shared
9621:24:2144K ./generator_templates
9721:24:211.3M ./scripts
9821:24:21763M ./tmp
9921:24:21127M ./spec
10021:24:2120K ./file_hooks
10121:24:2132K ./crystalball
10221:24:2116K ./sidekiq_cluster
10321:24:2117M ./gems
10421:24:211.2M ./fixtures
10521:24:21964K ./rubocop
10621:24:214.0K ./.go
10721:24:2133M ./db
10821:24:2112K ./.github
10921:24:21129M ./locale
11021:24:2120M ./config
11121:24:2282M ./app
11221:24:2220K ./.lefthook
11321:24:223.5G .
11421:24:22$ source scripts/prepare_build.sh
115 21:24:22 Installing gems 00:22
14621:24:44Using decomposed database config (config/database.yml.decomposed-postgresql)
14721:24:44Geo DB won't be set up.
14821:24:44Embedding DB won't be set up.
149 21:24:44 Setting up DBs 01:40
16221:26:24$ source ./scripts/rspec_helpers.sh
16321:26:24$ run_timed_command "gem install knapsack --no-document"
16421:26:23$ gem install knapsack --no-document
16521:26:24Successfully installed knapsack-4.0.0
16621:26:241 gem installed
16721:26:24==> 'gem install knapsack --no-document' succeeded in 0 seconds.
16821:26:24$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
169 21:26:24 Spawning Gitaly 00:05
17321:26:29$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17421:26:29$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17521:26:29$ tooling/bin/create_job_metrics_file || true
17621:26:33[job-metrics] Creating the job metrics file for the CI/CD job.
17721:26:33$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
178 21:26:33 RSpec 06:44
17921:26:33[21:26:33] Starting rspec_parallelized_job
18021:26:24RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
18121:26:34KNAPSACK_GENERATE_REPORT: true
18221:26:34FLAKY_RSPEC_GENERATE_REPORT: true
18321:26:34KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
18421:26:34KNAPSACK_LOG_LEVEL: debug
18521:26:34KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_26_26_278964_report.json
18621:26:34FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18721:26:34FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_26_26_278964_report.json
18821:26:34NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_26_26_278964_report.json
18921:26:34RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8368445133.txt
19021:26:34CRYSTALBALL:
19121:26:34RSPEC_TESTS_MAPPING_ENABLED:
19221:26:34RSPEC_TESTS_FILTER_FILE:
19321:26:34Shell set options (set -o) enabled:
19421:26:34braceexpand on
19521:26:34hashall on
19621:26:34interactive-comments on
19721:26:34pipefail on
19821:26:41Knapsack report generator started!
19921:26:34/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!
20021:27:42DEPRECATION 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)
20121:27:54warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
20221:27:54Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20321:28:01Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
20421:28:01# [RSpecRunTime] Starting RSpec timer...
20521:28:01[TEST PROF INFO] EventProf enabled (sql.active_record)
20621:28:01unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20721:28:01unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20821:28:09Test environment set up in 2.907754691 seconds
20921:28:10# [RSpecRunTime] Starting example group spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb. Expected to take 20.93 seconds.
21021:28:10QueueBackfillCiPipelineMessagesProjectId
21121:28:26ci: == [advisory_lock_connection] object_id: 4373980, pg_backend_pid: 323
21221:28:26ci: == 20241014081026 QueueBackfillCiPipelineMessagesProjectId: migrating =========
21321:28:26ci: == 20241014081026 QueueBackfillCiPipelineMessagesProjectId: migrated (0.1424s)
21421:28:26ci: == [advisory_lock_connection] object_id: 4373980, pg_backend_pid: 323
21521:28:31 schedules a new batched migration
21621:28:59# [RSpecRunTime] Finishing example group spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb. It took 49.33 seconds. Expected to take 20.93 seconds.
21721:28:59# [RSpecRunTime] Starting example group spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb. Expected to take 20.28 seconds.
21821:28:59QueueDeleteStalePackagesNpmMetadataCaches
21921:29:16main: == [advisory_lock_connection] object_id: 9976240, pg_backend_pid: 521
22021:29:16main: == 20240911115256 QueueDeleteStalePackagesNpmMetadataCaches: migrating ========
22121:29:16main: == 20240911115256 QueueDeleteStalePackagesNpmMetadataCaches: migrated (0.0731s)
22221:29:16main: == [advisory_lock_connection] object_id: 9976240, pg_backend_pid: 521
22321:29:22 schedules a new batched migration
22421:29:32I, [2024-11-14T21:29:32.900761 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
22521:29:32I, [2024-11-14T21:29:32.903283 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22621:29:32I, [2024-11-14T21:29:32.931081 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22721:29:32I, [2024-11-14T21:29:32.935714 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
22821:29:32I, [2024-11-14T21:29:32.937238 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22921:29:32I, [2024-11-14T21:29:32.938298 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23021:29:32I, [2024-11-14T21:29:32.942824 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
23121:29:32I, [2024-11-14T21:29:32.944347 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23221:29:32I, [2024-11-14T21:29:32.945421 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23321:29:32I, [2024-11-14T21:29:32.949569 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
23421:29:32I, [2024-11-14T21:29:32.950872 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23521:29:32I, [2024-11-14T21:29:32.951884 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23621:29:34I, [2024-11-14T21:29:34.193446 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
23721:29:34I, [2024-11-14T21:29:34.194797 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23821:29:34I, [2024-11-14T21:29:34.195948 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23921:29:34I, [2024-11-14T21:29:34.199954 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
24021:29:34I, [2024-11-14T21:29:34.201445 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24121:29:34I, [2024-11-14T21:29:34.202446 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24221:29:34I, [2024-11-14T21:29:34.207107 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
24321:29:34I, [2024-11-14T21:29:34.208562 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24421:29:34I, [2024-11-14T21:29:34.209625 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24521:29:34I, [2024-11-14T21:29:34.213388 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
24621:29:34I, [2024-11-14T21:29:34.214616 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24721:29:34I, [2024-11-14T21:29:34.215548 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24821:29:53# [RSpecRunTime] Finishing example group spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb. It took 53.76 seconds. Expected to take 20.28 seconds.
24921:29:53# [RSpecRunTime] RSpec elapsed time: 1 minute 52.06 seconds. Current RSS: ~1227M. Threads: 1. load average: 1.11 1.18 0.71 1/259 389
25021:29:53.
25121:29:53# [RSpecRunTime] Starting example group spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb. Expected to take 13.41 seconds.
25221:29:53SetCorrectIdToExistingWorkItemTypes
25321:30:06main: == [advisory_lock_connection] object_id: 15894960, pg_backend_pid: 534
25421:30:06main: == 20240918204510 SetCorrectIdToExistingWorkItemTypes: migrating ==============
25521:30:06main: == 20240918204510 SetCorrectIdToExistingWorkItemTypes: migrated (0.0381s) =====
25621:30:06main: == [advisory_lock_connection] object_id: 15894960, pg_backend_pid: 534
25721:30:07 sets the correct id for every record in the table
25821:30:13I, [2024-11-14T21:30:13.779734 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
25921:30:13I, [2024-11-14T21:30:13.781224 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26021:30:13I, [2024-11-14T21:30:13.782219 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26121:30:13I, [2024-11-14T21:30:13.786286 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
26221:30:13I, [2024-11-14T21:30:13.787604 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26321:30:13I, [2024-11-14T21:30:13.788624 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26421:30:13I, [2024-11-14T21:30:13.792276 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
26521:30:13I, [2024-11-14T21:30:13.793547 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26621:30:13I, [2024-11-14T21:30:13.794491 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26721:30:13I, [2024-11-14T21:30:13.800002 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
26821:30:13I, [2024-11-14T21:30:13.801602 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26921:30:13I, [2024-11-14T21:30:13.802680 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27021:30:14I, [2024-11-14T21:30:14.947747 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
27121:30:14I, [2024-11-14T21:30:14.949069 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27221:30:14I, [2024-11-14T21:30:14.950014 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27321:30:14I, [2024-11-14T21:30:14.954141 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
27421:30:14I, [2024-11-14T21:30:14.955426 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27521:30:14I, [2024-11-14T21:30:14.956437 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27621:30:14I, [2024-11-14T21:30:14.960245 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
27721:30:14I, [2024-11-14T21:30:14.961664 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27821:30:14I, [2024-11-14T21:30:14.962720 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27921:30:14I, [2024-11-14T21:30:14.966214 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
28021:30:14I, [2024-11-14T21:30:14.967377 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28121:30:14I, [2024-11-14T21:30:14.968261 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28221:30:32# [RSpecRunTime] Finishing example group spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb. It took 38.77 seconds. Expected to take 13.41 seconds.
28321:30:32# [RSpecRunTime] RSpec elapsed time: 2 minutes 31.28 seconds. Current RSS: ~1228M. Threads: 1. load average: 1.00 1.14 0.72 1/259 390
28421:30:32.
28521:30:32# [RSpecRunTime] Starting example group spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb. Expected to take 6.5 seconds.
28621:30:32RequeueBackfillWorkItemHierarchyForEpics
28721:30:34main: == [advisory_lock_connection] object_id: 19262780, pg_backend_pid: 545
28821:30:34main: == 20241108111720 RequeueBackfillWorkItemHierarchyForEpics: migrating =========
28921:30:34main: == 20241108111720 RequeueBackfillWorkItemHierarchyForEpics: migrated (0.0644s)
29021:30:34main: == [advisory_lock_connection] object_id: 19262780, pg_backend_pid: 545
29121:30:39 schedules a new batched migration
29221:30:49# [RSpecRunTime] Finishing example group spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb. It took 16.77 seconds. Expected to take 6.5 seconds.
29321:30:49# [RSpecRunTime] RSpec elapsed time: 2 minutes 48.1 seconds. Current RSS: ~1226M. Threads: 1. load average: 1.00 1.13 0.73 1/259 391
29421:30:49.
29521:30:49# [RSpecRunTime] Starting example group spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb.
29621:30:49QueueBackfillVsCodeSettingsUuid
29721:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillVsCodeSettingsUuid is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29821:30:49# [RSpecRunTime] Finishing example group spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb.
29921:30:49# [RSpecRunTime] Starting example group spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb.
30021:30:49EnsureMemberRolesNamesUniq
30121:30:49 #up
30221:30:49 updates the duplicated names with higher id (PENDING: Skipping because migration EnsureMemberRolesNamesUniq is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30321:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb.
30421:30:49# [RSpecRunTime] Starting example group spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb.
30521:30:49SetTrustedExternUidToFalseForExistingBitbucketIdentities
30621:30:49 #up
30721:30:49 sets trusted_extern_uid to false for existing bitbucket identities (PENDING: Skipping because migration SetTrustedExternUidToFalseForExistingBitbucketIdentities is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30821:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb.
30921:30:49# [RSpecRunTime] Starting example group spec/migrations/20240521090846_queue_backfill_boards_epic_board_labels_group_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240521090846_queue_backfill_boards_epic_board_labels_group_id_spec.rb.
31021:30:49QueueBackfillBoardsEpicBoardLabelsGroupId
31121:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillBoardsEpicBoardLabelsGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31221:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240521090846_queue_backfill_boards_epic_board_labels_group_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240521090846_queue_backfill_boards_epic_board_labels_group_id_spec.rb.
31321:30:49# [RSpecRunTime] Starting example group spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb.
31421:30:49QueueBackfillDastSiteProfileSecretVariablesProjectId
31521:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillDastSiteProfileSecretVariablesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
31621:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb.
31721:30:49# [RSpecRunTime] Starting example group spec/migrations/20240613072542_queue_backfill_merge_request_context_commits_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240613072542_queue_backfill_merge_request_context_commits_project_id_spec.rb.
31821:30:49QueueBackfillMergeRequestContextCommitsProjectId
31921:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillMergeRequestContextCommitsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32021:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240613072542_queue_backfill_merge_request_context_commits_project_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240613072542_queue_backfill_merge_request_context_commits_project_id_spec.rb.
32121:30:49# [RSpecRunTime] Starting example group spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb.
32221:30:49QueueBackfillIssuesDatesWithWorkItemDatesSources
32321:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillIssuesDatesWithWorkItemDatesSources is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32421:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb.
32521:30:49# [RSpecRunTime] Starting example group spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb.
32621:30:49QueueMakeSecurityPolicyBotsPrivate
32721:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueMakeSecurityPolicyBotsPrivate is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
32821:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb.
32921:30:49# [RSpecRunTime] Starting example group spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb.
33021:30:49QueueBackfillSecurityOrchestrationPolicyRuleSchedulesNamespaceId
33121:30:49 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillSecurityOrchestrationPolicyRuleSchedulesNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33221:30:49# [RSpecRunTime] Finishing example group spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb.
33321:30:49# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb.
33421:30:49CreatePartitionsForAuditEventTables
33521:30:49 #up
33621:30:49 when audit event partitions exists
33721:30:49 calls drop partitions and create daterange partitions with correct args (PENDING: Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
33821:30:49 when audit event partitions does not exist
33921:30:49 calls drop partitions and create daterange partitions with correct args (PENDING: Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34021:30:49 #down
34121:30:49 calls drop partitions with correct args (PENDING: Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34221:30:49 #drop_partitions
34321:30:49 drops all partitions (PENDING: Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34421:30:49# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb. It took 0.01 second. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb.
34521:30:49# [RSpecRunTime] Starting example group spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb. Missing expected duration from Knapsack report for spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb.
34621:30:49UpdateMaxTotalYamlSizeBytesValue
34721:30:49 #up
34821:30:49 updates the default value from 1MB to 2MB (PENDING: Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
34921:30:49 increases non-default value by 20% (PENDING: Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35021:30:49 does not change values that are already 2MB (PENDING: Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35121:30:49 sets the value to max integer when 20% increase exceeds max int (PENDING: Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
35221:30:49# [RSpecRunTime] Finishing example group spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb. It took 0.02 second. Missing expected duration from Knapsack report for spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb.
35321:30:52auto_explain log contains 854 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-26-26.344.main.ndjson.gz
35421:30:52took 2.50306773
35521:30:55auto_explain log contains 855 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-26-26.344.ci.ndjson.gz
35621:30:55took 2.440474252
35721:30:55[TEST PROF INFO] EventProf results for sql.active_record
35821:30:55Total time: 01:35.891 of 02:39.359 (60.17%)
35921:30:55Total events: 66400
36021:30:55Top 5 slowest suites (by time):
36121:30:55QueueDeleteSt...MetadataCaches (./spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb:6) – 00:34.960 (20508 / 1) of 00:54.208 (64.49%)
36221:30:55QueueBackfill...sagesProjectId (./spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb:6) – 00:28.853 (21433 / 1) of 00:49.328 (58.49%)
36321:30:55SetCorrectIdT...gWorkItemTypes (./spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb:6) – 00:23.713 (16656 / 1) of 00:38.819 (61.09%)
36421:30:55RequeueBackfi...rarchyForEpics (./spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb:6) – 00:08.325 (7726 / 1) of 00:16.825 (49.48%)
36521:30:55QueueBackfill...ablesProjectId (./spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb:6) – 00:00.004 (7 / 1) of 00:00.017 (23.96%)
36621:30:55Knapsack report was generated. Preview:
36721:30:55{
36821:30:55 "spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb": 22.211631221000005,
36921:30:55 "spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb": 22.913723239999968,
37021:30:55 "spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb": 14.238167511000029,
37121:30:55 "spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb": 6.542638803000045
37221:30:55}
37321:30:55Knapsack global time execution for tests: 01m 05s
37421:30:55Pending: (Failures listed here are expected and do not affect your suite's status)
37521:30:55 1) QueueBackfillVsCodeSettingsUuid schedules a new batched migration
37621:30:55 # Skipping because migration QueueBackfillVsCodeSettingsUuid is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
37721:30:55 # ./spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb:9
37821:30:55 2) EnsureMemberRolesNamesUniq#up updates the duplicated names with higher id
37921:30:55 # Skipping because migration EnsureMemberRolesNamesUniq is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38021:30:55 # ./spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb:28
38121:30:55 3) SetTrustedExternUidToFalseForExistingBitbucketIdentities#up sets trusted_extern_uid to false for existing bitbucket identities
38221:30:55 # Skipping because migration SetTrustedExternUidToFalseForExistingBitbucketIdentities is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38321:30:55 # ./spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb:36
38421:30:55 4) QueueBackfillBoardsEpicBoardLabelsGroupId schedules a new batched migration
38521:30:55 # Skipping because migration QueueBackfillBoardsEpicBoardLabelsGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38621:30:55 # ./spec/migrations/20240521090846_queue_backfill_boards_epic_board_labels_group_id_spec.rb:10
38721:30:55 5) QueueBackfillDastSiteProfileSecretVariablesProjectId schedules a new batched migration
38821:30:55 # Skipping because migration QueueBackfillDastSiteProfileSecretVariablesProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
38921:30:55 # ./spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb:10
39021:30:55 6) QueueBackfillMergeRequestContextCommitsProjectId schedules a new batched migration
39121:30:55 # Skipping because migration QueueBackfillMergeRequestContextCommitsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39221:30:55 # ./spec/migrations/20240613072542_queue_backfill_merge_request_context_commits_project_id_spec.rb:10
39321:30:55 7) QueueBackfillIssuesDatesWithWorkItemDatesSources schedules a new batched migration
39421:30:55 # Skipping because migration QueueBackfillIssuesDatesWithWorkItemDatesSources is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39521:30:55 # ./spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb:9
39621:30:55 8) QueueMakeSecurityPolicyBotsPrivate schedules a new batched migration
39721:30:55 # Skipping because migration QueueMakeSecurityPolicyBotsPrivate is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
39821:30:55 # ./spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb:9
39921:30:55 9) QueueBackfillSecurityOrchestrationPolicyRuleSchedulesNamespaceId schedules a new batched migration
40021:30:55 # Skipping because migration QueueBackfillSecurityOrchestrationPolicyRuleSchedulesNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40121:30:55 # ./spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb:10
40221:30:55 10) CreatePartitionsForAuditEventTables#up when audit event partitions exists calls drop partitions and create daterange partitions with correct args
40321:30:55 # Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40421:30:55 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:15
40521:30:55 11) CreatePartitionsForAuditEventTables#up when audit event partitions does not exist calls drop partitions and create daterange partitions with correct args
40621:30:55 # Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
40721:30:55 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:15
40821:30:55 12) CreatePartitionsForAuditEventTables#down calls drop partitions with correct args
40921:30:55 # Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41021:30:55 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:67
41121:30:55 13) CreatePartitionsForAuditEventTables#drop_partitions drops all partitions
41221:30:55 # Skipping because migration CreatePartitionsForAuditEventTables is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41321:30:55 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:101
41421:30:55 14) UpdateMaxTotalYamlSizeBytesValue#up updates the default value from 1MB to 2MB
41521:30:55 # Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41621:30:55 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:10
41721:30:55 15) UpdateMaxTotalYamlSizeBytesValue#up increases non-default value by 20%
41821:30:55 # Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
41921:30:55 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:18
42021:30:55 16) UpdateMaxTotalYamlSizeBytesValue#up does not change values that are already 2MB
42121:30:55 # Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42221:30:55 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:27
42321:30:55 17) UpdateMaxTotalYamlSizeBytesValue#up sets the value to max integer when 20% increase exceeds max int
42421:30:55 # Skipping because migration UpdateMaxTotalYamlSizeBytesValue is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
42521:30:55 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:35
42621:30:55Finished in 2 minutes 54.1 seconds (files took 1 minute 23.87 seconds to load)
42721:30:5521 examples, 0 failures, 17 pending
42821:30:55Randomized with seed 37504
42921:31:02[TEST PROF INFO] Time spent in factories: 00:00.221 (0.12% of total time)
43021:31:02RSpec exited with 0.
43121:31:02No examples to retry, congrats!
432 21:31:03 Running after_script 00:09
43321:31:04Running after script...
43421:31:04$ source scripts/utils.sh
43521:31:04$ log_disk_usage
43621:31:04*******************************************************
43721:31:04This runner currently has 30G free disk space.
43821:31:04*******************************************************
439 21:31:04 Disk usage detail 00:01
49821:31:05$ bundle exec gem list gitlab_quality-test_tooling
49921:31:06gitlab_quality-test_tooling (2.1.0)
50021:31:06$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
501 21:31:06 Report test failures 00:00
50321:31:06$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
504 21:31:06 Report test flakiness 00:00
50621:31:06$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
507 21:31:06 Report test slowness 00:00
50921:31:06$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
510 21:31:06 Report test slowness in MR note 00:02
51321:31:08$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
514 21:31:08 Report test files close to timing out 00:03
51721:31:11$ tooling/bin/push_job_metrics || true
51821:31:12[job-metrics] Pushing job metrics file for the CI/CD job.
51921:31:12[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
520 21:31:12 Saving cache for successful job 00:01
52121:31:13Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
522 21:31:13 Uploading artifacts for successful job 00:05
52321:31:14Uploading artifacts...
52421:31:14auto_explain/: found 3 matching artifact files and directories
52521:31:14coverage/: found 5 matching artifact files and directories
52621:31:14crystalball/: found 2 matching artifact files and directories
52721:31:14WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
52821:31:14knapsack/: found 4 matching artifact files and directories
52921:31:14rspec/: found 16 matching artifact files and directories
53021:31:14WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
53121:31:14log/*.log: found 13 matching artifact files and directories
53221:31:14WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8368445133/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
53321:31:14WARNING: Retrying... context=artifacts-uploader error=request redirected
53421:31:16Uploading artifacts as "archive" to coordinator... 201 Created id=8368445133 responseStatus=201 Created token=glcbt-66
53521:31:16Uploading artifacts...
53621:31:16rspec/rspec-*.xml: found 1 matching artifact files and directories
53721:31:16WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8368445133/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
53821:31:16WARNING: Retrying... context=artifacts-uploader error=request redirected
53921:31:18Uploading artifacts as "junit" to coordinator... 201 Created id=8368445133 responseStatus=201 Created token=glcbt-66
540 21:31:18 Cleaning up project directory and file based variables 00:01
54121:31:26Job succeeded