Skip to content
Snippets Groups Projects

rspec background_migration pg14 4/5

Passed Started by
Krasimir Angelov
@krasio
1Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
2 on green-4.private.runners-manager.gitlab.com/gitlab.com/gitlab-org rpvz2FF9, system ID: s_d704414ba02a
3 feature flags: FF_NETWORK_PER_BUILD:true
4Resolving secrets
5 Preparing the "docker+machine" executor 00:06
6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
8Authenticating with credentials from job payload (GitLab Registry)
9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
10Using 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 ...
11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
14Authenticating with credentials from job payload (GitLab Registry)
15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
16Using 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 ...
17Starting service redis:6.2-alpine ...
18Pulling docker image redis:6.2-alpine ...
19Using docker image sha256:0fc0b666ef9eb249e61edd4e2ec434a328e98117079727c5e7cf6dc9a00d7a94 for redis:6.2-alpine with digest redis@sha256:c6abddbb4223951cf5cb54a19d4a83418582fa69c97c0458ab4f0974f24f119c ...
20Waiting for services to be up and running (timeout 30 seconds)...
21Authenticating with credentials from job payload (GitLab Registry)
22Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
23Using docker image sha256:d10d211334d3c94a5e626cf961467431b7d3f3bd8b6109e5caf9357421c0117b for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-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.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14@sha256:3c4cd9a237deb59350b4836552151ba2aae96247034074c8f0c2ccaff4558cab ...
24 Preparing environment 00:00
25Running on runner-rpvz2ff9-project-278964-concurrent-0 via runner-rpvz2ff9-private-1717022791-5e767e1d...
26 Getting source from Git repository 00:01
27Skipping Git repository setup
28Skipping Git checkout
29Skipping Git submodules setup
30 Restoring cache 00:21
31Checking cache for ruby-gems-debian-bookworm-ruby-3.2-17...
32Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2-17
33Successfully extracted cache
34 Downloading artifacts 00:43
35Downloading artifacts for clone-gitlab-repo (6975007209)...
36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007209 responseStatus=200 OK token=glcbt-65
37WARNING: Part of .git directory is on the list of files to extract
38WARNING: This may introduce unexpected problems
39Downloading artifacts for compile-test-assets (6975007223)...
40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007223 responseStatus=200 OK token=glcbt-65
41Downloading artifacts for detect-tests (6975007229)...
42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007229 responseStatus=200 OK token=glcbt-65
43Downloading artifacts for retrieve-tests-metadata (6975007235)...
44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007235 responseStatus=200 OK token=glcbt-65
45Downloading artifacts for setup-test-env (6975007225)...
46Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6975007225 responseStatus=200 OK token=glcbt-65
47 Executing "step_script" stage of the job script
48Using docker image sha256:d10d211334d3c94a5e626cf961467431b7d3f3bd8b6109e5caf9357421c0117b for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-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.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14@sha256:3c4cd9a237deb59350b4836552151ba2aae96247034074c8f0c2ccaff4558cab ...
49$ echo $FOSS_ONLY
50$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
51$ export GOPATH=$CI_PROJECT_DIR/.go
52$ mkdir -p $GOPATH
53$ source scripts/utils.sh
54$ source scripts/prepare_build.sh
55 Installing gems 00:14
755Using decomposed database config (config/database.yml.decomposed-postgresql)
756Geo DB won't be set up.
757Embedding DB won't be set up.
758 Setting up DBs 00:57
769$ source ./scripts/rspec_helpers.sh
770$ run_timed_command "gem install knapsack --no-document"
771$ gem install knapsack --no-document
772Successfully installed knapsack-4.0.0
7731 gem installed
774==> 'gem install knapsack --no-document' succeeded in 1 seconds.
775$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
776 Spawning Gitaly 00:03
780$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
781$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
782$ tooling/bin/create_job_metrics_file || true
783[job-metrics] Creating the job metrics file for the CI/CD job.
784$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
785 RSpec 48:16
786[23:31:33] Starting rspec_parallelized_job
787RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
788KNAPSACK_GENERATE_REPORT: true
789FLAKY_RSPEC_GENERATE_REPORT: true
790KNAPSACK_TEST_FILE_PATTERN: spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
791KNAPSACK_LOG_LEVEL: debug
792KNAPSACK_REPORT_PATH: knapsack/rspec_background_migration_pg14_4_5_278964_report.json
793FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
794FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_background_migration_pg14_4_5_278964_report.json
795NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_background_migration_pg14_4_5_278964_report.json
796RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6975007398.txt
797CRYSTALBALL:
798RSPEC_TESTS_MAPPING_ENABLED:
799RSPEC_TESTS_FILTER_FILE:
800Shell set options (set -o) enabled:
801braceexpand on
802hashall on
803interactive-comments on
804pipefail on
805Knapsack report generator started!
806warning: parser/current is loading parser/ruby32, which recognizes 3.2.3-compliant syntax, but you are running 3.2.4.
807Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
808Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
809# [RSpecRunTime] Starting RSpec timer...
810[TEST PROF INFO] EventProf enabled (sql.active_record)
811unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
812unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
813Test environment set up in 0.540253392 seconds
814# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_resource_link_events_spec.rb. Expected to take 3 minutes 42.18 seconds.
815Gitlab::BackgroundMigration::BackfillResourceLinkEvents
816 #perform
817 does nothing when relevant notes do not exist
818 for 'relate_to_parent' system_note_metadata record
819 when issue_type_name is `issue`
820 behaves like a resource_link_event is correctly created
821 correctly backfills a resource_link_event record
822 when issue_type_name is not `issue`
823 behaves like a resource_link_event is correctly created
824 correctly backfills a resource_link_event record
825# [RSpecRunTime] RSpec elapsed time: 2 minutes 13.52 seconds. Current RSS: ~1135M. Threads: 2. load average: 0.97 1.00 1.00 1/287 393
826.
827 for 'unrelate_to_parent' system_note_metadata record
828 when issue_type_name is `issue`
829 behaves like a resource_link_event is correctly created
830 correctly backfills a resource_link_event record
831# [RSpecRunTime] RSpec elapsed time: 2 minutes 33.44 seconds. Current RSS: ~1136M. Threads: 2. load average: 0.90 0.98 0.99 1/287 394
832.
833 when issue_type_name is not `issue`
834 behaves like a resource_link_event is correctly created
835 correctly backfills a resource_link_event record
836# [RSpecRunTime] RSpec elapsed time: 2 minutes 53.47 seconds. Current RSS: ~1138M. Threads: 2. load average: 0.91 0.98 0.99 1/287 395
837.
838 when a backfilled note exists
839 correctly backfills the system notes without those that have been backfilled
840# [RSpecRunTime] RSpec elapsed time: 3 minutes 13.17 seconds. Current RSS: ~1125M. Threads: 2. load average: 1.02 1.00 1.00 2/286 396
841.
842 with unexpected note content
843 when note iid is prefixed
844 does not create resource_link_events record
845# [RSpecRunTime] RSpec elapsed time: 3 minutes 33.46 seconds. Current RSS: ~1141M. Threads: 2. load average: 1.09 1.02 1.01 1/286 397
846.
847INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
848INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
849INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
850INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
851INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
852INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
853INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
854INFO: analyzing "public.p_ci_job_annotations" inheritance tree
855INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
856INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
857INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
858INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
859INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
860INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
861INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
862INFO: analyzing "public.ci_builds_metadata"
863INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
864INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
865INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
866INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
867INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
868INFO: analyzing "public.p_ci_builds" inheritance tree
869INFO: analyzing "public.ci_builds"
870INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
871INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
872INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
873INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
874INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
875I, [2024-05-29T23:36:11.848171 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
876I, [2024-05-29T23:36:31.796317 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
877INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
878INFO: analyzing "public.ci_pipeline_variables"
879INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
880I, [2024-05-29T23:36:33.427049 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
881I, [2024-05-29T23:36:33.428174 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
882I, [2024-05-29T23:36:33.429219 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
883INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
884INFO: analyzing "public.ci_pipeline_variables"
885INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
886INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
887INFO: analyzing "public.ci_job_artifacts"
888INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
889I, [2024-05-29T23:36:39.946622 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
890I, [2024-05-29T23:36:39.947675 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
891I, [2024-05-29T23:36:39.948430 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
892INFO: analyzing "public.p_ci_stages" inheritance tree
893INFO: analyzing "public.ci_stages"
894INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
895I, [2024-05-29T23:36:43.382651 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
896I, [2024-05-29T23:36:43.383753 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
897I, [2024-05-29T23:36:43.384472 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
898I, [2024-05-29T23:36:44.678512 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
899I, [2024-05-29T23:36:51.405078 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
900# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_resource_link_events_spec.rb. It took 4 minutes 50.49 seconds. Expected to take 3 minutes 42.18 seconds.
901# [RSpecRunTime] RSpec elapsed time: 4 minutes 54.86 seconds. Current RSS: ~1118M. Threads: 2. load average: 1.02 1.01 1.00 1/286 398
902.
903# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb. Expected to take 2 minutes 29.89 seconds.
904Gitlab::BackgroundMigration::BackfillVulnerabilityReadsClusterAgent
905 #filter_batch
906 pick only vulnerability reads where report_type = 7
907# [RSpecRunTime] RSpec elapsed time: 6 minutes 23.85 seconds. Current RSS: ~1164M. Threads: 2. load average: 1.09 1.03 1.01 1/287 399
908.
909 #perform
910 backfills `casted_cluster_agent_id` for the selected records
911 tracks timings of queries
912# [RSpecRunTime] RSpec elapsed time: 7 minutes 5.31 seconds. Current RSS: ~1165M. Threads: 2. load average: 1.15 1.06 1.01 1/287 400
913.
914INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
915INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
916INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
917INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
918INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
919INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
920INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
921INFO: analyzing "public.p_ci_job_annotations" inheritance tree
922INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
923INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
924INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
925INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
926INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
927INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
928INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
929INFO: analyzing "public.ci_builds_metadata"
930INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
931INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
932INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
933INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
934INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
935INFO: analyzing "public.p_ci_builds" inheritance tree
936INFO: analyzing "public.ci_builds"
937INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
938INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
939INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
940INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
941INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
942I, [2024-05-29T23:39:43.961580 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
943I, [2024-05-29T23:40:04.065847 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
944INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
945INFO: analyzing "public.ci_pipeline_variables"
946INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
947I, [2024-05-29T23:40:05.734009 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
948I, [2024-05-29T23:40:05.735263 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
949I, [2024-05-29T23:40:05.736135 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
950INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
951INFO: analyzing "public.ci_pipeline_variables"
952INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
953INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
954INFO: analyzing "public.ci_job_artifacts"
955INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
956I, [2024-05-29T23:40:11.805389 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
957I, [2024-05-29T23:40:11.806429 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
958I, [2024-05-29T23:40:11.807618 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
959INFO: analyzing "public.p_ci_stages" inheritance tree
960INFO: analyzing "public.ci_stages"
961INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
962I, [2024-05-29T23:40:15.139936 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
963I, [2024-05-29T23:40:15.141263 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
964I, [2024-05-29T23:40:15.142048 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
965I, [2024-05-29T23:40:16.306815 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
966I, [2024-05-29T23:40:22.851058 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
967# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb. It took 3 minutes 30.9 seconds. Expected to take 2 minutes 29.89 seconds.
968# [RSpecRunTime] RSpec elapsed time: 8 minutes 25.79 seconds. Current RSS: ~1110M. Threads: 2. load average: 1.07 1.06 1.01 1/287 401
969.
970# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/destroy_invalid_members_spec.rb. Expected to take 2 minutes 16.55 seconds.
971Gitlab::BackgroundMigration::DestroyInvalidMembers
972 removes invalid memberships but keeps valid ones
973 tracks timings of queries
974 logs IDs of deleted records
975INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
976INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
977INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
978INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
979INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
980INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
981INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
982INFO: analyzing "public.p_ci_job_annotations" inheritance tree
983INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
984INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
985INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
986INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
987INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
988INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
989INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
990INFO: analyzing "public.ci_builds_metadata"
991INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
992INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
993INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
994INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
995INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
996INFO: analyzing "public.p_ci_builds" inheritance tree
997INFO: analyzing "public.ci_builds"
998INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
999INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1000INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1001INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1002INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1003I, [2024-05-29T23:43:11.460684 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1004I, [2024-05-29T23:43:31.359523 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1005INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1006INFO: analyzing "public.ci_pipeline_variables"
1007INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1008I, [2024-05-29T23:43:33.006043 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1009I, [2024-05-29T23:43:33.007033 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1010I, [2024-05-29T23:43:33.007732 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1011INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1012INFO: analyzing "public.ci_pipeline_variables"
1013INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1014INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1015INFO: analyzing "public.ci_job_artifacts"
1016INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1017I, [2024-05-29T23:43:39.018937 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1018I, [2024-05-29T23:43:39.020109 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1019I, [2024-05-29T23:43:39.020885 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1020INFO: analyzing "public.p_ci_stages" inheritance tree
1021INFO: analyzing "public.ci_stages"
1022INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1023I, [2024-05-29T23:43:42.294858 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1024I, [2024-05-29T23:43:42.296605 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1025I, [2024-05-29T23:43:42.297568 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1026I, [2024-05-29T23:43:43.502330 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1027I, [2024-05-29T23:43:49.675240 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1028# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/destroy_invalid_members_spec.rb. It took 3 minutes 26.39 seconds. Expected to take 2 minutes 16.55 seconds.
1029# [RSpecRunTime] RSpec elapsed time: 11 minutes 52.21 seconds. Current RSS: ~1111M. Threads: 2. load average: 1.06 1.04 1.00 1/287 402
1030.
1031# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_uuid_conversion_column_in_vulnerability_occurrences_spec.rb. Expected to take 2 minutes 0.66 second.
1032Gitlab::BackgroundMigration::BackfillUuidConversionColumnInVulnerabilityOccurrences
1033 #perform
1034 backfills the uuid_convert_string_to_uuid column
1035 doesn't change the UUID for exisiting records
1036# [RSpecRunTime] RSpec elapsed time: 13 minutes 39.6 seconds. Current RSS: ~1168M. Threads: 2. load average: 0.89 0.98 0.99 1/287 403
1037.
1038INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1039INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1040INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1041INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1042INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1043INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1044INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1045INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1046INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1047INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1048INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1049INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1050INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1051INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1052INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1053INFO: analyzing "public.ci_builds_metadata"
1054INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1055INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1056INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1057INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1058INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1059INFO: analyzing "public.p_ci_builds" inheritance tree
1060INFO: analyzing "public.ci_builds"
1061INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1062INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1063INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1064INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1065INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1066I, [2024-05-29T23:46:17.929704 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1067I, [2024-05-29T23:46:38.740441 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1068INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1069INFO: analyzing "public.ci_pipeline_variables"
1070INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1071I, [2024-05-29T23:46:40.416126 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1072I, [2024-05-29T23:46:40.417099 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1073I, [2024-05-29T23:46:40.417761 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1074INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1075INFO: analyzing "public.ci_pipeline_variables"
1076INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1077INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1078INFO: analyzing "public.ci_job_artifacts"
1079INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1080I, [2024-05-29T23:46:46.202983 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1081I, [2024-05-29T23:46:46.204387 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1082I, [2024-05-29T23:46:46.205465 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1083INFO: analyzing "public.p_ci_stages" inheritance tree
1084INFO: analyzing "public.ci_stages"
1085INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1086I, [2024-05-29T23:46:49.547818 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1087I, [2024-05-29T23:46:49.549214 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1088I, [2024-05-29T23:46:49.549969 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1089I, [2024-05-29T23:46:50.696112 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1090I, [2024-05-29T23:46:56.935173 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1091# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_uuid_conversion_column_in_vulnerability_occurrences_spec.rb. It took 3 minutes 7.11 seconds. Expected to take 2 minutes 0.66 second.
1092# [RSpecRunTime] RSpec elapsed time: 14 minutes 59.36 seconds. Current RSS: ~1129M. Threads: 2. load average: 1.03 0.99 1.00 1/287 404
1093.
1094# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_owasp_top_ten_of_vulnerability_reads_spec.rb. Expected to take 1 minute 50.8 seconds.
1095Gitlab::BackgroundMigration::BackfillOwaspTopTenOfVulnerabilityReads
1096 #perform
1097 with owasp top 10 data
1098 updates vulnerability_reads
1099 updates vulnerability_reads with correct mapping
1100# [RSpecRunTime] RSpec elapsed time: 15 minutes 50.32 seconds. Current RSS: ~1138M. Threads: 2. load average: 1.08 1.01 1.01 1/289 405
1101.
1102 with incorrect owasp top 10 data
1103 with incorrect long format external_id
1104 behaves like does not update vulnerability_reads
1105 is expected to be nil
1106# [RSpecRunTime] RSpec elapsed time: 15 minutes 59.64 seconds. Current RSS: ~1134M. Threads: 2. load average: 1.07 1.01 1.00 1/289 406
1107.
1108 with incorrect short format external_id
1109 behaves like does not update vulnerability_reads
1110 is expected to be nil
1111# [RSpecRunTime] RSpec elapsed time: 16 minutes 9.23 seconds. Current RSS: ~1132M. Threads: 2. load average: 1.14 1.03 1.01 1/289 407
1112.
1113 with incorrect external_type
1114 behaves like does not update vulnerability_reads
1115 is expected to be nil
1116# [RSpecRunTime] RSpec elapsed time: 16 minutes 18.64 seconds. Current RSS: ~1138M. Threads: 2. load average: 1.12 1.02 1.01 1/289 408
1117.
1118 with no vulnerability identifiers match
1119 does not update vulnerability_reads
1120# [RSpecRunTime] RSpec elapsed time: 16 minutes 28.32 seconds. Current RSS: ~1135M. Threads: 2. load average: 1.10 1.02 1.01 1/289 409
1121.
1122INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1123INFO: analyzing "public.ci_pipeline_variables"
1124INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1125INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1126INFO: analyzing "public.ci_job_artifacts"
1127INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1128I, [2024-05-29T23:48:54.193812 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1129I, [2024-05-29T23:48:54.195089 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1130I, [2024-05-29T23:48:54.195842 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1131INFO: analyzing "public.p_ci_stages" inheritance tree
1132INFO: analyzing "public.ci_stages"
1133INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1134I, [2024-05-29T23:48:57.489754 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1135I, [2024-05-29T23:48:57.490799 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1136I, [2024-05-29T23:48:57.491578 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1137I, [2024-05-29T23:48:58.727448 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1138I, [2024-05-29T23:49:04.995636 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1139# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_owasp_top_ten_of_vulnerability_reads_spec.rb. It took 2 minutes 7.66 seconds. Expected to take 1 minute 50.8 seconds.
1140# [RSpecRunTime] RSpec elapsed time: 17 minutes 7.05 seconds. Current RSS: ~1114M. Threads: 2. load average: 0.99 1.00 1.00 1/289 410
1141.
1142# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb. Expected to take 1 minute 43.36 seconds.
1143Gitlab::BackgroundMigration::SetLegacyOpenSourceLicenseAvailableForNonPublicProjects
1144 sets `legacy_open_source_license_available` attribute to false for non-public projects
1145INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1146INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1147INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1148INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1149INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1150INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1151INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1152INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1153INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1154INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1155INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1156INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1157INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1158INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1159INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1160INFO: analyzing "public.ci_builds_metadata"
1161INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1162INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1163INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1164INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1165INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1166INFO: analyzing "public.p_ci_builds" inheritance tree
1167INFO: analyzing "public.ci_builds"
1168INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1169INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1170INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1171INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1172INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1173I, [2024-05-29T23:51:12.539617 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1174I, [2024-05-29T23:51:33.025021 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1175INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1176INFO: analyzing "public.ci_pipeline_variables"
1177INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1178I, [2024-05-29T23:51:34.821556 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1179I, [2024-05-29T23:51:34.822629 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1180I, [2024-05-29T23:51:34.823412 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1181INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1182INFO: analyzing "public.ci_pipeline_variables"
1183INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1184INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1185INFO: analyzing "public.ci_job_artifacts"
1186INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1187I, [2024-05-29T23:51:40.799979 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1188I, [2024-05-29T23:51:40.800961 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1189I, [2024-05-29T23:51:40.801659 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1190INFO: analyzing "public.p_ci_stages" inheritance tree
1191INFO: analyzing "public.ci_stages"
1192INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1193I, [2024-05-29T23:51:44.131674 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1194I, [2024-05-29T23:51:44.132712 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1195I, [2024-05-29T23:51:44.133791 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1196I, [2024-05-29T23:51:45.273482 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1197I, [2024-05-29T23:51:51.385309 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1198# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb. It took 2 minutes 46.79 seconds. Expected to take 1 minute 43.36 seconds.
1199# [RSpecRunTime] RSpec elapsed time: 19 minutes 53.88 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.95 0.99 1.00 1/288 411
1200.
1201# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb. Expected to take 1 minute 38.54 seconds.
1202Gitlab::BackgroundMigration::BackfillTopicsTitle
1203 correctly backfills the title of the topics
1204INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1205INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1206INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1207INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1208INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1209INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1210INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1211INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1212INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1213INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1214INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1215INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1216INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1217INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1218INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1219INFO: analyzing "public.ci_builds_metadata"
1220INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1221INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1222INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1223INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1224INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1225INFO: analyzing "public.p_ci_builds" inheritance tree
1226INFO: analyzing "public.ci_builds"
1227INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1228INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1229INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1230INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1231INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1232I, [2024-05-29T23:54:05.729792 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1233I, [2024-05-29T23:54:26.658434 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1234INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1235INFO: analyzing "public.ci_pipeline_variables"
1236INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1237I, [2024-05-29T23:54:28.441101 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1238I, [2024-05-29T23:54:28.442238 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1239I, [2024-05-29T23:54:28.443013 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1240INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1241INFO: analyzing "public.ci_pipeline_variables"
1242INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1243INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1244INFO: analyzing "public.ci_job_artifacts"
1245INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1246I, [2024-05-29T23:54:34.312777 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1247I, [2024-05-29T23:54:34.313807 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1248I, [2024-05-29T23:54:34.314652 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1249INFO: analyzing "public.p_ci_stages" inheritance tree
1250INFO: analyzing "public.ci_stages"
1251INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1252I, [2024-05-29T23:54:37.693907 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1253I, [2024-05-29T23:54:37.695003 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1254I, [2024-05-29T23:54:37.695739 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1255I, [2024-05-29T23:54:38.830316 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1256I, [2024-05-29T23:54:44.979647 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1257# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb. It took 2 minutes 52.91 seconds. Expected to take 1 minute 38.54 seconds.
1258# [RSpecRunTime] RSpec elapsed time: 22 minutes 46.82 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.99 0.99 1.00 1/286 412
1259.
1260# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/truncate_overlong_vulnerability_html_titles_spec.rb. Expected to take 1 minute 35.32 seconds.
1261Gitlab::BackgroundMigration::TruncateOverlongVulnerabilityHtmlTitles
1262 #perform
1263 truncates the vulnerability html title when longer than 800 characters
1264# [RSpecRunTime] RSpec elapsed time: 24 minutes 22.91 seconds. Current RSS: ~1173M. Threads: 2. load average: 0.97 0.98 1.00 1/286 413
1265.
1266The application_settings (main) table has 1277 columns.
1267Recreating the database
1268Dropped database 'gitlabhq_test'
1269Dropped database 'gitlabhq_test_ci'
1270Created database 'gitlabhq_test'
1271Created database 'gitlabhq_test_ci'
1272main: == [advisory_lock_connection] object_id: 268717500, pg_backend_pid: 286
1273main: == [advisory_lock_connection] object_id: 268717500, pg_backend_pid: 286
1274ci: == [advisory_lock_connection] object_id: 268816520, pg_backend_pid: 288
1275ci: == [advisory_lock_connection] object_id: 268816520, pg_backend_pid: 288
1276Databases re-creation done in 14.53474038999957
1277# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/truncate_overlong_vulnerability_html_titles_spec.rb. It took 1 minute 50.88 seconds. Expected to take 1 minute 35.32 seconds.
1278# [RSpecRunTime] RSpec elapsed time: 24 minutes 37.74 seconds. Current RSS: ~1103M. Threads: 2. load average: 0.97 0.98 1.00 1/286 416
1279.
1280# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_code_suggestions_namespace_settings_spec.rb. Expected to take 1 minute 31.69 seconds.
1281Gitlab::BackgroundMigration::BackfillCodeSuggestionsNamespaceSettings
1282 updates the code suggestions values only for group and user namespace
1283INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1284INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1285INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1286INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1287INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1288INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1289INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1290INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1291INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1292INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1293INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1294INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1295INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1296INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1297INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1298INFO: analyzing "public.ci_builds_metadata"
1299INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1300INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1301INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1302INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1303INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1304INFO: analyzing "public.p_ci_builds" inheritance tree
1305INFO: analyzing "public.ci_builds"
1306INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1307INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1308INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1309INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1310INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1311I, [2024-05-29T23:58:45.503907 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1312I, [2024-05-29T23:59:07.118637 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1313INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1314INFO: analyzing "public.ci_pipeline_variables"
1315INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1316I, [2024-05-29T23:59:08.841750 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1317I, [2024-05-29T23:59:08.842985 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1318I, [2024-05-29T23:59:08.843710 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1319INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1320INFO: analyzing "public.ci_pipeline_variables"
1321INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1322INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1323INFO: analyzing "public.ci_job_artifacts"
1324INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1325I, [2024-05-29T23:59:15.435187 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1326I, [2024-05-29T23:59:15.438444 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1327I, [2024-05-29T23:59:15.439710 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1328INFO: analyzing "public.p_ci_stages" inheritance tree
1329INFO: analyzing "public.ci_stages"
1330INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1331I, [2024-05-29T23:59:19.142221 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1332I, [2024-05-29T23:59:19.143808 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1333I, [2024-05-29T23:59:19.144942 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1334I, [2024-05-29T23:59:20.494914 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1335I, [2024-05-29T23:59:27.667609 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1336# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_code_suggestions_namespace_settings_spec.rb. It took 2 minutes 54.91 seconds. Expected to take 1 minute 31.69 seconds.
1337# [RSpecRunTime] RSpec elapsed time: 27 minutes 32.69 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.90 0.93 0.99 1/287 417
1338.
1339# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_users_with_defaults_spec.rb. Expected to take 1 minute 23.64 seconds.
1340Gitlab::BackgroundMigration::BackfillUsersWithDefaults
1341 backfills the null values with the default values
1342INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree
1343INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"
1344INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1345INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"
1346INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1347INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"
1348INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1349INFO: analyzing "public.p_ci_job_annotations" inheritance tree
1350INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"
1351INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1352INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"
1353INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1354INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"
1355INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1356INFO: analyzing "public.p_ci_builds_metadata" inheritance tree
1357INFO: analyzing "public.ci_builds_metadata"
1358INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1359INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"
1360INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1361INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"
1362INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1363INFO: analyzing "public.p_ci_builds" inheritance tree
1364INFO: analyzing "public.ci_builds"
1365INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1366INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"
1367INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1368INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"
1369INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1370I, [2024-05-30T00:01:13.498462 #343] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
1371I, [2024-05-30T00:01:36.632386 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1372INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1373INFO: analyzing "public.ci_pipeline_variables"
1374INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1375I, [2024-05-30T00:01:38.330466 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1376I, [2024-05-30T00:01:38.331937 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1377I, [2024-05-30T00:01:38.333214 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1378INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1379INFO: analyzing "public.ci_pipeline_variables"
1380INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1381INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1382INFO: analyzing "public.ci_job_artifacts"
1383INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1384I, [2024-05-30T00:01:45.060662 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1385I, [2024-05-30T00:01:45.061824 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1386I, [2024-05-30T00:01:45.062631 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1387INFO: analyzing "public.p_ci_stages" inheritance tree
1388INFO: analyzing "public.ci_stages"
1389INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1390I, [2024-05-30T00:01:48.602357 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1391I, [2024-05-30T00:01:48.603880 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1392I, [2024-05-30T00:01:48.604679 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1393I, [2024-05-30T00:01:50.033854 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1394I, [2024-05-30T00:01:57.004813 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1395# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_users_with_defaults_spec.rb. It took 2 minutes 29.3 seconds. Expected to take 1 minute 23.64 seconds.
1396# [RSpecRunTime] RSpec elapsed time: 30 minutes 2.02 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.97 0.95 0.99 1/287 418
1397.
1398# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vs_code_settings_uuid_spec.rb. Expected to take 1 minute 5.25 seconds.
1399Gitlab::BackgroundMigration::BackfillVsCodeSettingsUuid
1400 #perform
1401 when it finds vs_code_setting rows with empty uuid
1402 populates uuid column with a generated uuid
1403# [RSpecRunTime] RSpec elapsed time: 30 minutes 56.05 seconds. Current RSS: ~1159M. Threads: 2. load average: 0.76 0.90 0.98 1/287 419
1404.
1405 when it finds vs_code_setting rows with non-empty uuid
1406 populates uuid column with a generated uuid
1407# [RSpecRunTime] RSpec elapsed time: 31 minutes 9.1 seconds. Current RSS: ~1153M. Threads: 2. load average: 0.81 0.90 0.98 1/287 420
1408.
1409I, [2024-05-30T00:03:36.359236 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1410INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1411INFO: analyzing "public.ci_pipeline_variables"
1412INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1413I, [2024-05-30T00:03:38.133560 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1414I, [2024-05-30T00:03:38.135227 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1415I, [2024-05-30T00:03:38.136121 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1416INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1417INFO: analyzing "public.ci_pipeline_variables"
1418INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1419INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1420INFO: analyzing "public.ci_job_artifacts"
1421INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1422I, [2024-05-30T00:03:44.843333 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1423I, [2024-05-30T00:03:44.844808 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1424I, [2024-05-30T00:03:44.845722 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1425INFO: analyzing "public.p_ci_stages" inheritance tree
1426INFO: analyzing "public.ci_stages"
1427INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1428I, [2024-05-30T00:03:48.452064 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1429I, [2024-05-30T00:03:48.453389 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1430I, [2024-05-30T00:03:48.454181 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1431I, [2024-05-30T00:03:49.896307 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1432I, [2024-05-30T00:03:57.294474 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1433# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vs_code_settings_uuid_spec.rb. It took 2 minutes 1.33 seconds. Expected to take 1 minute 5.25 seconds.
1434# [RSpecRunTime] RSpec elapsed time: 32 minutes 3.39 seconds. Current RSS: ~1126M. Threads: 2. load average: 0.81 0.89 0.97 1/286 421
1435.
1436# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vs_code_settings_version_spec.rb. Expected to take 1 minute 2.1 seconds.
1437Gitlab::BackgroundMigration::BackfillVsCodeSettingsVersion
1438 #perform
1439 when it finds vs_code_setting rows with version that is nil or zero
1440 sets version field with default value for setting type
1441# [RSpecRunTime] RSpec elapsed time: 32 minutes 56.55 seconds. Current RSS: ~1164M. Threads: 2. load average: 0.92 0.91 0.98 1/286 422
1442.
1443 when it finds vs_code_setting rows with version that is not nil or zero
1444 does not set version field
1445# [RSpecRunTime] RSpec elapsed time: 33 minutes 9.27 seconds. Current RSS: ~1152M. Threads: 2. load average: 0.86 0.90 0.97 1/286 423
1446.
1447I, [2024-05-30T00:05:35.489502 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1448INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1449INFO: analyzing "public.ci_pipeline_variables"
1450INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1451I, [2024-05-30T00:05:37.276555 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1452I, [2024-05-30T00:05:37.277651 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1453I, [2024-05-30T00:05:37.278404 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1454INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1455INFO: analyzing "public.ci_pipeline_variables"
1456INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1457INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1458INFO: analyzing "public.ci_job_artifacts"
1459INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1460I, [2024-05-30T00:05:44.236496 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1461I, [2024-05-30T00:05:44.238161 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1462I, [2024-05-30T00:05:44.239175 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1463INFO: analyzing "public.p_ci_stages" inheritance tree
1464INFO: analyzing "public.ci_stages"
1465INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1466I, [2024-05-30T00:05:47.882137 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1467I, [2024-05-30T00:05:47.883205 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1468I, [2024-05-30T00:05:47.884748 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1469I, [2024-05-30T00:05:49.207280 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1470I, [2024-05-30T00:05:56.029362 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1471# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vs_code_settings_version_spec.rb. It took 1 minute 58.02 seconds. Expected to take 1 minute 2.1 seconds.
1472# [RSpecRunTime] RSpec elapsed time: 34 minutes 1.44 seconds. Current RSS: ~1119M. Threads: 2. load average: 1.00 0.93 0.98 1/286 424
1473.
1474# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_artifact_spec.rb. Expected to take 53.35 seconds.
1475Gitlab::BackgroundMigration::BackfillPartitionIdCiPipelineArtifact
1476 #perform
1477 when second partition does not exist
1478 does not execute the migration
1479# [RSpecRunTime] RSpec elapsed time: 34 minutes 44.94 seconds. Current RSS: ~1163M. Threads: 2. load average: 1.00 0.94 0.99 1/286 425
1480.
1481 when second partition exists
1482 fixes invalid records in the wrong the partition
1483# [RSpecRunTime] RSpec elapsed time: 34 minutes 54.81 seconds. Current RSS: ~1147M. Threads: 2. load average: 1.00 0.95 0.99 1/286 426
1484.
1485INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1486INFO: analyzing "public.ci_pipeline_variables"
1487INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1488INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1489INFO: analyzing "public.ci_job_artifacts"
1490INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1491I, [2024-05-30T00:07:21.689461 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1492I, [2024-05-30T00:07:21.690778 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1493I, [2024-05-30T00:07:21.691832 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1494INFO: analyzing "public.p_ci_stages" inheritance tree
1495INFO: analyzing "public.ci_stages"
1496INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1497I, [2024-05-30T00:07:25.519331 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1498I, [2024-05-30T00:07:25.521126 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1499I, [2024-05-30T00:07:25.522190 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1500I, [2024-05-30T00:07:26.867007 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1501I, [2024-05-30T00:07:34.009390 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1502# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_artifact_spec.rb. It took 1 minute 38.74 seconds. Expected to take 53.35 seconds.
1503# [RSpecRunTime] RSpec elapsed time: 35 minutes 40.22 seconds. Current RSS: ~1121M. Threads: 2. load average: 1.07 0.97 1.00 1/287 427
1504.
1505# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_branch_protection_namespace_setting_spec.rb. Expected to take 50.78 seconds.
1506Gitlab::BackgroundMigration::BackfillBranchProtectionNamespaceSetting
1507 updates default_branch_protection_defaults to a correct value
1508I, [2024-05-30T00:08:50.837545 #343] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
1509INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1510INFO: analyzing "public.ci_pipeline_variables"
1511INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1512I, [2024-05-30T00:08:52.653542 #343] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
1513I, [2024-05-30T00:08:52.654770 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1514I, [2024-05-30T00:08:52.655506 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1515INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
1516INFO: analyzing "public.ci_pipeline_variables"
1517INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1518INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
1519INFO: analyzing "public.ci_job_artifacts"
1520INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1521I, [2024-05-30T00:08:59.460003 #343] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
1522I, [2024-05-30T00:08:59.461730 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1523I, [2024-05-30T00:08:59.462646 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1524INFO: analyzing "public.p_ci_stages" inheritance tree
1525INFO: analyzing "public.ci_stages"
1526INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1527I, [2024-05-30T00:09:03.310165 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1528I, [2024-05-30T00:09:03.311970 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1529I, [2024-05-30T00:09:03.313393 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1530I, [2024-05-30T00:09:04.692843 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1531I, [2024-05-30T00:09:11.518172 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1532# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_branch_protection_namespace_setting_spec.rb. It took 1 minute 37.01 seconds. Expected to take 50.78 seconds.
1533# [RSpecRunTime] RSpec elapsed time: 37 minutes 17.27 seconds. Current RSS: ~1127M. Threads: 2. load average: 0.91 0.94 0.99 1/287 428
1534.
1535# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb. Expected to take 45.04 seconds.
1536Gitlab::BackgroundMigration::BackfillDefaultOrganizationUsers
1537 #perform
1538 with no entries for a regular user in organization_users
1539 adds regular users correctly with the default organization to organization_users
1540# [RSpecRunTime] RSpec elapsed time: 37 minutes 54.01 seconds. Current RSS: ~1154M. Threads: 2. load average: 0.95 0.95 0.99 1/287 429
1541.
1542 when user already exists in organization_users as an admin user
1543 updates the organization_users entry to a regular user
1544# [RSpecRunTime] RSpec elapsed time: 38 minutes 2.47 seconds. Current RSS: ~1134M. Threads: 2. load average: 0.96 0.95 0.99 1/287 430
1545.
1546INFO: analyzing "public.p_ci_stages" inheritance tree
1547INFO: analyzing "public.ci_stages"
1548INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
1549I, [2024-05-30T00:10:23.460893 #343] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
1550I, [2024-05-30T00:10:23.462251 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
1551I, [2024-05-30T00:10:23.463153 #343] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
1552I, [2024-05-30T00:10:24.775586 #343] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
1553I, [2024-05-30T00:10:31.975439 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1554# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb. It took 1 minute 20.09 seconds. Expected to take 45.04 seconds.
1555# [RSpecRunTime] RSpec elapsed time: 38 minutes 37.39 seconds. Current RSS: ~1130M. Threads: 2. load average: 0.97 0.95 1.00 1/287 431
1556.
1557# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_deployment_approvals_project_id_spec.rb. Expected to take 40.94 seconds.
1558Gitlab::BackgroundMigration::BackfillDeploymentApprovalsProjectId
1559 performs without error
1560 constructs a valid query
1561I, [2024-05-30T00:11:44.006526 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1562# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_deployment_approvals_project_id_spec.rb. It took 1 minute 13.63 seconds. Expected to take 40.94 seconds.
1563# [RSpecRunTime] RSpec elapsed time: 39 minutes 51.07 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.90 0.94 0.99 1/287 432
1564.
1565# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb. Expected to take 33.14 seconds.
1566Gitlab::BackgroundMigration::BackfillWorkItemParentLinksNamespaceId
1567 constructs a valid query
1568 performs without error
1569I, [2024-05-30T00:12:43.979271 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1570# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb. It took 58.27 seconds. Expected to take 33.14 seconds.
1571# [RSpecRunTime] RSpec elapsed time: 40 minutes 49.37 seconds. Current RSS: ~1131M. Threads: 2. load average: 0.99 0.95 1.00 1/286 433
1572.
1573# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_onboarding_status_step_url_spec.rb. Expected to take 31.49 seconds.
1574Gitlab::BackgroundMigration::BackfillOnboardingStatusStepUrl
1575 #perform
1576 updates the correct data
1577# [RSpecRunTime] RSpec elapsed time: 41 minutes 21.26 seconds. Current RSS: ~1168M. Threads: 2. load average: 1.00 0.96 1.00 1/286 434
1578.
1579I, [2024-05-30T00:13:47.097299 #343] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
1580# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_onboarding_status_step_url_spec.rb. It took 1 minute 3.08 seconds. Expected to take 31.49 seconds.
1581# [RSpecRunTime] RSpec elapsed time: 41 minutes 52.49 seconds. Current RSS: ~1119M. Threads: 2. load average: 0.87 0.93 0.99 1/287 435
1582.
1583# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/reset_status_on_container_repositories_spec.rb. Expected to take 20.25 seconds.
1584Gitlab::BackgroundMigration::ResetStatusOnContainerRepositories
1585 #filter_batch
1586 scopes the relation to delete scheduled container repositories
1587# [RSpecRunTime] RSpec elapsed time: 41 minutes 57.46 seconds. Current RSS: ~1132M. Threads: 2. load average: 0.88 0.93 0.99 1/287 436
1588.
1589 #perform
1590 resets status of container repositories with tags
1591 with the registry disabled
1592 behaves like resetting status of all container repositories scheduled for deletion
1593 resets all statuses
1594# [RSpecRunTime] RSpec elapsed time: 42 minutes 5.07 seconds. Current RSS: ~1117M. Threads: 2. load average: 0.90 0.94 0.99 1/287 437
1595.
1596 with the registry api url not defined
1597 behaves like resetting status of all container repositories scheduled for deletion
1598 resets all statuses
1599# [RSpecRunTime] RSpec elapsed time: 42 minutes 8.83 seconds. Current RSS: ~1115M. Threads: 2. load average: 0.83 0.92 0.99 1/287 438
1600.
1601 with a faraday error
1602 behaves like resetting status of all container repositories scheduled for deletion
1603 resets all statuses
1604# [RSpecRunTime] RSpec elapsed time: 42 minutes 12.67 seconds. Current RSS: ~1117M. Threads: 2. load average: 0.83 0.92 0.99 1/287 439
1605.
1606# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/reset_status_on_container_repositories_spec.rb. It took 27.77 seconds. Expected to take 20.25 seconds.
1607# [RSpecRunTime] RSpec elapsed time: 42 minutes 20.3 seconds. Current RSS: ~1103M. Threads: 2. load average: 0.85 0.92 0.99 1/287 440
1608.
1609# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/batching_strategies/loose_index_scan_batching_strategy_spec.rb. Expected to take 19.89 seconds.
1610Gitlab::BackgroundMigration::BatchingStrategies::LooseIndexScanBatchingStrategy#next_batch
1611 is expected to be < Gitlab::BackgroundMigration::BatchingStrategies::BaseStrategy
1612 when starting on the first batch
1613 returns the bounds of the next batch
1614# [RSpecRunTime] RSpec elapsed time: 42 minutes 27.88 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.95 0.94 0.99 1/287 441
1615.
1616 when additional batches remain
1617 returns the bounds of the next batch
1618# [RSpecRunTime] RSpec elapsed time: 42 minutes 31.57 seconds. Current RSS: ~1116M. Threads: 2. load average: 1.03 0.96 1.00 1/287 442
1619.
1620 when on the final batch
1621 returns the bounds of the next batch
1622# [RSpecRunTime] RSpec elapsed time: 42 minutes 35.29 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.95 0.94 0.99 1/287 443
1623.
1624 when no additional batches remain
1625 returns nil
1626# [RSpecRunTime] RSpec elapsed time: 42 minutes 38.95 seconds. Current RSS: ~1118M. Threads: 2. load average: 0.95 0.94 0.99 1/287 444
1627.
1628# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/batching_strategies/loose_index_scan_batching_strategy_spec.rb. It took 26.74 seconds. Expected to take 19.89 seconds.
1629# [RSpecRunTime] RSpec elapsed time: 42 minutes 47.07 seconds. Current RSS: ~1106M. Threads: 2. load average: 0.96 0.95 0.99 1/287 445
1630.
1631# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_boards_epic_lists_group_id_spec.rb. Expected to take 13.99 seconds.
1632Gitlab::BackgroundMigration::BackfillBoardsEpicListsGroupId
1633 performs without error
1634 constructs a valid query
1635# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_boards_epic_lists_group_id_spec.rb. It took 25.69 seconds. Expected to take 13.99 seconds.
1636# [RSpecRunTime] RSpec elapsed time: 43 minutes 12.8 seconds. Current RSS: ~1106M. Threads: 2. load average: 0.97 0.95 1.00 1/287 446
1637.
1638# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb. Expected to take 13.41 seconds.
1639Gitlab::BackgroundMigration::BackfillAuditEventsStreamingEventTypeFiltersGroupId
1640 performs without error
1641 constructs a valid query
1642# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb. It took 24.84 seconds. Expected to take 13.41 seconds.
1643# [RSpecRunTime] RSpec elapsed time: 43 minutes 37.68 seconds. Current RSS: ~1110M. Threads: 2. load average: 0.98 0.95 1.00 1/287 447
1644.
1645# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/remove_namespace_from_os_type_sbom_components_spec.rb. Expected to take 10.5 seconds.
1646Gitlab::BackgroundMigration::RemoveNamespaceFromOsTypeSbomComponents
1647 #perform
1648 succesfully removes the os namespace prefix
1649 with existing record in regards to name, purl_type and component_type
1650 rescues valid ActiveRecord::RecordNotUnique errors
1651# [RSpecRunTime] RSpec elapsed time: 43 minutes 45.06 seconds. Current RSS: ~1119M. Threads: 2. load average: 0.99 0.96 1.00 1/287 448
1652.
1653 with unexpected ActiveRecord::RecordNotUnique error
1654 raises unknown ActiveRecord::RecordNotUnique errors
1655# [RSpecRunTime] RSpec elapsed time: 43 minutes 48.77 seconds. Current RSS: ~1116M. Threads: 2. load average: 0.99 0.96 1.00 1/287 449
1656.
1657# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/remove_namespace_from_os_type_sbom_components_spec.rb. It took 18.79 seconds. Expected to take 10.5 seconds.
1658# [RSpecRunTime] RSpec elapsed time: 43 minutes 56.51 seconds. Current RSS: ~1104M. Threads: 2. load average: 0.99 0.96 1.00 1/287 450
1659.
1660# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_default_branch_protection_settings_spec.rb. Expected to take 7.18 seconds.
1661Gitlab::BackgroundMigration::BackfillDefaultBranchProtectionSettings
1662 updates default_branch_protection_defaults to a correct value
1663 when all the namespaces have `default_branch_protection` set
1664 does not update the settings
1665# [RSpecRunTime] RSpec elapsed time: 44 minutes 3.76 seconds. Current RSS: ~1117M. Threads: 2. load average: 0.99 0.96 1.00 1/287 451
1666.
1667# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_default_branch_protection_settings_spec.rb. It took 14.75 seconds. Expected to take 7.18 seconds.
1668# [RSpecRunTime] RSpec elapsed time: 44 minutes 11.29 seconds. Current RSS: ~1110M. Threads: 2. load average: 0.99 0.96 1.00 1/287 452
1669.
1670# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/update_sbom_components_name_based_on_pep503_spec.rb. Expected to take 6.6 seconds.
1671Gitlab::BackgroundMigration::UpdateSbomComponentsNameBasedOnPep503
1672 #perform
1673 successfully updates name according to PEP 0503
1674 with existing record in regards to name, purl_type and component_type
1675 raises ActiveRecord::RecordNotUnique
1676# [RSpecRunTime] RSpec elapsed time: 44 minutes 18.41 seconds. Current RSS: ~1121M. Threads: 2. load average: 0.91 0.95 0.99 1/287 453
1677.
1678# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/update_sbom_components_name_based_on_pep503_spec.rb. It took 14.59 seconds. Expected to take 6.6 seconds.
1679# [RSpecRunTime] RSpec elapsed time: 44 minutes 25.92 seconds. Current RSS: ~1112M. Threads: 2. load average: 0.92 0.95 1.00 1/287 454
1680.
1681# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/populate_topics_slug_column_spec.rb. Expected to take 4.23 seconds.
1682Gitlab::BackgroundMigration::PopulateTopicsSlugColumn
1683 #perform
1684 populates topics slug column
1685# [RSpecRunTime] RSpec elapsed time: 44 minutes 29.98 seconds. Current RSS: ~1120M. Threads: 2. load average: 0.93 0.95 1.00 1/287 455
1686.
1687# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/populate_topics_slug_column_spec.rb. It took 12.65 seconds. Expected to take 4.23 seconds.
1688# [RSpecRunTime] RSpec elapsed time: 44 minutes 38.6 seconds. Current RSS: ~1106M. Threads: 2. load average: 1.02 0.97 1.00 1/287 456
1689.
1690# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_project_namespace_details_spec.rb. Expected to take 4.14 seconds.
1691Gitlab::BackgroundMigration::BackfillProjectNamespaceDetails
1692 #perform
1693 creates details for all project namespaces in range
1694# [RSpecRunTime] RSpec elapsed time: 44 minutes 42.68 seconds. Current RSS: ~1115M. Threads: 2. load average: 1.02 0.97 1.00 1/287 457
1695.
1696# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_project_namespace_details_spec.rb. It took 11.8 seconds. Expected to take 4.14 seconds.
1697# [RSpecRunTime] RSpec elapsed time: 44 minutes 50.44 seconds. Current RSS: ~1099M. Threads: 2. load average: 1.02 0.97 1.00 1/287 458
1698.
1699# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/update_ci_pipeline_artifacts_unknown_locked_status_spec.rb. Expected to take 4.05 seconds.
1700Gitlab::BackgroundMigration::UpdateCiPipelineArtifactsUnknownLockedStatus
1701 #perform
1702 updates ci_pipeline_artifacts with unknown lock status
1703# [RSpecRunTime] RSpec elapsed time: 44 minutes 54.37 seconds. Current RSS: ~1114M. Threads: 2. load average: 1.01 0.97 1.00 1/287 459
1704.
1705# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/update_ci_pipeline_artifacts_unknown_locked_status_spec.rb. It took 11.35 seconds. Expected to take 4.05 seconds.
1706# [RSpecRunTime] RSpec elapsed time: 45 minutes 1.83 seconds. Current RSS: ~1103M. Threads: 2. load average: 1.01 0.97 1.00 1/287 460
1707.
1708# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_catalog_resource_version_sem_ver_spec.rb. Expected to take 3.91 seconds.
1709Gitlab::BackgroundMigration::BackfillCatalogResourceVersionSemVer
1710 updates the semver columns with the expected values
1711# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_catalog_resource_version_sem_ver_spec.rb. It took 10.88 seconds. Expected to take 3.91 seconds.
1712# [RSpecRunTime] RSpec elapsed time: 45 minutes 12.74 seconds. Current RSS: ~1109M. Threads: 2. load average: 1.09 0.99 1.01 1/287 461
1713.
1714# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/base_job_spec.rb. Expected to take 3.73 seconds.
1715Gitlab::BackgroundMigration::BaseJob#perform
1716 #perform
1717 raises an error if not overridden by a subclass
1718# [RSpecRunTime] RSpec elapsed time: 45 minutes 16.25 seconds. Current RSS: ~1118M. Threads: 2. load average: 1.08 0.99 1.00 1/287 462
1719.
1720# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/base_job_spec.rb. It took 10.86 seconds. Expected to take 3.73 seconds.
1721# [RSpecRunTime] RSpec elapsed time: 45 minutes 23.63 seconds. Current RSS: ~1112M. Threads: 2. load average: 1.07 0.99 1.00 1/287 463
1722.
1723auto_explain log contains 967 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-4-5.343.main.ndjson.gz
1724took 22.603464825
1725auto_explain log contains 967 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-4-5.343.ci.ndjson.gz
1726took 22.539391935
1727[TEST PROF INFO] EventProf results for sql.active_record
1728Total time: 27:13.473 of 45:19.289 (60.07%)
1729Total events: 1208348
1730Top 5 slowest suites (by time):
1731Gitlab::Backg...urceLinkEvents (./spec/lib/gitlab/background_migration/backfill_resource_link_events_spec.rb:5) – 02:05.522 (89445 / 7) of 04:50.522 (43.21%)
1732Gitlab::Backg...illTopicsTitle (./spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb:5) – 02:01.820 (76319 / 1) of 02:52.940 (70.44%)
1733Gitlab::Backg...dsClusterAgent (./spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb:5) – 02:00.434 (80753 / 3) of 03:30.930 (57.1%)
1734Gitlab::Backg...espaceSettings (./spec/lib/gitlab/background_migration/backfill_code_suggestions_namespace_settings_spec.rb:5) – 01:59.371 (76428 / 1) of 02:54.944 (68.23%)
1735Gitlab::Backg...InvalidMembers (./spec/lib/gitlab/background_migration/destroy_invalid_members_spec.rb:6) – 01:59.151 (80706 / 3) of 03:26.425 (57.72%)
1736Knapsack report was generated. Preview:
1737{
1738 "spec/lib/gitlab/background_migration/backfill_resource_link_events_spec.rb": 210.29537269599996,
1739 "spec/lib/gitlab/background_migration/backfill_vulnerability_reads_cluster_agent_spec.rb": 130.5839797450003,
1740 "spec/lib/gitlab/background_migration/destroy_invalid_members_spec.rb": 127.08559375699997,
1741 "spec/lib/gitlab/background_migration/backfill_uuid_conversion_column_in_vulnerability_occurrences_spec.rb": 107.50934430999996,
1742 "spec/lib/gitlab/background_migration/backfill_owasp_top_ten_of_vulnerability_reads_spec.rb": 89.07387232700012,
1743 "spec/lib/gitlab/background_migration/set_legacy_open_source_license_available_for_non_public_projects_spec.rb": 86.97524207499964,
1744 "spec/lib/gitlab/background_migration/backfill_topics_title_spec.rb": 92.77065254800027,
1745 "spec/lib/gitlab/background_migration/truncate_overlong_vulnerability_html_titles_spec.rb": 96.2048097500001,
1746 "spec/lib/gitlab/background_migration/backfill_code_suggestions_namespace_settings_spec.rb": 89.61889542800054,
1747 "spec/lib/gitlab/background_migration/backfill_users_with_defaults_spec.rb": 77.31006636999973,
1748 "spec/lib/gitlab/background_migration/backfill_vs_code_settings_uuid_spec.rb": 67.19584038799985,
1749 "spec/lib/gitlab/background_migration/backfill_vs_code_settings_version_spec.rb": 65.99729951400059,
1750 "spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_artifact_spec.rb": 53.48103764899952,
1751 "spec/lib/gitlab/background_migration/backfill_branch_protection_namespace_setting_spec.rb": 48.056501361999835,
1752 "spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb": 45.31692985799964,
1753 "spec/lib/gitlab/background_migration/backfill_deployment_approvals_project_id_spec.rb": 40.2083195539999,
1754 "spec/lib/gitlab/background_migration/backfill_work_item_parent_links_namespace_id_spec.rb": 32.36057987700042,
1755 "spec/lib/gitlab/background_migration/backfill_onboarding_status_step_url_spec.rb": 32.009677138999905,
1756 "spec/lib/gitlab/background_migration/reset_status_on_container_repositories_spec.rb": 20.28659089499979,
1757 "spec/lib/gitlab/background_migration/batching_strategies/loose_index_scan_batching_strategy_spec.rb": 18.779200367999692,
1758 "spec/lib/gitlab/background_migration/backfill_boards_epic_lists_group_id_spec.rb": 14.982486851999965,
1759 "spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb": 14.062000550999983,
1760 "spec/lib/gitlab/background_migration/remove_namespace_from_os_type_sbom_components_spec.rb": 11.200107058999492,
1761 "spec/lib/gitlab/background_migration/backfill_default_branch_protection_settings_spec.rb": 7.367874409000251,
1762 "spec/lib/gitlab/background_migration/update_sbom_components_name_based_on_pep503_spec.rb": 7.249256393999531,
1763 "spec/lib/gitlab/background_migration/populate_topics_slug_column_spec.rb": 4.181901865000327,
1764 "spec/lib/gitlab/background_migration/backfill_project_namespace_details_spec.rb": 4.195058156000414,
1765 "spec/lib/gitlab/background_migration/update_ci_pipeline_artifacts_unknown_locked_status_spec.rb": 4.048397616000329,
1766 "spec/lib/gitlab/background_migration/backfill_catalog_resource_version_sem_ver_spec.rb": 3.764478997999504,
1767 "spec/lib/gitlab/background_migration/base_job_spec.rb": 3.6221589589995347
1768}
1769Knapsack global time execution for tests: 26m 45s
1770Finished in 46 minutes 9 seconds (files took 44.88 seconds to load)
177166 examples, 0 failures
1772Randomized with seed 25883
1773[TEST PROF INFO] Time spent in factories: 00:00.427 (0.02% of total time)
1774RSpec exited with 0.
1775No examples to retry, congrats!
1776 Running after_script 00:05
1777Running after script...
1778$ source scripts/utils.sh
1779$ bundle exec gem list gitlab_quality-test_tooling
1780gitlab_quality-test_tooling (1.26.0)
1781$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
1782 Report test failures 00:00
1784$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
1785 Report test flakiness 00:00
1787$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
1788 Report test slowness 00:00
1790$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
1791 Report test slowness in MR note 00:01
1794$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
1795 Report test files close to timing out 00:02
1798$ tooling/bin/push_job_metrics || true
1799[job-metrics] Pushing job metrics file for the CI/CD job.
1800[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
1801 Saving cache for successful job 00:00
1802Not uploading cache ruby-gems-debian-bookworm-ruby-3.2-17 due to policy
1803 Uploading artifacts for successful job 00:05
1804Uploading artifacts...
1805auto_explain/: found 3 matching artifact files and directories
1806coverage/: found 5 matching artifact files and directories
1807crystalball/: found 2 matching artifact files and directories
1808WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
1809knapsack/: found 4 matching artifact files and directories
1810rspec/: found 16 matching artifact files and directories
1811WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
1812log/*.log: found 13 matching artifact files and directories
1813WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6975007398/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
1814WARNING: Retrying... context=artifacts-uploader error=request redirected
1815Uploading artifacts as "archive" to coordinator... 201 Created id=6975007398 responseStatus=201 Created token=glcbt-65
1816Uploading artifacts...
1817rspec/rspec-*.xml: found 1 matching artifact files and directories
1818WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6975007398/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
1819WARNING: Retrying... context=artifacts-uploader error=request redirected
1820Uploading artifacts as "junit" to coordinator... 201 Created id=6975007398 responseStatus=201 Created token=glcbt-65
1821 Cleaning up project directory and file based variables 00:00
1822Job succeeded