Skip to content
Snippets Groups Projects

rspec migration pg14 single-db-ci-connection 24/26

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
122:12:17Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
222:12:17 on blue-2.private.runners-manager.gitlab.com/gitlab.com/gitlab-org QvBVRoXM, system ID: s_49b872f8ccf6
322:12:17 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
422:12:17Resolving secrets
5 22:12:17 Preparing the "docker+machine" executor 01:31
622:12:18Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
722:12:22Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
822:12:22Authenticating with credentials from job payload (GitLab Registry)
922:12:22Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1022:12:29Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...
1122:12:33WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1222:12:33WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1322:12:33Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1422:12:33Authenticating with credentials from job payload (GitLab Registry)
1522:12:33Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1622:12:37Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...
1722:12:38Starting service redis:6.2-alpine...
1822:12:38Pulling docker image redis:6.2-alpine ...
1922:12:41Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2022:12:42Waiting for services to be up and running (timeout 30 seconds)...
2122:12:43Authenticating with credentials from job payload (GitLab Registry)
2222:12:43Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
2322:13:48Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
24 22:13:48 Preparing environment 00:00
2522:13:48Running on runner-qvbvroxm-project-13083-concurrent-0 via runner-qvbvroxm-private-1731535893-22f11ebd...
26 22:13:48 Getting source from Git repository 00:01
2722:13:49Skipping Git repository setup
2822:13:49Skipping Git checkout
2922:13:49Skipping Git submodules setup
30 22:13:49 Restoring cache 00:17
3122:13:49Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-3...
3222:13:49Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/13083/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-3 ETag="2f01fc71854036e0b450342e02ca5726"
3322:14:00Successfully extracted cache
34 22:14:06 Downloading artifacts 00:40
3522:14:07Downloading artifacts for clone-gitlab-repo (8353300418)...
3622:14:10Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300418 responseStatus=200 OK token=glcbt-66
3722:14:10WARNING: Part of .git directory is on the list of files to extract
3822:14:10WARNING: This may introduce unexpected problems
3922:14:20Downloading artifacts for compile-test-assets (8353300423)...
4022:14:23Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300423 responseStatus=200 OK token=glcbt-66
4122:14:29Downloading artifacts for detect-tests (8353300433)...
4222:14:29Downloading artifacts for retrieve-tests-metadata (8353300434)...
4322:14:29Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300433 responseStatus=200 OK token=glcbt-66
4422:14:29Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300434 responseStatus=200 OK token=glcbt-66
4522:14:30Downloading artifacts for setup-test-env (8353300427)...
4622:14:36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300427 responseStatus=200 OK token=glcbt-66
47 22:14:46 Executing "step_script" stage of the job script
4822:14:46Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
4922:14:47$ echo $FOSS_ONLY
5022:14:47$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5122:14:47$ export GOPATH=$CI_PROJECT_DIR/.go
5222:14:47$ mkdir -p $GOPATH
5322:14:47$ source scripts/utils.sh
5422:14:47$ log_disk_usage "true"
5522:14:48*******************************************************
5622:14:48This runner currently has 80G free disk space.
5722:14:48*******************************************************
58 22:14:48 Disk usage detail 00:00
5922:14:48df -h
6022:14:48Filesystem Size Used Avail Use% Mounted on
6122:14:48overlay 95G 15G 80G 16% /
6222:14:48tmpfs 64M 0 64M 0% /dev
6322:14:48shm 64M 0 64M 0% /dev/shm
6422:14:48/dev/sda1 95G 15G 80G 16% /cache
6522:14:48tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6622:14:48du -h -d 1
6722:14:487.4M ./workhorse
6822:14:48148K ./bin
6922:14:481.2M ./.gitlab
7022:14:48121M ./.git
7122:14:484.0K ./builds
7222:14:4828M ./lib
7322:14:48560K ./storybook
7422:14:4824K ./log
7522:14:481.8M ./data
7622:14:487.1M ./qa
7722:14:483.2M ./.rubocop_todo
7822:14:48856M ./vendor
7922:14:48858M ./public
8022:14:4862M ./doc
8122:14:48484K ./danger
8222:14:4832K ./rspec
8322:14:48556K ./tooling
8422:14:481.3M ./knapsack
8522:14:48300K ./patches
8622:14:483.9M ./changelogs
8722:14:4820K ./haml_lint
8822:14:4828K ./metrics_server
8922:14:48108K ./keeps
9022:14:4836K ./shared
9122:14:4844K ./generator_templates
9222:14:481.3M ./scripts
9322:14:48641M ./tmp
9422:14:48127M ./spec
9522:14:4820K ./file_hooks
9622:14:4832K ./crystalball
9722:14:4816K ./sidekiq_cluster
9822:14:4817M ./gems
9922:14:481.2M ./fixtures
10022:14:48964K ./rubocop
10122:14:484.0K ./.go
10222:14:4833M ./db
10322:14:4812K ./.github
10422:14:48129M ./locale
10522:14:4820M ./config
10622:14:4882M ./app
10722:14:4820K ./.lefthook
10822:14:483.0G .
10922:14:48$ source scripts/prepare_build.sh
110 22:14:48 Installing gems 00:40
15122:15:28Using two connections, single database config (config/database.yml.postgresql)
15222:15:28Geo DB won't be set up.
15322:15:28Embedding DB won't be set up.
154 22:15:28 Setting up DBs 01:07
16722:16:35$ source ./scripts/rspec_helpers.sh
16822:16:35$ run_timed_command "gem install knapsack --no-document"
16922:16:34$ gem install knapsack --no-document
17022:16:36Successfully installed knapsack-4.0.0
17122:16:361 gem installed
17222:16:36$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
17322:16:35==> 'gem install knapsack --no-document' succeeded in 1 seconds.
174 22:16:36 Spawning Gitaly 00:06
17822:16:42$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17922:16:42$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
18022:16:42$ tooling/bin/create_job_metrics_file || true
18122:16:45[job-metrics] Feature disabled because CI_JOB_METRICS_ENABLED is not set to true.
18222:16:45$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
183 22:16:45 RSpec 05:52
18422:16:45[22:16:45] Starting rspec_parallelized_job
18522:16:36RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
18622:16:45KNAPSACK_GENERATE_REPORT: true
18722:16:45FLAKY_RSPEC_GENERATE_REPORT: true
18822:16:45KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
18922:16:45KNAPSACK_LOG_LEVEL: debug
19022:16:45KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_single-db-ci-connection_24_26_13083_report.json
19122:16:45FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
19222:16:45FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_single-db-ci-connection_24_26_13083_report.json
19322:16:45NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_single-db-ci-connection_24_26_13083_report.json
19422:16:45RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8353301202.txt
19522:16:45CRYSTALBALL:
19622:16:45RSPEC_TESTS_MAPPING_ENABLED:
19722:16:45RSPEC_TESTS_FILTER_FILE:
19822:16:45Shell set options (set -o) enabled:
19922:16:45braceexpand on
20022:16:45hashall on
20122:16:45interactive-comments on
20222:16:45pipefail on
20322:16:51Knapsack report generator started!
20422:16:45/builds/gitlab-org/gitlab-foss/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
20522:17:31DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <top (required)> at /builds/gitlab-org/gitlab-foss/config/environment.rb:7)
20622:17:39warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
20722:17:39Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20822:17:51/builds/gitlab-org/gitlab-foss/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
20922:18:03DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <main> at /builds/gitlab-org/gitlab-foss/config/environment.rb:7)
21022:18:23Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
21122:18:23# [RSpecRunTime] Starting RSpec timer...
21222:18:23[TEST PROF INFO] EventProf enabled (sql.active_record)
21322:18:23unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
21422:18:31Test environment set up in 1.890330578 seconds
21522:18:31# [RSpecRunTime] Starting example group spec/migrations/20241001073229_backfill_project_id_for_ci_pipeline_chat_data_spec.rb. Expected to take 23.72 seconds.
21622:18:31BackfillProjectIdForCiPipelineChatData
21722:18:37 #up
21822:18:38ci: == [advisory_lock_connection] object_id: 1520320, pg_backend_pid: 147
21922:18:38ci: == 20241011101723 BackfillProjectIdForCiPipelineChatData: migrating ===========
22022:18:38ci: == 20241011101723 BackfillProjectIdForCiPipelineChatData: migrated (0.0461s) ==
22122:18:39ci: == [advisory_lock_connection] object_id: 1520320, pg_backend_pid: 147
22222:18:43 sets the project_id
22322:19:01# [RSpecRunTime] Finishing example group spec/migrations/20241001073229_backfill_project_id_for_ci_pipeline_chat_data_spec.rb. It took 30.08 seconds. Expected to take 23.72 seconds.
22422:19:02# [RSpecRunTime] RSpec elapsed time: 38.71 seconds. Current RSS: ~911M. Threads: 1. load average: 1.00 1.07 0.61 1/259 409
22522:19:02.
22622:19:02# [RSpecRunTime] Starting example group spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb. Expected to take 21.51 seconds.
22722:19:02QueueBackfillCiPipelineMessagesProjectId
22822:19:08ci: == [advisory_lock_connection] object_id: 4518280, pg_backend_pid: 160
22922:19:08ci: == 20241014081026 QueueBackfillCiPipelineMessagesProjectId: migrating =========
23022:19:08ci: == 20241014081026 QueueBackfillCiPipelineMessagesProjectId: migrated (0.1322s)
23122:19:09ci: == [advisory_lock_connection] object_id: 4518280, pg_backend_pid: 160
23222:19:14 schedules a new batched migration
23322:19:32# [RSpecRunTime] Finishing example group spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb. It took 29.95 seconds. Expected to take 21.51 seconds.
23422:19:32# [RSpecRunTime] RSpec elapsed time: 1 minute 9.14 seconds. Current RSS: ~906M. Threads: 1. load average: 1.05 1.07 0.63 1/258 410
23522:19:32.
23622:19:32# [RSpecRunTime] Starting example group spec/migrations/20241001122046_queue_backfill_dast_site_profiles_builds_project_id_spec.rb. Expected to take 16.67 seconds.
23722:19:32QueueBackfillDastSiteProfilesBuildsProjectId
23822:19:41main: == [advisory_lock_connection] object_id: 8065400, pg_backend_pid: 173
23922:19:41main: == 20241001122045 QueueBackfillDastSiteProfilesBuildsProjectId: migrating =====
24022:19:41main: == 20241001122045 QueueBackfillDastSiteProfilesBuildsProjectId: migrated (0.0762s)
24122:19:41main: == [advisory_lock_connection] object_id: 8065400, pg_backend_pid: 173
24222:19:47 schedules a new batched migration
24322:20:12# [RSpecRunTime] Finishing example group spec/migrations/20241001122046_queue_backfill_dast_site_profiles_builds_project_id_spec.rb. It took 39.82 seconds. Expected to take 16.67 seconds.
24422:20:12# [RSpecRunTime] RSpec elapsed time: 1 minute 48.99 seconds. Current RSS: ~911M. Threads: 1. load average: 1.04 1.06 0.65 1/258 411
24522:20:12.
24622:20:12# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20241030125718_update_namespaces_organization_id_spec.rb. Expected to take 8.22 seconds.
24722:20:12UpdateNamespacesOrganizationId
24822:20:13 #up
24922:20:14main: == [advisory_lock_connection] object_id: 11373680, pg_backend_pid: 184
25022:20:14main: == 20241030125718 UpdateNamespacesOrganizationId: migrating ===================
25122:20:14main: == 20241030125718 UpdateNamespacesOrganizationId: migrated (0.0243s) ==========
25222:20:14main: == [advisory_lock_connection] object_id: 11373680, pg_backend_pid: 184
25322:20:19 updates organization_id
25422:20:19# [RSpecRunTime] RSpec elapsed time: 1 minute 56.14 seconds. Current RSS: ~911M. Threads: 1. load average: 1.04 1.06 0.65 1/258 412
25522:20:19.
25622:20:32# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20241030125718_update_namespaces_organization_id_spec.rb. It took 20.35 seconds. Expected to take 8.22 seconds.
25722:20:32# [RSpecRunTime] RSpec elapsed time: 2 minutes 9.38 seconds. Current RSS: ~907M. Threads: 1. load average: 1.09 1.07 0.66 1/258 413
25822:20:32.
25922:20:32# [RSpecRunTime] Starting example group spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb.
26022:20:32QueueBackfillMergeRequestDiffsProjectId
26122:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillMergeRequestDiffsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
26222:20:32# [RSpecRunTime] Finishing example group spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb.
26322:20:32# [RSpecRunTime] Starting example group spec/migrations/20240126210641_requeue_backfill_default_organization_owners_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240126210641_requeue_backfill_default_organization_owners_spec.rb.
26422:20:32RequeueBackfillDefaultOrganizationOwners
26522:20:32 schedules a new batched migration (PENDING: Skipping because migration RequeueBackfillDefaultOrganizationOwners is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
26622:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240126210641_requeue_backfill_default_organization_owners_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240126210641_requeue_backfill_default_organization_owners_spec.rb.
26722:20:32# [RSpecRunTime] Starting example group spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb.
26822:20:32QueueBackfillWorkItemParentLinksNamespaceId
26922:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillWorkItemParentLinksNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
27022:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb.
27122:20:32# [RSpecRunTime] Starting example group spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb.
27222:20:32QueueBackfillWorkItemHierarchyForEpics
27322:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillWorkItemHierarchyForEpics is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
27422:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb.
27522:20:32# [RSpecRunTime] Starting example group spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb.
27622:20:32QueueBackfillProjectRelationExportsProjectId
27722:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillProjectRelationExportsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
27822:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb.
27922:20:32# [RSpecRunTime] Starting example group spec/migrations/20240613065420_queue_backfill_related_epic_links_group_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240613065420_queue_backfill_related_epic_links_group_id_spec.rb.
28022:20:32QueueBackfillRelatedEpicLinksGroupId
28122:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillRelatedEpicLinksGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
28222:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240613065420_queue_backfill_related_epic_links_group_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240613065420_queue_backfill_related_epic_links_group_id_spec.rb.
28322:20:32# [RSpecRunTime] Starting example group spec/migrations/20240701074852_queue_backfill_packages_debian_group_components_group_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240701074852_queue_backfill_packages_debian_group_components_group_id_spec.rb.
28422:20:32QueueBackfillPackagesDebianGroupComponentsGroupId
28522:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueBackfillPackagesDebianGroupComponentsGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
28622:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240701074852_queue_backfill_packages_debian_group_components_group_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240701074852_queue_backfill_packages_debian_group_components_group_id_spec.rb.
28722:20:32# [RSpecRunTime] Starting example group spec/migrations/20240726081618_queue_nullify_organization_id_for_snippets_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240726081618_queue_nullify_organization_id_for_snippets_spec.rb.
28822:20:32QueueNullifyOrganizationIdForSnippets
28922:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueNullifyOrganizationIdForSnippets is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29022:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240726081618_queue_nullify_organization_id_for_snippets_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240726081618_queue_nullify_organization_id_for_snippets_spec.rb.
29122:20:32# [RSpecRunTime] Starting example group spec/migrations/20240816110844_queue_rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240816110844_queue_rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb.
29222:20:32QueueRerunEpicDatesToWorkItemDatesSourcesSync
29322:20:32 schedules a new batched migration (PENDING: Skipping because migration QueueRerunEpicDatesToWorkItemDatesSourcesSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29422:20:32# [RSpecRunTime] Finishing example group spec/migrations/20240816110844_queue_rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/20240816110844_queue_rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb.
29522:20:32# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240416005004_swap_columns_for_p_ci_builds_runner_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240416005004_swap_columns_for_p_ci_builds_runner_id_spec.rb.
29622:20:32SwapColumnsForPCiBuildsRunnerId
29722:20:32 behaves like swap conversion columns
29822:20:32 correctly swaps conversion columns (PENDING: Skipping because migration SwapColumnsForPCiBuildsRunnerId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
29922:20:32 when index is different
30022:20:32 behaves like swap conversion columns
30122:20:32 correctly swaps conversion columns (PENDING: Skipping because migration SwapColumnsForPCiBuildsRunnerId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30222:20:32# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240416005004_swap_columns_for_p_ci_builds_runner_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240416005004_swap_columns_for_p_ci_builds_runner_id_spec.rb.
30322:20:32# [RSpecRunTime] Starting example group spec/migrations/swap_columns_for_system_note_metadata_id_spec.rb. Missing expected duration from Knapsack report for spec/migrations/swap_columns_for_system_note_metadata_id_spec.rb.
30422:20:32SwapColumnsForSystemNoteMetadataId
30522:20:32 #up
30622:20:32 swaps the integer and bigint columns with correct constraint names (PENDING: Skipping because migration SwapColumnsForSystemNoteMetadataId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.)
30722:20:32# [RSpecRunTime] Finishing example group spec/migrations/swap_columns_for_system_note_metadata_id_spec.rb. It took . Missing expected duration from Knapsack report for spec/migrations/swap_columns_for_system_note_metadata_id_spec.rb.
30822:20:32[TEST PROF INFO] EventProf results for sql.active_record
30922:20:32Total time: 00:55.952 of 02:00.809 (46.32%)
31022:20:32Total events: 49349
31122:20:32Top 5 slowest suites (by time):
31222:20:32QueueBackfill...uildsProjectId (./spec/migrations/20241001122046_queue_backfill_dast_site_profiles_builds_project_id_spec.rb:6) – 00:19.249 (16166 / 1) of 00:39.859 (48.29%)
31322:20:32BackfillProje...pelineChatData (./spec/migrations/20241001073229_backfill_project_id_for_ci_pipeline_chat_data_spec.rb:6) – 00:14.369 (12459 / 1) of 00:30.553 (47.03%)
31422:20:32QueueBackfill...sagesProjectId (./spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb:6) – 00:13.986 (12102 / 1) of 00:29.987 (46.64%)
31522:20:32UpdateNamespacesOrganizationId (./spec/migrations/db/post_migrate/20241030125718_update_namespaces_organization_id_spec.rb:6) – 00:08.348 (8622 / 1) of 00:20.382 (40.96%)
31622:20:32Knapsack report was generated. Preview:
31722:20:32{
31822:20:32 "spec/migrations/20241001073229_backfill_project_id_for_ci_pipeline_chat_data_spec.rb": 11.856429354,
31922:20:32 "spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb": 12.169963532999986,
32022:20:32 "spec/migrations/20241001122046_queue_backfill_dast_site_profiles_builds_project_id_spec.rb": 15.301439605000041,
32122:20:32 "spec/migrations/db/post_migrate/20241030125718_update_namespaces_organization_id_spec.rb": 7.14632094000001
32222:20:32}
32322:20:32Knapsack global time execution for tests: 46s
32422:20:32Pending: (Failures listed here are expected and do not affect your suite's status)
32522:20:32 1) QueueBackfillMergeRequestDiffsProjectId schedules a new batched migration
32622:20:32 # Skipping because migration QueueBackfillMergeRequestDiffsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
32722:20:32 # ./spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb:9
32822:20:32 2) RequeueBackfillDefaultOrganizationOwners schedules a new batched migration
32922:20:32 # Skipping because migration RequeueBackfillDefaultOrganizationOwners is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
33022:20:32 # ./spec/migrations/20240126210641_requeue_backfill_default_organization_owners_spec.rb:9
33122:20:32 3) QueueBackfillWorkItemParentLinksNamespaceId schedules a new batched migration
33222:20:32 # Skipping because migration QueueBackfillWorkItemParentLinksNamespaceId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
33322:20:32 # ./spec/migrations/20240419035508_queue_backfill_work_item_parent_links_namespace_id_spec.rb:9
33422:20:32 4) QueueBackfillWorkItemHierarchyForEpics schedules a new batched migration
33522:20:32 # Skipping because migration QueueBackfillWorkItemHierarchyForEpics is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
33622:20:32 # ./spec/migrations/20240517141003_queue_backfill_work_item_hierarchy_for_epics_spec.rb:9
33722:20:32 5) QueueBackfillProjectRelationExportsProjectId schedules a new batched migration
33822:20:32 # Skipping because migration QueueBackfillProjectRelationExportsProjectId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
33922:20:32 # ./spec/migrations/20240605113250_queue_backfill_project_relation_exports_project_id_spec.rb:9
34022:20:32 6) QueueBackfillRelatedEpicLinksGroupId schedules a new batched migration
34122:20:32 # Skipping because migration QueueBackfillRelatedEpicLinksGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
34222:20:32 # ./spec/migrations/20240613065420_queue_backfill_related_epic_links_group_id_spec.rb:10
34322:20:32 7) QueueBackfillPackagesDebianGroupComponentsGroupId schedules a new batched migration
34422:20:32 # Skipping because migration QueueBackfillPackagesDebianGroupComponentsGroupId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
34522:20:32 # ./spec/migrations/20240701074852_queue_backfill_packages_debian_group_components_group_id_spec.rb:10
34622:20:32 8) QueueNullifyOrganizationIdForSnippets schedules a new batched migration
34722:20:32 # Skipping because migration QueueNullifyOrganizationIdForSnippets is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
34822:20:32 # ./spec/migrations/20240726081618_queue_nullify_organization_id_for_snippets_spec.rb:9
34922:20:32 9) QueueRerunEpicDatesToWorkItemDatesSourcesSync schedules a new batched migration
35022:20:32 # Skipping because migration QueueRerunEpicDatesToWorkItemDatesSourcesSync is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35122:20:32 # ./spec/migrations/20240816110844_queue_rerun_epic_dates_to_work_item_dates_sources_sync_spec.rb:9
35222:20:32 10) SwapColumnsForPCiBuildsRunnerId behaves like swap conversion columns correctly swaps conversion columns
35322:20:32 # Skipping because migration SwapColumnsForPCiBuildsRunnerId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35422:20:32 # ./spec/support/shared_examples/migrations/swap_conversion_columns_shared_examples.rb:26
35522:20:32 11) SwapColumnsForPCiBuildsRunnerId when index is different behaves like swap conversion columns correctly swaps conversion columns
35622:20:32 # Skipping because migration SwapColumnsForPCiBuildsRunnerId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
35722:20:32 # ./spec/support/shared_examples/migrations/swap_conversion_columns_shared_examples.rb:26
35822:20:32 12) SwapColumnsForSystemNoteMetadataId#up swaps the integer and bigint columns with correct constraint names
35922:20:32 # Skipping because migration SwapColumnsForSystemNoteMetadataId is outside the test window. Run with `RUN_ALL_MIGRATION_TESTS=1 bundle exec rspec <spec> if you need to run this spec.
36022:20:32 # ./spec/migrations/swap_columns_for_system_note_metadata_id_spec.rb:19
36122:20:32Finished in 2 minutes 9.5 seconds (files took 1 minute 35.05 seconds to load)
36222:20:3216 examples, 0 failures, 12 pending
36322:20:32Randomized with seed 17742
36422:20:37RSpec exited with 0.
36522:20:37No examples to retry, congrats!
366 22:20:38 Running after_script 00:04
36722:20:39Running after script...
36822:20:39$ source scripts/utils.sh
36922:20:39$ log_disk_usage
37022:20:39*******************************************************
37122:20:39This runner currently has 79G free disk space.
37222:20:39*******************************************************
373 22:20:39 Disk usage detail 00:00
42622:20:39$ bundle exec gem list gitlab_quality-test_tooling
42722:20:41gitlab_quality-test_tooling (2.1.0)
42822:20:41$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
429 22:20:41 Report test failures 00:00
43122:20:41$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
432 22:20:41 Report test flakiness 00:00
43422:20:41$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
435 22:20:41 Report test slowness 00:00
43722:20:41$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
438 22:20:41 Report test slowness in MR note 00:00
44022:20:41$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
441 22:20:41 Report test files close to timing out 00:00
44322:20:41$ tooling/bin/push_job_metrics || true
44422:20:42[job-metrics] Feature disabled because CI_JOB_METRICS_ENABLED is not set to true.
445 22:20:42 Saving cache for successful job 00:01
44622:20:42Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-3 due to policy
447 22:20:43 Uploading artifacts for successful job 00:04
44822:20:43Uploading artifacts...
44922:20:43WARNING: auto_explain/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss)
45022:20:43coverage/: found 5 matching artifact files and directories
45122:20:43crystalball/: found 2 matching artifact files and directories
45222:20:43WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss)
45322:20:43knapsack/: found 4 matching artifact files and directories
45422:20:43rspec/: found 16 matching artifact files and directories
45522:20:43WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss)
45622:20:43log/*.log: found 13 matching artifact files and directories
45722:20:43WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353301202/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
45822:20:43WARNING: Retrying... context=artifacts-uploader error=request redirected
45922:20:46Uploading artifacts as "archive" to coordinator... 201 Created id=8353301202 responseStatus=201 Created token=glcbt-66
46022:20:46Uploading artifacts...
46122:20:46rspec/rspec-*.xml: found 1 matching artifact files and directories
46222:20:46WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353301202/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
46322:20:46WARNING: Retrying... context=artifacts-uploader error=request redirected
46422:20:47Uploading artifacts as "junit" to coordinator... 201 Created id=8353301202 responseStatus=201 Created token=glcbt-66
465 22:20:47 Cleaning up project directory and file based variables 00:01
46622:20:55Job succeeded