Skip to content
Snippets Groups Projects

rspec-ee background_migration pg14 2/3

Passed Started by
Andrew Jung
@andrew.jung
Log timestamps in UTC.
121:02:20Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
221:02:20 on blue-6.private.runners-manager.gitlab.com/gitlab.com/gitlab-org mKJTFBei, system ID: s_90a66139ec06
321:02:20 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
421:02:20Resolving secrets
5 21:02:20 Preparing the "docker+machine" executor 02:03
621:02:20Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
721:02:23Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
821:02:23Authenticating with credentials from job payload (GitLab Registry)
921:02:23Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1021:02:31Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...
1121:02:35WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1221:02:35WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1321:02:35Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1421:02:35Authenticating with credentials from job payload (GitLab Registry)
1521:02:35Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1621:02:38Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...
1721:02:40Starting service redis:6.2-alpine...
1821:02:40Pulling docker image redis:6.2-alpine ...
1921:02:43Using docker image sha256:ec1b0a84ddf9124276ac189cc4d98ef28f0aea95ebd148d9e4c18b28928dd999 for redis:6.2-alpine with digest redis@sha256:2ba50e1ac3a0ea17b736ce9db2b0a9f6f8b85d4c27d5f5accc6a416d8f42c6d5 ...
2021:02:44WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 is already created. Ignoring.
2121:02:44WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 is already created. Ignoring.
2221:02:44Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8...
2321:02:44Authenticating with credentials from job payload (GitLab Registry)
2421:02:44Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 ...
2521:02:45Using docker image sha256:df819140e3df737cdc7b8d6fb73ce804b05058c790e025b5f52840956feb1734 for registry.gitlab.com/gitlab-org/gitlab-build-images:zoekt-ci-image-1.8 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:7bdf349fc3dd9da64bc6581d44c0e906bffd38977ddbd2668ba34adb5dedfe38 ...
2621:02:46Starting service elasticsearch:7.17.6...
2721:02:46Pulling docker image elasticsearch:7.17.6 ...
2821:02:57Using docker image sha256:5fad10241ffd65d817ed0ddfaf6e87eee1f7dc2a7db33db1047835560ea71fda for elasticsearch:7.17.6 with digest elasticsearch@sha256:6c128de5d01c0c130a806022d6bd99b3e4c27a9af5bfc33b6b81861ae117d028 ...
2921:03:01Starting service registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.18.0...
3021:03:01Authenticating with credentials from job payload (GitLab Registry)
3121:03:01Pulling docker image registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.18.0 ...
3221:03:35Using docker image sha256:eb97d149d5b7fa6f8d1c3551036054839115ce4ae8b1896d5b4cc1563b098719 for registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway:v1.18.0 with digest registry.gitlab.com/gitlab-org/modelops/applied-ml/code-suggestions/ai-assist/model-gateway@sha256:d8fb36abcbce56db6ebaaff6504a26ff7345d5f463f273b9f993e0cf65c85818 ...
3321:03:39Starting service ghcr.io/berriai/litellm:main-latest...
3421:03:39Pulling docker image ghcr.io/berriai/litellm:main-latest ...
3521:04:11Using docker image sha256:ee08e2d94626a6ecf23bcd550f2f9e9ecf8806acb2a8a36d476a86fb24895e6c for ghcr.io/berriai/litellm:main-latest with digest ghcr.io/berriai/litellm@sha256:cfd706eefaf24913846760437bb504c199c87d40b415747ca928e7e20a4105bb ...
3621:04:17Waiting for services to be up and running (timeout 30 seconds)...
3721:04:23Authenticating with credentials from job payload (GitLab Registry)
3821:04:23Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
3921:04:23Using docker image sha256:aa37a7017401b2a84d3f720a6ed6389cfe8d2a1468aaca032b100991cbba77ce for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14@sha256:036c9797d01edcfb3215f122f60a3f361ccc9f49fbbf2e6885d697523594297e ...
40 21:04:23 Preparing environment 00:01
4121:04:23Running on runner-mkjtfbei-project-278964-concurrent-0 via runner-mkjtfbei-private-1729798113-85e9db06...
42 21:04:24 Getting source from Git repository 00:00
4321:04:24Skipping Git repository setup
4421:04:24Skipping Git checkout
4521:04:24Skipping Git submodules setup
46 21:04:24 Restoring cache 00:15
4721:04:25Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19...
4821:04:25Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19 ETag="0c5095f10121bb5ad66eedb77afe35b5"
4921:04:34Successfully extracted cache
50 21:04:39 Downloading artifacts 00:45
5121:04:39Downloading artifacts for clone-gitlab-repo (8181075443)...
5221:04:43Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8181075443 responseStatus=200 OK token=glcbt-66
5321:04:43WARNING: Part of .git directory is on the list of files to extract
5421:04:43WARNING: This may introduce unexpected problems
5521:04:56Downloading artifacts for compile-test-assets (8181075500)...
5621:05:00Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8181075500 responseStatus=200 OK token=glcbt-66
5721:05:06Downloading artifacts for detect-tests (8181075512)...
5821:05:07Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8181075512 responseStatus=200 OK token=glcbt-66
5921:05:07Downloading artifacts for retrieve-tests-metadata (8181075525)...
6021:05:07Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8181075525 responseStatus=200 OK token=glcbt-66
6121:05:07Downloading artifacts for setup-test-env (8181075505)...
6221:05:12Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8181075505 responseStatus=200 OK token=glcbt-66
63 21:05:24 Executing "step_script" stage of the job script
6421:05:24Using docker image sha256:aa37a7017401b2a84d3f720a6ed6389cfe8d2a1468aaca032b100991cbba77ce for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.22-rust-1.73-node-20.12-postgresql-14@sha256:036c9797d01edcfb3215f122f60a3f361ccc9f49fbbf2e6885d697523594297e ...
6521:05:24$ echo $FOSS_ONLY
6621:05:24$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
6721:05:24$ export GOPATH=$CI_PROJECT_DIR/.go
6821:05:24$ mkdir -p $GOPATH
6921:05:24$ source scripts/utils.sh
7021:05:24$ log_disk_usage "true"
7121:05:24*******************************************************
7221:05:24This runner currently has 71G free disk space.
7321:05:24*******************************************************
74 21:05:24 Disk usage detail 00:08
7521:05:24df -h
7621:05:24Filesystem Size Used Avail Use% Mounted on
7721:05:24overlay 95G 24G 71G 26% /
7821:05:24tmpfs 64M 0 64M 0% /dev
7921:05:24shm 64M 0 64M 0% /dev/shm
8021:05:24/dev/sda1 95G 24G 71G 26% /cache
8121:05:24tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
8221:05:24du -h -d 1
8321:05:257.4M ./workhorse
8421:05:25148K ./bin
8521:05:251.2M ./.gitlab
8621:05:25177M ./.git
8721:05:254.0K ./builds
8821:05:2528M ./lib
8921:05:258.0K ./.vscode
9021:05:25532K ./storybook
9121:05:2524K ./log
9221:05:251.8M ./data
9321:05:2510M ./qa
9421:05:253.3M ./.rubocop_todo
9521:05:25817M ./vendor
9621:05:251003M ./public
9721:05:2560M ./doc
9821:05:25492K ./danger
9921:05:25100K ./rspec
10021:05:25552K ./tooling
10121:05:25142M ./ee
10221:05:251.3M ./knapsack
10321:05:25296K ./patches
10421:05:254.3M ./changelogs
10521:05:2520K ./haml_lint
10621:05:2528K ./metrics_server
10721:05:2588K ./keeps
10821:05:2548K ./shared
10921:05:2544K ./generator_templates
11021:05:251.3M ./scripts
11121:05:25788M ./tmp
11221:05:25127M ./spec
11321:05:2520K ./file_hooks
11421:05:2532K ./crystalball
11521:05:2516K ./sidekiq_cluster
11621:05:2517M ./gems
11721:05:251.2M ./fixtures
11821:05:25968K ./rubocop
11921:05:254.0K ./.go
12021:05:2531M ./db
12121:05:2512K ./.github
12221:05:25129M ./locale
12321:05:2519M ./config
12421:05:2681M ./app
12521:05:2620K ./.lefthook
12621:05:263.4G .
12721:05:26$ source scripts/prepare_build.sh
128 21:05:26 Installing gems 00:17
15921:05:43Using decomposed database config (config/database.yml.decomposed-postgresql)
16021:05:43Geo DB will be set up.
16121:05:43Embedding DB will be set up.
162 21:05:43 Setting up DBs 01:51
18421:07:34$ source ./scripts/rspec_helpers.sh
18521:07:34$ run_timed_command "gem install knapsack --no-document"
18621:07:33$ gem install knapsack --no-document
18721:07:36Successfully installed knapsack-4.0.0
18821:07:361 gem installed
18921:07:34==> 'gem install knapsack --no-document' succeeded in 2 seconds.
19021:07:36$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
191 21:07:36 Spawning Gitaly 00:05
19521:07:41$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
19621:07:41$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
19721:07:41$ tooling/bin/create_job_metrics_file || true
19821:07:44[job-metrics] Creating the job metrics file for the CI/CD job.
19921:07:44$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
200 21:07:44 RSpec 22:04
20121:07:44[21:07:44] Starting rspec_parallelized_job
20221:07:36RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
20321:07:45KNAPSACK_GENERATE_REPORT: true
20421:07:45FLAKY_RSPEC_GENERATE_REPORT: true
20521:07:45KNAPSACK_TEST_FILE_PATTERN: {ee/}spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
20621:07:45KNAPSACK_LOG_LEVEL: debug
20721:07:45KNAPSACK_REPORT_PATH: knapsack/rspec-ee_background_migration_pg14_2_3_278964_report.json
20821:07:45FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
20921:07:45FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec-ee_background_migration_pg14_2_3_278964_report.json
21021:07:45NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec-ee_background_migration_pg14_2_3_278964_report.json
21121:07:45RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8181076044.txt
21221:07:45CRYSTALBALL:
21321:07:45RSPEC_TESTS_MAPPING_ENABLED:
21421:07:45RSPEC_TESTS_FILTER_FILE:
21521:07:45Shell set options (set -o) enabled:
21621:07:45braceexpand on
21721:07:45hashall on
21821:07:45interactive-comments on
21921:07:45pipefail on
22021:07:54Knapsack report generator started!
22121:07:45/builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
22221:09:39/builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!
22321:10:24Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
22421:10:24# [RSpecRunTime] Starting RSpec timer...
22521:10:24[TEST PROF INFO] EventProf enabled (sql.active_record)
22621:10:24unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
22721:10:25unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
22821:10:34Test environment set up in 2.569243178 seconds
22921:10:35# [RSpecRunTime] Starting example group ee/spec/lib/gitlab/background_migration/create_vulnerability_links_spec.rb. Expected to take 2 minutes 36.38 seconds.
23021:10:35Gitlab::BackgroundMigration::CreateVulnerabilityLinks
23121:10:36 #perform
23221:10:36 for vulnerability feedback issues
23321:10:36 when a Finding has no Vulnerability
23421:10:44 creates a Vulnerability from the Vulnerabilities::Finding
23521:10:44 behaves like when there was a problem saving the Vulnerability
23621:10:51 doesn't create a Vulnerability record
23721:10:57 logs an error
23821:10:57 when the feedback is associated with a non-existent issue
23921:11:05 doesn't create a Vulnerability record
24021:11:05# [RSpecRunTime] RSpec elapsed time: 40.28 seconds. Current RSS: ~1172M. Threads: 2. load average: 1.09 1.56 1.77 1/412 410
24121:11:05.
24221:11:05 behaves like a migration creating a vulnerability issue link
24321:11:12 creates a Vulnerabilities::IssueLink from the Vulnerabilities::Feedback
24421:11:12# [RSpecRunTime] RSpec elapsed time: 47.61 seconds. Current RSS: ~1178M. Threads: 2. load average: 1.00 1.53 1.76 1/412 411
24521:11:12.
24621:11:12 when there's only a Security::Finding
24721:11:20 creates a Vulnerability from the Security::Finding
24821:11:28 creates a Vulnerabilities::IssueLink from the Vulnerabilities::Feedback
24921:11:28 behaves like when creating any associated record fails
25021:11:35 doesn't create a Vulnerability record
25121:11:41 logs an error
25221:11:41# [RSpecRunTime] RSpec elapsed time: 1 minute 16.49 seconds. Current RSS: ~1178M. Threads: 2. load average: 1.16 1.51 1.75 1/412 412
25321:11:41.
25421:11:41 when there is a vulnerability
25521:11:41 behaves like when the link is invalid
25621:11:48 doesn't create a IssueLink
25721:11:54 logs an error
25821:11:54# [RSpecRunTime] RSpec elapsed time: 1 minute 29.43 seconds. Current RSS: ~1179M. Threads: 2. load average: 1.20 1.51 1.74 1/412 413
25921:11:54.
26021:11:54 behaves like a migration creating a vulnerability issue link
26121:12:01 creates a Vulnerabilities::IssueLink from the Vulnerabilities::Feedback
26221:12:01# [RSpecRunTime] RSpec elapsed time: 1 minute 36.6 seconds. Current RSS: ~1175M. Threads: 2. load average: 1.27 1.51 1.74 1/413 414
26321:12:01.
26421:12:01 for vulnerability feedback merge requests
26521:12:01 when a Finding has no Vulnerability
26621:12:08 creates a Vulnerability from the Vulnerabilities::Finding
26721:12:08 behaves like when there was a problem saving the Vulnerability
26821:12:15 doesn't create a Vulnerability record
26921:12:22 logs an error
27021:12:22# [RSpecRunTime] RSpec elapsed time: 1 minute 58.13 seconds. Current RSS: ~1181M. Threads: 2. load average: 1.13 1.46 1.72 1/413 416
27121:12:22.
27221:12:22 when the feedback is associated with a non-existent merge request
27321:12:29 doesn't create a Vulnerability record
27421:12:29# [RSpecRunTime] RSpec elapsed time: 2 minutes 4.27 seconds. Current RSS: ~1178M. Threads: 2. load average: 1.11 1.45 1.71 1/413 417
27521:12:29.
27621:12:29 behaves like a migration creating a vulnerability merge request link
27721:12:36 creates a Vulnerabilities::MergeRequestLink from the Vulnerabilities::Feedback
27821:12:36# [RSpecRunTime] RSpec elapsed time: 2 minutes 11.75 seconds. Current RSS: ~1181M. Threads: 2. load average: 1.02 1.42 1.70 1/413 418
27921:12:36.
28021:12:36 when there's only a Security::Finding
28121:12:43 creates a Vulnerability from the Security::Finding
28221:12:51 creates a Vulnerabilities::MergeRequestLink from the Vulnerabilities::Feedback
28321:12:51 behaves like when creating any associated record fails
28421:12:58 doesn't create a Vulnerability record
28521:13:04 logs an error
28621:13:04# [RSpecRunTime] RSpec elapsed time: 2 minutes 40.04 seconds. Current RSS: ~1179M. Threads: 2. load average: 1.09 1.40 1.68 1/413 419
28721:13:04.
28821:13:04 when there is a vulnerability
28921:13:04 behaves like when the link is invalid
29021:13:12 doesn't create a MergeRequestLink
29121:13:19 logs an error
29221:13:19# [RSpecRunTime] RSpec elapsed time: 2 minutes 54.35 seconds. Current RSS: ~1180M. Threads: 2. load average: 1.15 1.39 1.68 1/413 420
29321:13:19.
29421:13:19 behaves like a migration creating a vulnerability merge request link
29521:13:25 creates a Vulnerabilities::MergeRequestLink from the Vulnerabilities::Feedback
29621:13:25# [RSpecRunTime] RSpec elapsed time: 3 minutes 0.72 second. Current RSS: ~1178M. Threads: 2. load average: 1.14 1.39 1.68 2/414 421
29721:13:25.
29821:13:42# [RSpecRunTime] Finishing example group ee/spec/lib/gitlab/background_migration/create_vulnerability_links_spec.rb. It took 3 minutes 7.66 seconds. Expected to take 2 minutes 36.38 seconds.
29921:13:42# [RSpecRunTime] RSpec elapsed time: 3 minutes 17.88 seconds. Current RSS: ~1174M. Threads: 2. load average: 1.04 1.35 1.66 1/413 422
30021:13:42.
30121:13:42# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb. Expected to take 2 minutes 28.29 seconds.
30221:13:42Gitlab::BackgroundMigration::MigrateApproverToApprovalRules
30321:13:43 sync approval rule and its members
30421:13:43 merge request
30521:13:43 behaves like sync approval member
30621:13:43 when member in old schema but not in new schema
30721:13:53 creates in new schema
30821:13:53 when rule is not created yet
30921:14:02 creates rule in new schema
31021:14:02# [RSpecRunTime] RSpec elapsed time: 3 minutes 38.09 seconds. Current RSS: ~1211M. Threads: 4. load average: 1.03 1.33 1.64 1/424 434
31121:14:02.
31221:14:02 when member not in old schema but in new schema
31321:14:12 removes in new schema
31421:14:12# [RSpecRunTime] RSpec elapsed time: 3 minutes 47.66 seconds. Current RSS: ~1220M. Threads: 4. load average: 1.10 1.33 1.64 1/424 435
31521:14:12.
31621:14:12 when approver has user_id which no longer exists
31721:14:21 ignores broken reference when assigning
31821:14:22# [RSpecRunTime] RSpec elapsed time: 3 minutes 57.15 seconds. Current RSS: ~1237M. Threads: 4. load average: 1.01 1.31 1.63 1/424 436
31921:14:22.
32021:14:22 when project rule is present
32121:14:29 sets MR rule's source to project rule without duplication
32221:14:29# [RSpecRunTime] RSpec elapsed time: 4 minutes 4.68 seconds. Current RSS: ~1253M. Threads: 4. load average: 1.01 1.30 1.62 1/424 437
32321:14:29.
32421:14:29 when project rule is absent
32521:14:36 has MR rule's source as nil
32621:14:36# [RSpecRunTime] RSpec elapsed time: 4 minutes 11.66 seconds. Current RSS: ~1246M. Threads: 4. load average: 1.00 1.29 1.62 1/424 438
32721:14:36.
32821:14:36 when approvals_before_merge is nil
32921:14:43 updates with project's approvals_required
33021:14:43# [RSpecRunTime] RSpec elapsed time: 4 minutes 18.89 seconds. Current RSS: ~1243M. Threads: 4. load average: 1.00 1.29 1.61 1/424 439
33121:14:43.
33221:14:43 when approvals_before_merge is too big
33321:14:50 caps at allowed maximum
33421:14:51# [RSpecRunTime] RSpec elapsed time: 4 minutes 26.2 seconds. Current RSS: ~1247M. Threads: 4. load average: 1.00 1.28 1.61 1/424 440
33521:14:51.
33621:14:51 when approver is no longer overwritten
33721:14:58 removes rule
33821:14:58# [RSpecRunTime] RSpec elapsed time: 4 minutes 33.69 seconds. Current RSS: ~1258M. Threads: 4. load average: 1.08 1.29 1.61 1/424 441
33921:14:58.
34021:14:58 #sync_code_owners_with_approvers
34121:14:58 when merge request is merged
34221:15:07 does nothing
34321:15:07# [RSpecRunTime] RSpec elapsed time: 4 minutes 43.06 seconds. Current RSS: ~1275M. Threads: 4. load average: 1.07 1.28 1.60 1/426 462
34421:15:07.
34521:15:07 when code owner rule does not exist
34621:15:15 creates rule
34721:15:15# [RSpecRunTime] RSpec elapsed time: 4 minutes 50.87 seconds. Current RSS: ~1264M. Threads: 4. load average: 1.06 1.27 1.60 1/424 463
34821:15:15.
34921:15:15 when code owner rule exists
35021:15:23 reuses and updates existing rule
35121:15:23 when there is no code owner
35221:15:30 removes rule
35321:15:30# [RSpecRunTime] RSpec elapsed time: 5 minutes 6.11 seconds. Current RSS: ~1275M. Threads: 4. load average: 1.05 1.25 1.59 1/424 464
35421:15:30.
35521:15:30 project
35621:15:30 behaves like sync approval member
35721:15:30 when member in old schema but not in new schema
35821:15:38 creates in new schema
35921:15:38 when rule is not created yet
36021:15:46 creates rule in new schema
36121:15:46# [RSpecRunTime] RSpec elapsed time: 5 minutes 21.41 seconds. Current RSS: ~1263M. Threads: 4. load average: 1.03 1.24 1.58 1/424 465
36221:15:46.
36321:15:46 when member not in old schema but in new schema
36421:15:54 removes in new schema
36521:15:54# [RSpecRunTime] RSpec elapsed time: 5 minutes 29.73 seconds. Current RSS: ~1267M. Threads: 4. load average: 0.95 1.22 1.56 1/424 466
36621:15:54.
36721:15:54 when approver has user_id which no longer exists
36821:16:03 ignores broken reference when assigning
36921:16:03# [RSpecRunTime] RSpec elapsed time: 5 minutes 38.29 seconds. Current RSS: ~1272M. Threads: 4. load average: 0.95 1.21 1.56 1/424 467
37021:16:03.
37121:16:03 when target is deleted
37221:16:10 does not err
37321:16:11# [RSpecRunTime] RSpec elapsed time: 5 minutes 46.16 seconds. Current RSS: ~1266M. Threads: 4. load average: 0.88 1.19 1.55 1/424 468
37421:16:11.
37521:16:11 when project has no repository
37621:16:18 does not err
37721:16:18# [RSpecRunTime] RSpec elapsed time: 5 minutes 53.45 seconds. Current RSS: ~1265M. Threads: 4. load average: 0.81 1.17 1.54 1/424 469
37821:16:18.
37921:16:35# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb. It took 2 minutes 52.52 seconds. Expected to take 2 minutes 28.29 seconds.
38021:16:35# [RSpecRunTime] RSpec elapsed time: 6 minutes 10.45 seconds. Current RSS: ~1249M. Threads: 4. load average: 0.80 1.14 1.52 1/424 470
38121:16:35.
38221:16:35# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb. Expected to take 2 minutes 10.27 seconds.
38321:16:35Gitlab::BackgroundMigration::PopulateDenormalizedColumnsForSbomOccurrences
38421:18:52 sets the denormalized columns
38521:18:54I, [2024-10-24T21:18:54.133892 #345] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
38621:19:16I, [2024-10-24T21:19:16.738267 #345] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
38721:19:18INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
38821:19:18INFO: analyzing "public.ci_pipeline_variables"
38921:19:18INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
39021:19:18I, [2024-10-24T21:19:18.534344 #345] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
39121:19:18I, [2024-10-24T21:19:18.535658 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
39221:19:18I, [2024-10-24T21:19:18.536656 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
39321:19:20INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
39421:19:20INFO: analyzing "public.ci_pipeline_variables"
39521:19:20INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
39621:19:25INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
39721:19:25INFO: analyzing "public.ci_job_artifacts"
39821:19:25INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
39921:19:25I, [2024-10-24T21:19:25.478549 #345] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
40021:19:25I, [2024-10-24T21:19:25.480010 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40121:19:25I, [2024-10-24T21:19:25.481040 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40221:19:29INFO: analyzing "public.p_ci_stages" inheritance tree
40321:19:29INFO: analyzing "public.ci_stages"
40421:19:29INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
40521:19:29I, [2024-10-24T21:19:29.168305 #345] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
40621:19:29I, [2024-10-24T21:19:29.169503 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40721:19:29I, [2024-10-24T21:19:29.170349 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40821:19:30I, [2024-10-24T21:19:30.585606 #345] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
40921:19:37I, [2024-10-24T21:19:37.956353 #345] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
41021:19:53I, [2024-10-24T21:19:53.947508 #345] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
41121:19:53I, [2024-10-24T21:19:53.949230 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41221:19:53I, [2024-10-24T21:19:53.950016 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41321:20:08I, [2024-10-24T21:20:08.493566 #345] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
41421:20:17I, [2024-10-24T21:20:17.210591 #345] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
41521:20:27I, [2024-10-24T21:20:27.413804 #345] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
41621:20:30I, [2024-10-24T21:20:30.710989 #345] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
41721:20:35INFO: analyzing "public.p_ci_pipelines" inheritance tree
41821:20:35INFO: analyzing "public.ci_pipelines"
41921:20:35INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
42021:20:35I, [2024-10-24T21:20:35.506663 #345] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
42121:20:35I, [2024-10-24T21:20:35.507937 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
42221:20:35I, [2024-10-24T21:20:35.508804 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
42321:20:42I, [2024-10-24T21:20:42.987238 #345] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
42421:21:17# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb. It took 4 minutes 41.97 seconds. Expected to take 2 minutes 10.27 seconds.
42521:21:17# [RSpecRunTime] RSpec elapsed time: 10 minutes 52.51 seconds. Current RSS: ~1259M. Threads: 4. load average: 0.89 1.04 1.38 1/423 471
42621:21:17.
42721:21:17# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb. Expected to take 1 minute 24.88 seconds.
42821:21:17Gitlab::BackgroundMigration::DeleteInvalidEpicIssues
42921:22:16 #perform
43021:22:27 removes invalid epic issues
43121:22:37 logs deleted records
43221:22:49 prevents N+1 queries
43321:22:49# [RSpecRunTime] RSpec elapsed time: 12 minutes 24.51 seconds. Current RSS: ~1267M. Threads: 4. load average: 0.98 1.03 1.34 1/423 472
43421:22:49.
43521:22:52I, [2024-10-24T21:22:52.146078 #345] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
43621:22:52I, [2024-10-24T21:22:52.147576 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
43721:22:52I, [2024-10-24T21:22:52.148495 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
43821:23:06I, [2024-10-24T21:23:06.774201 #345] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
43921:23:15I, [2024-10-24T21:23:15.511290 #345] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
44021:23:25I, [2024-10-24T21:23:25.286592 #345] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
44121:23:28I, [2024-10-24T21:23:28.479437 #345] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
44221:23:32INFO: analyzing "public.p_ci_pipelines" inheritance tree
44321:23:32INFO: analyzing "public.ci_pipelines"
44421:23:32INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
44521:23:33I, [2024-10-24T21:23:33.000628 #345] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
44621:23:33I, [2024-10-24T21:23:33.001911 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
44721:23:33I, [2024-10-24T21:23:33.002792 #345] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
44821:23:40I, [2024-10-24T21:23:40.380324 #345] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
44921:24:12# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb. It took 2 minutes 54.66 seconds. Expected to take 1 minute 24.88 seconds.
45021:24:12# [RSpecRunTime] RSpec elapsed time: 13 minutes 47.29 seconds. Current RSS: ~1268M. Threads: 4. load average: 1.05 1.03 1.31 1/424 473
45121:24:12.
45221:24:12# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/backfill_root_namespace_cluster_agent_mappings_spec.rb. Expected to take 33.33 seconds.
45321:24:12Gitlab::BackgroundMigration::BackfillRootNamespaceClusterAgentMappings
45421:24:12 #perform
45521:24:12 when remote dev is enabled for an agent project that resides within a group
45621:24:19 create a mapping from the cluster agent to its root namespace
45721:24:19# [RSpecRunTime] RSpec elapsed time: 13 minutes 54.86 seconds. Current RSS: ~1287M. Threads: 4. load average: 1.04 1.03 1.31 1/424 474
45821:24:19.
45921:24:19 when rd-enabled cluster agent project resides within a user namespace
46021:24:19 behaves like skipped migration
46121:24:26 skips migration for such records
46221:24:26# [RSpecRunTime] RSpec elapsed time: 14 minutes 1.24 seconds. Current RSS: ~1276M. Threads: 4. load average: 1.04 1.03 1.31 1/424 475
46321:24:26.
46421:24:26 when remote dev is disabled for an agent within a group
46521:24:26 behaves like skipped migration
46621:24:32 skips migration for such records
46721:24:32# [RSpecRunTime] RSpec elapsed time: 14 minutes 7.2 seconds. Current RSS: ~1275M. Threads: 4. load average: 1.03 1.03 1.31 1/424 476
46821:24:32.
46921:24:32 when mapping already exists between an agent and the root namespace
47021:24:39 skips migration for such records
47121:24:39# [RSpecRunTime] RSpec elapsed time: 14 minutes 14.6 seconds. Current RSS: ~1292M. Threads: 4. load average: 1.03 1.03 1.30 1/424 477
47221:24:39.
47321:24:39 when candidate remote dev agents exist in multiple root namespaces
47421:24:46 carries out migration for all agent configs
47521:24:46# [RSpecRunTime] RSpec elapsed time: 14 minutes 21.43 seconds. Current RSS: ~1280M. Threads: 4. load average: 1.11 1.05 1.31 1/424 478
47621:24:46.
47721:25:02# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/backfill_root_namespace_cluster_agent_mappings_spec.rb. It took 50.53 seconds. Expected to take 33.33 seconds.
47821:25:02# [RSpecRunTime] RSpec elapsed time: 14 minutes 37.88 seconds. Current RSS: ~1270M. Threads: 4. load average: 0.93 1.01 1.29 1/424 479
47921:25:02.
48021:25:02# [RSpecRunTime] Starting example group ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb. Expected to take 18.46 seconds.
48121:25:02Gitlab::BackgroundMigration::UpdateWorkspacesConfigVersion3
48221:25:11 #perform
48321:25:17 updates config_version and force_include_all_resources for existing non-terminated workspaces
48421:25:23 does not update workspaces with different config_version or actual_state
48521:25:23# [RSpecRunTime] RSpec elapsed time: 14 minutes 58.37 seconds. Current RSS: ~1287M. Threads: 4. load average: 0.95 1.01 1.28 1/424 480
48621:25:23.
48721:25:49# [RSpecRunTime] Finishing example group ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb. It took 47.1 seconds. Expected to take 18.46 seconds.
48821:25:49# [RSpecRunTime] RSpec elapsed time: 15 minutes 25.03 seconds. Current RSS: ~1279M. Threads: 4. load average: 1.03 1.02 1.28 1/425 481
48921:25:49.
49021:25:49# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_in_batch_spec.rb. Expected to take 11.57 seconds.
49121:25:49Gitlab::BackgroundMigration::MigrateApproverToApprovalRulesInBatch
49221:25:50 when there is no more MigrateApproverToApprovalRules jobs
49321:26:03 migrates individual target
49421:26:03# [RSpecRunTime] RSpec elapsed time: 15 minutes 38.4 seconds. Current RSS: ~1333M. Threads: 4. load average: 1.03 1.02 1.27 1/425 551
49521:26:03.
49621:26:19# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_in_batch_spec.rb. It took 29.49 seconds. Expected to take 11.57 seconds.
49721:26:19# [RSpecRunTime] RSpec elapsed time: 15 minutes 54.58 seconds. Current RSS: ~1264M. Threads: 4. load average: 0.94 1.00 1.26 1/426 552
49821:26:19.
49921:26:19# [RSpecRunTime] Starting example group ee/spec/lib/ee/gitlab/background_migration/sync_scan_result_policies_spec.rb. Expected to take 6.53 seconds.
50021:26:19Gitlab::BackgroundMigration::SyncScanResultPolicies
50121:26:20 #perform
50221:26:25 enqueues Security::SyncScanPoliciesWorker for each project of policy configuration
50321:26:26# [RSpecRunTime] RSpec elapsed time: 16 minutes 1.16 seconds. Current RSS: ~1278M. Threads: 4. load average: 0.95 1.00 1.26 1/426 553
50421:26:26.
50521:26:41# [RSpecRunTime] Finishing example group ee/spec/lib/ee/gitlab/background_migration/sync_scan_result_policies_spec.rb. It took 22.24 seconds. Expected to take 6.53 seconds.
50621:26:41# [RSpecRunTime] RSpec elapsed time: 16 minutes 16.89 seconds. Current RSS: ~1265M. Threads: 4. load average: 0.82 0.97 1.24 1/426 554
50721:26:41.
50821:27:02auto_explain log contains 1241 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-background-migration-pg14-2-3.345.main.ndjson.gz
50921:27:02took 20.049053032
51021:27:21auto_explain log contains 1242 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-ee-background-migration-pg14-2-3.345.ci.ndjson.gz
51121:27:21took 18.657034448
51221:27:21[TEST PROF INFO] EventProf results for sql.active_record
51321:27:21Total time: 07:32.326 of 16:06.708 (46.79%)
51421:27:21Total events: 352210
51521:27:21Top 5 slowest suites (by time):
51621:27:21Gitlab::Backg...bomOccurrences (./ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb:5) – 02:57.715 (113172 / 1) of 04:42.104 (63.0%)
51721:27:21Gitlab::Backg...alidEpicIssues (./ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb:6) – 01:34.818 (60576 / 3) of 02:54.720 (54.27%)
51821:27:21Gitlab::Backg...erabilityLinks (./ee/spec/lib/gitlab/background_migration/create_vulnerability_links_spec.rb:7) – 01:03.425 (65933 / 24) of 03:07.696 (33.79%)
51921:27:21Gitlab::Backg...oApprovalRules (./ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb:6) – 00:57.389 (56259 / 19) of 02:52.592 (33.25%)
52021:27:21Gitlab::Backg...ConfigVersion3 (./ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb:5) – 00:22.431 (18586 / 2) of 00:47.155 (47.57%)
52121:27:21Knapsack report was generated. Preview:
52221:27:21{
52321:27:21 "ee/spec/lib/gitlab/background_migration/create_vulnerability_links_spec.rb": 170.55887768899993,
52421:27:21 "ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_spec.rb": 155.58532324999942,
52521:27:21 "ee/spec/lib/ee/gitlab/background_migration/populate_denormalized_columns_for_sbom_occurrences_spec.rb": 137.45928666300006,
52621:27:21 "ee/spec/lib/ee/gitlab/background_migration/delete_invalid_epic_issues_spec.rb": 91.9626986910007,
52721:27:21 "ee/spec/lib/ee/gitlab/background_migration/backfill_root_namespace_cluster_agent_mappings_spec.rb": 34.1593818500005,
52821:27:21 "ee/spec/lib/gitlab/background_migration/update_workspaces_config_version3_spec.rb": 20.521566787000665,
52921:27:21 "ee/spec/lib/ee/gitlab/background_migration/migrate_approver_to_approval_rules_in_batch_spec.rb": 13.380445523999697,
53021:27:21 "ee/spec/lib/ee/gitlab/background_migration/sync_scan_result_policies_spec.rb": 6.590080587000557
53121:27:21}
53221:27:21Knapsack global time execution for tests: 10m 30s
53321:27:21Finished in 16 minutes 57 seconds (files took 2 minutes 36.5 seconds to load)
53421:27:2156 examples, 0 failures
53521:27:21Randomized with seed 49727
53621:27:27[TEST PROF INFO] Time spent in factories: 00:29.419 (2.74% of total time)
53721:27:27RSpec exited with 0.
53821:27:27No examples to retry, congrats!
539 21:27:28 Running after_script 00:19
54021:27:30Running after script...
54121:27:30$ source scripts/utils.sh
54221:27:30$ log_disk_usage
54321:27:30*******************************************************
54421:27:30This runner currently has 69G free disk space.
54521:27:30*******************************************************
546 21:27:30 Disk usage detail 00:08
60221:27:38$ bundle exec gem list gitlab_quality-test_tooling
60321:27:41gitlab_quality-test_tooling (2.1.0)
60421:27:41$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
605 21:27:41 Report test failures 00:00
60721:27:41$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
608 21:27:41 Report test flakiness 00:00
61021:27:41$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
611 21:27:41 Report test slowness 00:00
61321:27:41$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
614 21:27:41 Report test slowness in MR note 00:02
61721:27:43$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
618 21:27:43 Report test files close to timing out 00:03
62121:27:46$ tooling/bin/push_job_metrics || true
62221:27:47[job-metrics] Pushing job metrics file for the CI/CD job.
62321:27:47[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
624 21:27:47 Saving cache for successful job 00:01
62521:27:48Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19 due to policy
626 21:27:48 Uploading artifacts for successful job 00:08
62721:27:51Uploading artifacts...
62821:27:51auto_explain/: found 3 matching artifact files and directories
62921:27:51coverage/: found 5 matching artifact files and directories
63021:27:51crystalball/: found 2 matching artifact files and directories
63121:27:51WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
63221:27:51knapsack/: found 4 matching artifact files and directories
63321:27:51rspec/: found 16 matching artifact files and directories
63421:27:51WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
63521:27:51log/*.log: found 13 matching artifact files and directories
63621:27:51WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8181076044/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
63721:27:51WARNING: Retrying... context=artifacts-uploader error=request redirected
63821:27:54Uploading artifacts as "archive" to coordinator... 201 Created id=8181076044 responseStatus=201 Created token=glcbt-66
63921:27:54Uploading artifacts...
64021:27:54rspec/rspec-*.xml: found 1 matching artifact files and directories
64121:27:54WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8181076044/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
64221:27:54WARNING: Retrying... context=artifacts-uploader error=request redirected
64321:27:55Uploading artifacts as "junit" to coordinator... 201 Created id=8181076044 responseStatus=201 Created token=glcbt-66
644 21:27:56 Cleaning up project directory and file based variables 00:00
64521:28:07Job succeeded