Skip to content
Snippets Groups Projects

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

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
121:21:51Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
221:21:51 on blue-4.shared-gitlab-org.runners-manager.gitlab.com/default 8cwZ3F43, system ID: s_df0f7e24ca8b
321:21:51 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
421:21:51Resolving secrets
5 21:21:51 Preparing the "docker+machine" executor 00:12
621:21:51Using 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:54Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
821:21:54Authenticating with credentials from job payload (GitLab Registry)
921:21:54Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1021:21:55Using 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:55WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1221:21:55WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1321:21:55Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1421:21:55Authenticating with credentials from job payload (GitLab Registry)
1521:21:55Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1621:21:56Using 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:56Starting service redis:6.2-alpine...
1821:21:56Pulling docker image redis:6.2-alpine ...
1921:21:57Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2021:21:58Waiting for services to be up and running (timeout 30 seconds)...
2121:22:02Authenticating with credentials from job payload (GitLab Registry)
2221:22:02Pulling 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:22:03Using 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:22:03 Preparing environment 00:00
2521:22:03Running on runner-8cwz3f43-project-278964-concurrent-0 via runner-8cwz3f43-shared-gitlab-org-1731610923-90262aa1...
26 21:22:03 Getting source from Git repository 00:01
2721:22:04Skipping Git repository setup
2821:22:04Skipping Git checkout
2921:22:04Skipping Git submodules setup
30 21:22:04 Restoring cache 00:20
3121:22:05Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
3221:22:05cache.zip is up to date
3321:22:20Successfully extracted cache
34 21:22:24 Downloading artifacts 00:54
3521:22:25Downloading artifacts for clone-gitlab-repo (8368443091)...
3621:22:30Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443091 responseStatus=200 OK token=glcbt-66
3721:22:30WARNING: Part of .git directory is on the list of files to extract
3821:22:30WARNING: This may introduce unexpected problems
3921:22:47Downloading artifacts for compile-test-assets (8368443337)...
4021:22:51Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443337 responseStatus=200 OK token=glcbt-66
4121:22:59Downloading artifacts for detect-tests (8368443460)...
4221:22:59Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443460 responseStatus=200 OK token=glcbt-66
4321:22:59Downloading artifacts for retrieve-tests-metadata (8368443480)...
4421:23:00Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443480 responseStatus=200 OK token=glcbt-66
4521:23:00Downloading artifacts for setup-test-env (8368443411)...
4621:23:04Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8368443411 responseStatus=200 OK token=glcbt-66
47 21:23:18 Executing "step_script" stage of the job script
4821:23:18Using 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:23:19$ echo $FOSS_ONLY
5021:23:19$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5121:23:19$ export GOPATH=$CI_PROJECT_DIR/.go
5221:23:19$ mkdir -p $GOPATH
5321:23:19$ source scripts/utils.sh
5421:23:19$ log_disk_usage "true"
5521:23:19*******************************************************
5621:23:19This runner currently has 27G free disk space.
5721:23:19*******************************************************
58 21:23:19 Disk usage detail 00:07
5921:23:19df -h
6021:23:19Filesystem Size Used Avail Use% Mounted on
6121:23:19overlay 46G 19G 27G 41% /
6221:23:19tmpfs 64M 0 64M 0% /dev
6321:23:19shm 256M 0 256M 0% /dev/shm
6421:23:19/dev/sda1 46G 19G 27G 41% /cache
6521:23:19tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6621:23:19tmpfs 3.9G 0 3.9G 0% /proc/acpi
6721:23:19tmpfs 3.9G 0 3.9G 0% /proc/scsi
6821:23:19tmpfs 3.9G 0 3.9G 0% /sys/firmware
6921:23:19du -h -d 1
7021:23:197.4M ./workhorse
7121:23:19148K ./bin
7221:23:191.2M ./.gitlab
7321:23:19177M ./.git
7421:23:194.0K ./builds
7521:23:1928M ./lib
7621:23:198.0K ./.vscode
7721:23:19560K ./storybook
7821:23:1924K ./log
7921:23:191.8M ./data
8021:23:1911M ./qa
8121:23:193.2M ./.rubocop_todo
8221:23:19856M ./vendor
8321:23:191023M ./public
8421:23:1962M ./doc
8521:23:19484K ./danger
8621:23:1932K ./rspec
8721:23:19556K ./tooling
8821:23:20146M ./ee
8921:23:201.3M ./knapsack
9021:23:20300K ./patches
9121:23:204.3M ./changelogs
9221:23:2020K ./haml_lint
9321:23:2028K ./metrics_server
9421:23:20108K ./keeps
9521:23:2036K ./shared
9621:23:2044K ./generator_templates
9721:23:201.3M ./scripts
9821:23:20763M ./tmp
9921:23:20127M ./spec
10021:23:2020K ./file_hooks
10121:23:2032K ./crystalball
10221:23:2016K ./sidekiq_cluster
10321:23:2017M ./gems
10421:23:201.2M ./fixtures
10521:23:20964K ./rubocop
10621:23:204.0K ./.go
10721:23:2033M ./db
10821:23:2012K ./.github
10921:23:20129M ./locale
11021:23:2020M ./config
11121:23:2082M ./app
11221:23:2020K ./.lefthook
11321:23:203.5G .
11421:23:20$ source scripts/prepare_build.sh
115 21:23:20 Installing gems 00:30
14621:23:50Using two connections, single database config (config/database.yml.postgresql)
14721:23:50Geo DB won't be set up.
14821:23:50Embedding DB won't be set up.
149 21:23:50 Setting up DBs 01:16
16221:25:06$ source ./scripts/rspec_helpers.sh
16321:25:06$ run_timed_command "gem install knapsack --no-document"
16421:25:06$ gem install knapsack --no-document
16521:25:07Successfully installed knapsack-4.0.0
16621:25:071 gem installed
16721:25:06==> 'gem install knapsack --no-document' succeeded in 1 seconds.
16821:25:07$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
169 21:25:07 Spawning Gitaly 00:07
17321:25:14$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17421:25:14$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17521:25:14$ tooling/bin/create_job_metrics_file || true
17621:25:18[job-metrics] Creating the job metrics file for the CI/CD job.
17721:25:18$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
178 21:25:18 RSpec 06:52
17921:25:18[21:25:18] Starting rspec_parallelized_job
18021:25:07RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
18121:25:18KNAPSACK_GENERATE_REPORT: true
18221:25:18FLAKY_RSPEC_GENERATE_REPORT: true
18321:25:18KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb
18421:25:18KNAPSACK_LOG_LEVEL: debug
18521:25:18KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_single-db-ci-connection_26_26_278964_report.json
18621:25:18FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18721:25:18FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_single-db-ci-connection_26_26_278964_report.json
18821:25:18NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_single-db-ci-connection_26_26_278964_report.json
18921:25:18RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8368446133.txt
19021:25:18CRYSTALBALL:
19121:25:18RSPEC_TESTS_MAPPING_ENABLED:
19221:25:18RSPEC_TESTS_FILTER_FILE:
19321:25:18Shell set options (set -o) enabled:
19421:25:18braceexpand on
19521:25:18hashall on
19621:25:18interactive-comments on
19721:25:18pipefail on
19821:25:27Knapsack report generator started!
19921:25:18/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:26:23DEPRECATION 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:26:40warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
20221:26:40Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20321:26:46Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
20421:26:46# [RSpecRunTime] Starting RSpec timer...
20521:26:46[TEST PROF INFO] EventProf enabled (sql.active_record)
20621:26:46unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20721:26:54Test environment set up in 2.743391472 seconds
20821:26:55# [RSpecRunTime] Starting example group spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb. Expected to take 20.93 seconds.
20921:26:55QueueBackfillCiPipelineMessagesProjectId
21021:27:05ci: == [advisory_lock_connection] object_id: 1510000, pg_backend_pid: 125
21121:27:05ci: == 20241014081026 QueueBackfillCiPipelineMessagesProjectId: migrating =========
21221:27:05ci: == 20241014081026 QueueBackfillCiPipelineMessagesProjectId: migrated (0.1561s)
21321:27:05ci: == [advisory_lock_connection] object_id: 1510000, pg_backend_pid: 125
21421:27:11 schedules a new batched migration
21521:27:34# [RSpecRunTime] Finishing example group spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb. It took 39.15 seconds. Expected to take 20.93 seconds.
21621:27:34# [RSpecRunTime] Starting example group spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb. Expected to take 20.28 seconds.
21721:27:34QueueDeleteStalePackagesNpmMetadataCaches
21821:27:56main: == [advisory_lock_connection] object_id: 5890700, pg_backend_pid: 139
21921:27:56main: == 20240911115256 QueueDeleteStalePackagesNpmMetadataCaches: migrating ========
22021:27:56main: == 20240911115256 QueueDeleteStalePackagesNpmMetadataCaches: migrated (0.0855s)
22121:27:56main: == [advisory_lock_connection] object_id: 5890700, pg_backend_pid: 139
22221:28:02 schedules a new batched migration
22321:28:38# [RSpecRunTime] Finishing example group spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb. It took 1 minute 4.22 seconds. Expected to take 20.28 seconds.
22421:28:38# [RSpecRunTime] RSpec elapsed time: 1 minute 51.76 seconds. Current RSS: ~1205M. Threads: 1. load average: 0.99 1.14 1.28 1/273 388
22521:28:38.
22621:28:38# [RSpecRunTime] Starting example group spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb. Expected to take 13.41 seconds.
22721:28:38SetCorrectIdToExistingWorkItemTypes
22821:28:56main: == [advisory_lock_connection] object_id: 12009940, pg_backend_pid: 151
22921:28:56main: == 20240918204510 SetCorrectIdToExistingWorkItemTypes: migrating ==============
23021:28:56main: == 20240918204510 SetCorrectIdToExistingWorkItemTypes: migrated (0.0553s) =====
23121:28:56main: == [advisory_lock_connection] object_id: 12009940, pg_backend_pid: 151
23221:28:57 sets the correct id for every record in the table
23321:29:31# [RSpecRunTime] Finishing example group spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb. It took 53.1 seconds. Expected to take 13.41 seconds.
23421:29:31# [RSpecRunTime] RSpec elapsed time: 2 minutes 45.07 seconds. Current RSS: ~1210M. Threads: 1. load average: 1.00 1.12 1.26 1/273 389
23521:29:31.
23621:29:31# [RSpecRunTime] Starting example group spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb. Expected to take 6.5 seconds.
23721:29:31RequeueBackfillWorkItemHierarchyForEpics
23821:29:34main: == [advisory_lock_connection] object_id: 15369860, pg_backend_pid: 162
23921:29:34main: == 20241108111720 RequeueBackfillWorkItemHierarchyForEpics: migrating =========
24021:29:34main: == 20241108111720 RequeueBackfillWorkItemHierarchyForEpics: migrated (0.1038s)
24121:29:34main: == [advisory_lock_connection] object_id: 15369860, pg_backend_pid: 162
24221:29:41 schedules a new batched migration
24321:29:53# [RSpecRunTime] Finishing example group spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb. It took 22.06 seconds. Expected to take 6.5 seconds.
24421:29:54# [RSpecRunTime] RSpec elapsed time: 3 minutes 7.2 seconds. Current RSS: ~1206M. Threads: 1. load average: 1.08 1.12 1.26 1/273 390
24521:29:54.
24621:29:54# [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.
24721:29:54QueueBackfillVsCodeSettingsUuid
24821:29:54 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.)
24921:29:54# [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.
25021:29:54# [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.
25121:29:54EnsureMemberRolesNamesUniq
25221:29:54 #up
25321:29:54 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.)
25421:29:54# [RSpecRunTime] Finishing example group spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb.
25521:29:54# [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.
25621:29:54SetTrustedExternUidToFalseForExistingBitbucketIdentities
25721:29:54 #up
25821:29:54 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.)
25921:29:54# [RSpecRunTime] Finishing example group spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb.
26021:29:54# [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.
26121:29:54QueueBackfillBoardsEpicBoardLabelsGroupId
26221:29:54 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.)
26321:29:54# [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.
26421:29:54# [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.
26521:29:54QueueBackfillDastSiteProfileSecretVariablesProjectId
26621:29:54 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.)
26721:29:54# [RSpecRunTime] Finishing example group spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb.
26821:29:54# [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.
26921:29:54QueueBackfillMergeRequestContextCommitsProjectId
27021:29:54 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.)
27121:29:54# [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.
27221:29:54# [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.
27321:29:54QueueBackfillIssuesDatesWithWorkItemDatesSources
27421:29:54 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.)
27521:29:54# [RSpecRunTime] Finishing example group spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb.
27621:29:54# [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.
27721:29:54QueueMakeSecurityPolicyBotsPrivate
27821:29:54 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.)
27921:29:54# [RSpecRunTime] Finishing example group spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb.
28021:29:54# [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.
28121:29:54QueueBackfillSecurityOrchestrationPolicyRuleSchedulesNamespaceId
28221:29:54 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.)
28321:29:54# [RSpecRunTime] Finishing example group spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb.
28421:29:54# [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.
28521:29:54CreatePartitionsForAuditEventTables
28621:29:54 #up
28721:29:54 when audit event partitions exists
28821:29:54 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.)
28921:29:54 when audit event partitions does not exist
29021:29:54 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.)
29121:29:54 #down
29221:29:54 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.)
29321:29:54 #drop_partitions
29421:29:54 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.)
29521:29:54# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb. It took 0.03 second. Missing expected duration from Knapsack report for spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb.
29621:29:54# [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.
29721:29:54UpdateMaxTotalYamlSizeBytesValue
29821:29:54 #up
29921:29:54 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.)
30021:29:54 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.)
30121:29:54 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.)
30221:29:54 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.)
30321:29:54# [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.
30421:29:59auto_explain log contains 855 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-single-db-ci-connection-26-26.342.main.ndjson.gz
30521:29:59took 4.687646328
30621:30:01auto_explain log contains 856 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-single-db-ci-connection-26-26.342.ci.ndjson.gz
30721:30:01took 2.565636229
30821:30:01[TEST PROF INFO] EventProf results for sql.active_record
30921:30:01Total time: 01:42.077 of 02:59.188 (56.97%)
31021:30:01Total events: 57595
31121:30:01Top 5 slowest suites (by time):
31221:30:01QueueDeleteSt...MetadataCaches (./spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb:6) – 00:38.697 (20631 / 1) of 01:04.435 (60.06%)
31321:30:01SetCorrectIdT...gWorkItemTypes (./spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb:6) – 00:31.415 (16780 / 1) of 00:53.166 (59.09%)
31421:30:01QueueBackfill...sagesProjectId (./spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb:6) – 00:21.288 (12368 / 1) of 00:39.150 (54.38%)
31521:30:01RequeueBackfi...rarchyForEpics (./spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb:6) – 00:10.609 (7739 / 1) of 00:22.129 (47.94%)
31621:30:01QueueMakeSecu...icyBotsPrivate (./spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb:6) – 00:00.007 (7 / 1) of 00:00.032 (24.56%)
31721:30:01Knapsack report was generated. Preview:
31821:30:01{
31921:30:01 "spec/migrations/20241014081026_queue_backfill_ci_pipeline_messages_project_id_spec.rb": 16.861853582999174,
32021:30:01 "spec/migrations/20240911115256_queue_delete_stale_packages_npm_metadata_caches_spec.rb": 28.375673962000292,
32121:30:01 "spec/migrations/20240918204510_set_correct_id_to_existing_work_item_types_spec.rb": 18.87097723000079,
32221:30:01 "spec/migrations/20241108111720_requeue_backfill_work_item_hierarchy_for_epics_spec.rb": 9.590957574999265
32321:30:01}
32421:30:01Knapsack global time execution for tests: 01m 13s
32521:30:01Pending: (Failures listed here are expected and do not affect your suite's status)
32621:30:01 1) QueueBackfillVsCodeSettingsUuid schedules a new batched migration
32721:30:01 # 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.
32821:30:01 # ./spec/migrations/20231130140901_queue_backfill_vs_code_settings_uuid_spec.rb:9
32921:30:01 2) EnsureMemberRolesNamesUniq#up updates the duplicated names with higher id
33021:30:01 # 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.
33121:30:01 # ./spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb:28
33221:30:01 3) SetTrustedExternUidToFalseForExistingBitbucketIdentities#up sets trusted_extern_uid to false for existing bitbucket identities
33321:30:01 # 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.
33421:30:01 # ./spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb:36
33521:30:01 4) QueueBackfillBoardsEpicBoardLabelsGroupId schedules a new batched migration
33621:30:01 # 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.
33721:30:01 # ./spec/migrations/20240521090846_queue_backfill_boards_epic_board_labels_group_id_spec.rb:10
33821:30:01 5) QueueBackfillDastSiteProfileSecretVariablesProjectId schedules a new batched migration
33921:30:01 # 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.
34021:30:01 # ./spec/migrations/20240605192711_queue_backfill_dast_site_profile_secret_variables_project_id_spec.rb:10
34121:30:01 6) QueueBackfillMergeRequestContextCommitsProjectId schedules a new batched migration
34221:30:01 # 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.
34321:30:01 # ./spec/migrations/20240613072542_queue_backfill_merge_request_context_commits_project_id_spec.rb:10
34421:30:01 7) QueueBackfillIssuesDatesWithWorkItemDatesSources schedules a new batched migration
34521:30:01 # 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.
34621:30:01 # ./spec/migrations/20240702212015_queue_backfill_issues_dates_with_work_item_dates_sources_spec.rb:9
34721:30:01 8) QueueMakeSecurityPolicyBotsPrivate schedules a new batched migration
34821:30:01 # 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.
34921:30:01 # ./spec/migrations/20240729124632_queue_make_security_policy_bots_private_spec.rb:9
35021:30:01 9) QueueBackfillSecurityOrchestrationPolicyRuleSchedulesNamespaceId schedules a new batched migration
35121:30:01 # 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.
35221:30:01 # ./spec/migrations/20240816130544_queue_backfill_security_orchestration_policy_rule_schedules_namespace_id_spec.rb:10
35321:30:01 10) CreatePartitionsForAuditEventTables#up when audit event partitions exists calls drop partitions and create daterange partitions with correct args
35421:30:01 # 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.
35521:30:01 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:15
35621:30:01 11) CreatePartitionsForAuditEventTables#up when audit event partitions does not exist calls drop partitions and create daterange partitions with correct args
35721:30:01 # 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.
35821:30:01 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:15
35921:30:01 12) CreatePartitionsForAuditEventTables#down calls drop partitions with correct args
36021:30:01 # 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.
36121:30:01 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:67
36221:30:01 13) CreatePartitionsForAuditEventTables#drop_partitions drops all partitions
36321:30:01 # 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.
36421:30:01 # ./spec/migrations/db/post_migrate/20240527083319_create_partitions_for_audit_event_tables_spec.rb:101
36521:30:01 14) UpdateMaxTotalYamlSizeBytesValue#up updates the default value from 1MB to 2MB
36621:30:01 # 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.
36721:30:01 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:10
36821:30:01 15) UpdateMaxTotalYamlSizeBytesValue#up increases non-default value by 20%
36921:30:01 # 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.
37021:30:01 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:18
37121:30:01 16) UpdateMaxTotalYamlSizeBytesValue#up does not change values that are already 2MB
37221:30:01 # 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.
37321:30:01 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:27
37421:30:01 17) UpdateMaxTotalYamlSizeBytesValue#up sets the value to max integer when 20% increase exceeds max int
37521:30:01 # 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.
37621:30:01 # ./spec/migrations/update_max_total_yaml_size_bytes_value_spec.rb:35
37721:30:01Finished in 3 minutes 14.9 seconds (files took 1 minute 23.52 seconds to load)
37821:30:0121 examples, 0 failures, 17 pending
37921:30:01Randomized with seed 59393
38021:30:09[TEST PROF INFO] Time spent in factories: 00:00.316 (0.16% of total time)
38121:30:09RSpec exited with 0.
38221:30:09No examples to retry, congrats!
383 21:30:10 Running after_script 00:17
38421:30:10Running after script...
38521:30:10$ source scripts/utils.sh
38621:30:10$ log_disk_usage
38721:30:11*******************************************************
38821:30:11This runner currently has 27G free disk space.
38921:30:11*******************************************************
390 21:30:11 Disk usage detail 00:07
44921:30:18$ bundle exec gem list gitlab_quality-test_tooling
45021:30:21gitlab_quality-test_tooling (2.1.0)
45121:30:21$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
452 21:30:21 Report test failures 00:00
45421:30:21$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
455 21:30:21 Report test flakiness 00:00
45721:30:21$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
458 21:30:21 Report test slowness 00:00
46021:30:21$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
461 21:30:21 Report test slowness in MR note 00:02
46421:30:23$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
465 21:30:23 Report test files close to timing out 00:02
46821:30:25$ tooling/bin/push_job_metrics || true
46921:30:27[job-metrics] Pushing job metrics file for the CI/CD job.
47021:30:27[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
471 21:30:27 Saving cache for successful job 00:01
47221:30:28Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
473 21:30:28 Uploading artifacts for successful job 00:06
47421:30:29Uploading artifacts...
47521:30:30auto_explain/: found 3 matching artifact files and directories
47621:30:30coverage/: found 5 matching artifact files and directories
47721:30:30crystalball/: found 2 matching artifact files and directories
47821:30:30WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
47921:30:30knapsack/: found 4 matching artifact files and directories
48021:30:30rspec/: found 16 matching artifact files and directories
48121:30:30WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
48221:30:30log/*.log: found 13 matching artifact files and directories
48321:30:30WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8368446133/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
48421:30:30WARNING: Retrying... context=artifacts-uploader error=request redirected
48521:30:32Uploading artifacts as "archive" to coordinator... 201 Created id=8368446133 responseStatus=201 Created token=glcbt-66
48621:30:32Uploading artifacts...
48721:30:32rspec/rspec-*.xml: found 1 matching artifact files and directories
48821:30:32WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8368446133/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
48921:30:32WARNING: Retrying... context=artifacts-uploader error=request redirected
49021:30:34Uploading artifacts as "junit" to coordinator... 201 Created id=8368446133 responseStatus=201 Created token=glcbt-66
491 21:30:34 Cleaning up project directory and file based variables 00:01
49221:30:44Job succeeded