Skip to content
Snippets Groups Projects

rspec system predictive 10/17

Passed Started by
Jack Chapman
@jachapman
Log timestamps in UTC.
111:24:01Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)
211:24:01 on green-4.shared-gitlab-org.runners-manager.gitlab.com/default x5QiHUKw, system ID: s_b9637080a79e
311:24:01 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
411:24:01Resolving secrets
5 11:24:01 Preparing the "docker+machine" executor 00:10
611:24:01Using 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 ...
711:24:03Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
811:24:03Authenticating with credentials from job payload (GitLab Registry)
911:24:03Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1011:24:03Using 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 ...
1111:24:04WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1211:24:04WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1311:24:04Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1411:24:04Authenticating with credentials from job payload (GitLab Registry)
1511:24:04Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1611:24:04Using 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 ...
1711:24:04Starting service redis:6.2-alpine ...
1811:24:04Pulling docker image redis:6.2-alpine ...
1911:24:05Using docker image sha256:36c1d3cfe18f4b12d4640576f7048068acbfc3625027b6bbb46bba0e31b11831 for redis:6.2-alpine with digest redis@sha256:e3b17ba9479deec4b7d1eeec1548a253acc5374d68d3b27937fcfe4df8d18c7e ...
2011:24:06Waiting for services to be up and running (timeout 30 seconds)...
2111:24:10Authenticating with credentials from job payload (GitLab Registry)
2211:24:10Pulling 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 ...
2311:24:11Using docker image sha256:72c427a29f80c5ff14e5cd83601d7507c7de71ce18b2ccd8a04667fdd0d6aba0 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:ba1a3d49eae4bc906535413ec79e7efdb3497247de3e53f23a928039672611a1 ...
24 11:24:11 Preparing environment 00:00
2511:24:11Running on runner-x5qihukw-project-278964-concurrent-0 via runner-x5qihukw-shared-gitlab-org-1725523978-b13d61c1...
26 11:24:11 Getting source from Git repository 00:01
2711:24:12Skipping Git repository setup
2811:24:12Skipping Git checkout
2911:24:12Skipping Git submodules setup
30 11:24:12 Restoring cache 00:18
3111:24:12Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-18...
3211:24:12cache.zip is up to date
3311:24:24Successfully extracted cache
34 11:24:30 Downloading artifacts 00:43
3511:24:30Downloading artifacts for clone-gitlab-repo (7752957209)...
3611:24:35Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7752957209 responseStatus=200 OK token=glcbt-66
3711:24:35WARNING: Part of .git directory is on the list of files to extract
3811:24:35WARNING: This may introduce unexpected problems
3911:24:49Downloading artifacts for compile-test-assets (7752957260)...
4011:24:52Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7752957260 responseStatus=200 OK token=glcbt-66
4111:24:56Downloading artifacts for detect-tests (7752957270)...
4211:24:56Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7752957270 responseStatus=200 OK token=glcbt-66
4311:24:56Downloading artifacts for retrieve-tests-metadata (7752957280)...
4411:24:57Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7752957280 responseStatus=200 OK token=glcbt-66
4511:24:57Downloading artifacts for setup-test-env (7752957263)...
4611:25:01Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7752957263 responseStatus=200 OK token=glcbt-66
47 11:25:13 Executing "step_script" stage of the job script
4811:25:13Using docker image sha256:72c427a29f80c5ff14e5cd83601d7507c7de71ce18b2ccd8a04667fdd0d6aba0 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:ba1a3d49eae4bc906535413ec79e7efdb3497247de3e53f23a928039672611a1 ...
4911:25:14$ echo $FOSS_ONLY
5011:25:14$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5111:25:14$ export GOPATH=$CI_PROJECT_DIR/.go
5211:25:14$ mkdir -p $GOPATH
5311:25:14$ source scripts/utils.sh
5411:25:14$ log_disk_usage before_script
5511:25:14[log_disk_usage before_script] start
5611:25:14df -h
5711:25:14Filesystem Size Used Avail Use% Mounted on
5811:25:14overlay 46G 30G 16G 66% /
5911:25:14tmpfs 64M 0 64M 0% /dev
6011:25:14shm 256M 0 256M 0% /dev/shm
6111:25:14/dev/sda1 46G 30G 16G 66% /cache
6211:25:14tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6311:25:14tmpfs 3.9G 0 3.9G 0% /proc/acpi
6411:25:14tmpfs 3.9G 0 3.9G 0% /proc/scsi
6511:25:14tmpfs 3.9G 0 3.9G 0% /sys/firmware
6611:25:14du -h -d 1
6711:25:147.3M ./workhorse
6811:25:14148K ./bin
6911:25:141.2M ./.gitlab
7011:25:14159M ./.git
7111:25:144.0K ./builds
7211:25:1427M ./lib
7311:25:148.0K ./.vscode
7411:25:14516K ./storybook
7511:25:1420K ./log
7611:25:141.8M ./data
7711:25:149.9M ./qa
7811:25:142.9M ./.rubocop_todo
7911:25:14822M ./vendor
8011:25:14609M ./public
8111:25:1458M ./doc
8211:25:14480K ./danger
8311:25:14128K ./rspec
8411:25:14536K ./tooling
8511:25:14138M ./ee
8611:25:141.3M ./knapsack
8711:25:14296K ./patches
8811:25:144.3M ./changelogs
8911:25:1420K ./haml_lint
9011:25:1428K ./metrics_server
9111:25:1488K ./keeps
9211:25:1448K ./shared
9311:25:1444K ./generator_templates
9411:25:141.3M ./scripts
9511:25:14650M ./tmp
9611:25:14125M ./spec
9711:25:1420K ./file_hooks
9811:25:1432K ./crystalball
9911:25:1416K ./sidekiq_cluster
10011:25:1416M ./gems
10111:25:141.2M ./fixtures
10211:25:14948K ./rubocop
10311:25:144.0K ./.go
10411:25:1429M ./db
10511:25:1412K ./.github
10611:25:14118M ./locale
10711:25:141.9M ./glfm_specification
10811:25:1417M ./config
10911:25:1480M ./app
11011:25:1416K ./.lefthook
11111:25:142.9G .
11211:25:14[log_disk_usage before_script] end
11311:25:14$ source scripts/prepare_build.sh
114 11:25:14 Installing gems 00:22
14511:25:36Using decomposed database config (config/database.yml.decomposed-postgresql)
14611:25:36Geo DB won't be set up.
14711:25:36Embedding DB won't be set up.
148 11:25:36 Setting up DBs 01:20
16011:26:56$ source ./scripts/rspec_helpers.sh
16111:26:56$ run_timed_command "gem install knapsack --no-document"
16211:26:55$ gem install knapsack --no-document
16311:26:56Successfully installed knapsack-4.0.0
16411:26:561 gem installed
16511:26:56==> 'gem install knapsack --no-document' succeeded in 0 seconds.
16611:26:56$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
167 11:26:56 Spawning Gitaly 00:05
17111:27:01$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17211:27:01$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17311:27:01$ tooling/bin/create_job_metrics_file || true
17411:27:03[job-metrics] Creating the job metrics file for the CI/CD job.
17511:27:04$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~level:background_migration --tag ~click_house"
176 11:27:04 RSpec 09:44
17711:27:04[11:27:04] Starting rspec_parallelized_job
17811:26:56RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
17911:27:04KNAPSACK_GENERATE_REPORT: true
18011:27:04FLAKY_RSPEC_GENERATE_REPORT: true
18111:27:04KNAPSACK_TEST_FILE_PATTERN: spec/{features}{,/**/}*_spec.rb
18211:27:04KNAPSACK_LOG_LEVEL: debug
18311:27:04KNAPSACK_REPORT_PATH: knapsack/rspec_system_predictive_10_17_278964_report.json
18411:27:04FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18511:27:04FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_system_predictive_10_17_278964_report.json
18611:27:04NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_system_predictive_10_17_278964_report.json
18711:27:04RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7753125135.txt
18811:27:04CRYSTALBALL:
18911:27:04RSPEC_TESTS_MAPPING_ENABLED: true
19011:27:04RSPEC_TESTS_FILTER_FILE: rspec/matching_tests-foss.txt
19111:27:04Shell set options (set -o) enabled:
19211:27:04braceexpand on
19311:27:04hashall on
19411:27:04interactive-comments on
19511:27:04pipefail on
19611:27:11Knapsack report generator started!
19711:27:04/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!
19811:28:01warning: parser/current is loading parser/ruby32, which recognizes 3.2.4-compliant syntax, but you are running 3.2.5.
19911:28:01Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20011:28:05Run options: exclude {:quarantine=>true, :level=>"background_migration", :click_house=>true}
20111:28:05# [RSpecRunTime] Starting RSpec timer...
20211:28:05[TEST PROF INFO] EventProf enabled (sql.active_record)
20311:28:05unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20411:28:05unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20511:28:10Test environment set up in 1.055647627 seconds
20611:28:11# [RSpecRunTime] Starting example group spec/features/discussion_comments/issue_spec.rb. Expected to take 2 minutes 39.21 seconds.
20711:28:11Thread Comments Issue
20811:28:11Starting the Capybara driver server...
20911:28:15 behaves like thread comments for issue, epic and merge request
21011:28:15Capybara starting Puma...
21111:28:15* Version 6.4.0, codename: The Eagle of Durango
21211:28:15* Min threads: 0, max threads: 4
21311:28:15* Listening on unix:///tmp/20240905-331-1onz98
21411:28:36 clicking "Comment" will post a comment
21511:28:46 clicking 'Comment & close issue' will post a comment and close the issue
21611:28:46 when the toggle is clicked
21711:28:53 has a "Comment" item (selected by default) and "Start thread" item
21811:29:00 closes the menu when clicking the toggle or body
21911:29:00 when selecting "Start thread"
22011:29:10 clicking 'Start thread & close issue' will post a thread and close the issue
22111:29:10 creating a thread
22211:29:18 clicking "Start thread" will post a thread and show a reply component
22311:29:27 can be replied to
22411:29:38 can be collapsed
22511:29:53 can be replied to after resolving
22611:30:09 shows resolved thread when toggled
22711:30:09 when opening the menu
22811:30:16 has "Start thread" selected
22911:30:16 when selecting "Comment"
23011:30:23 updates the submit button text and closes the dropdown
23111:30:31 updates the close button text
23211:30:39 typing does not change the close button text
23311:30:46 has "Comment" selected when opening the menu
23411:30:46# [RSpecRunTime] RSpec elapsed time: 2 minutes 41.08 seconds. Current RSS: ~1364M. Threads: 24. load average: 1.96 1.76 1.70 1/382 569
23511:30:46.
23611:30:46 on a closed issue
23711:30:54 shows a 'Comment & reopen issue' button
23811:31:02 shows a 'Start thread & reopen issue' button when 'Start thread' is selected
23911:31:02# [RSpecRunTime] RSpec elapsed time: 2 minutes 56.92 seconds. Current RSS: ~1383M. Threads: 25. load average: 1.89 1.76 1.70 1/385 579
24011:31:02.
24111:31:03# [RSpecRunTime] Finishing example group spec/features/discussion_comments/issue_spec.rb. It took 2 minutes 52.18 seconds. Expected to take 2 minutes 39.21 seconds.
24211:31:03# [RSpecRunTime] Starting example group spec/features/issues/user_comments_on_issue_spec.rb. Expected to take 1 minute 22.11 seconds.
24311:31:03User comments on issue
24411:31:04 when adding comments
24511:31:10 adds comment
24611:31:16 adds comment with code block
24711:31:23 opens autocomplete menu for quick actions and have `/label` first choice
24811:31:29 switches back to edit mode if a comment is submitted in preview mode
24911:31:29 behaves like rich text editor - common
25011:31:43 saves page content in local storage if the user navigates away
25111:31:50 autofocuses the rich text editor when switching to rich text
25211:31:58 autofocuses the plain text editor when switching back to markdown
25311:31:58 rendering with initial content
25411:32:06 renders correctly with table as initial content
25511:32:06# [RSpecRunTime] RSpec elapsed time: 4 minutes 1.37 seconds. Current RSS: ~1387M. Threads: 26. load average: 1.91 1.78 1.71 1/385 597
25611:32:06.
25711:32:06 block content is added to a table
25811:32:16 converts a markdown table to HTML and shows a warning for it
25911:32:16# [RSpecRunTime] RSpec elapsed time: 4 minutes 10.67 seconds. Current RSS: ~1378M. Threads: 25. load average: 1.84 1.77 1.71 1/385 600
26011:32:16.
26111:32:16 when editing comments
26211:32:24 edits comment
26311:32:24# [RSpecRunTime] RSpec elapsed time: 4 minutes 19.18 seconds. Current RSS: ~1389M. Threads: 27. load average: 1.79 1.76 1.71 2/387 603
26411:32:24.
26511:32:25# [RSpecRunTime] Finishing example group spec/features/issues/user_comments_on_issue_spec.rb. It took 1 minute 21.8 seconds. Expected to take 1 minute 22.11 seconds.
26611:32:25# [RSpecRunTime] Starting example group spec/features/projects/work_items/shortcuts_work_item_spec.rb. Expected to take 52.43 seconds.
26711:32:25Work item keyboard shortcuts
26811:32:26 for signed in user
26911:32:26 sidebar
27011:32:30Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
27111:32:30See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
27211:32:30To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
27311:32:31Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
27411:32:31See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
27511:32:31To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
27611:32:33 pressing m opens milestones dropdown for editing
27711:32:35Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
27811:32:35See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
27911:32:35To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
28011:32:39Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
28111:32:39See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
28211:32:39To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
28311:32:39 pressing l opens labels dropdown for editing
28411:32:42Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
28511:32:42See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
28611:32:42To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
28711:32:44Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
28811:32:44See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
28911:32:44To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
29011:32:45 pressing a opens assignee dropdown for editing
29111:32:48Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
29211:32:48See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
29311:32:48To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
29411:32:50Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
29511:32:50See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
29611:32:50To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
29711:32:51 pressing e starts editing mode
29811:32:51# [RSpecRunTime] RSpec elapsed time: 4 minutes 45.7 seconds. Current RSS: ~1377M. Threads: 27. load average: 1.81 1.76 1.71 1/388 652
29911:32:51.
30011:32:51 pressing r
30111:32:55Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
30211:32:55See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
30311:32:55To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
30411:32:56Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
30511:32:56See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
30611:32:56To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
30711:32:57 focuses main comment field by default
30811:33:00Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
30911:33:00See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
31011:33:00To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
31111:33:02Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
31211:33:02See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
31311:33:02To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
31411:33:03 quotes the selected text in main comment form
31511:33:06Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
31611:33:06See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
31711:33:06To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
31811:33:08Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
31911:33:08See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
32011:33:08To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
32111:33:09 quotes the selected text in the discussion reply form
32211:33:09# [RSpecRunTime] RSpec elapsed time: 5 minutes 3.83 seconds. Current RSS: ~1377M. Threads: 27. load average: 2.01 1.81 1.73 1/387 654
32311:33:09.
32411:33:09 navigation
32511:33:12Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
32611:33:12See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
32711:33:12To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
32811:33:15Job arguments to ReactiveCachingWorker must be native JSON types, but WorkItems::Type(id: integer, base_type: integer, cached_markdown_version: integer, name: text, description: text, description_html: text, icon_name: text, created_at: timestamptz, updated_at: timestamptz) is a Class.
32911:33:15See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
33011:33:15To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
33111:33:17 pressing . opens web IDE
33211:33:18# [RSpecRunTime] RSpec elapsed time: 5 minutes 12.41 seconds. Current RSS: ~1371M. Threads: 26. load average: 2.01 1.81 1.73 1/390 686
33311:33:18.
33411:33:18# [RSpecRunTime] Finishing example group spec/features/projects/work_items/shortcuts_work_item_spec.rb. It took 53.15 seconds. Expected to take 52.43 seconds.
33511:33:18# [RSpecRunTime] Starting example group spec/features/projects/issues/email_participants_spec.rb. Expected to take 41.09 seconds.
33611:33:18viewing an issue
33711:33:19 when issue is confidential
33811:33:19 behaves like email participants warning in all editors
33911:33:19 for a new note
34011:33:19 behaves like email participants warning
34111:33:25 shows the correct message
34211:33:25# [RSpecRunTime] RSpec elapsed time: 5 minutes 20.19 seconds. Current RSS: ~1372M. Threads: 27. load average: 1.85 1.79 1.72 4/393 690
34311:33:25.
34411:33:25 for a reply form
34511:33:25 behaves like email participants warning
34611:33:32 shows the correct message
34711:33:32# [RSpecRunTime] RSpec elapsed time: 5 minutes 27.15 seconds. Current RSS: ~1377M. Threads: 27. load average: 2.10 1.84 1.74 1/388 692
34811:33:32.
34911:33:32 when issue is not confidential
35011:33:32 with signed in user
35111:33:32 when user has no role in project
35211:33:32 behaves like email participants warning in all editors
35311:33:32 for a new note
35411:33:32 behaves like email participants warning
35511:33:39 shows the correct message
35611:33:39# [RSpecRunTime] RSpec elapsed time: 5 minutes 33.49 seconds. Current RSS: ~1392M. Threads: 25. load average: 1.93 1.81 1.73 2/386 693
35711:33:39.
35811:33:39 for a reply form
35911:33:39 behaves like email participants warning
36011:33:45 shows the correct message
36111:33:45# [RSpecRunTime] RSpec elapsed time: 5 minutes 39.65 seconds. Current RSS: ~1395M. Threads: 25. load average: 1.94 1.82 1.73 1/386 695
36211:33:45.
36311:33:45 when user has (at least) reporter role in project
36411:33:45 behaves like email participants warning in all editors
36511:33:45 for a new note
36611:33:45 behaves like email participants warning
36711:33:51 shows the correct message
36811:33:51# [RSpecRunTime] RSpec elapsed time: 5 minutes 45.75 seconds. Current RSS: ~1402M. Threads: 25. load average: 1.86 1.80 1.73 1/386 698
36911:33:51.
37011:33:51 for a reply form
37111:33:51 behaves like email participants warning
37211:33:57 shows the correct message
37311:33:57# [RSpecRunTime] RSpec elapsed time: 5 minutes 52.15 seconds. Current RSS: ~1403M. Threads: 27. load average: 1.88 1.81 1.73 1/387 702
37411:33:57.
37511:33:57# [RSpecRunTime] Finishing example group spec/features/projects/issues/email_participants_spec.rb. It took 39.73 seconds. Expected to take 41.09 seconds.
37611:33:57# [RSpecRunTime] Starting example group spec/features/issues/user_sees_live_update_spec.rb. Expected to take 24.62 seconds.
37711:33:57Issues > User sees live update
37811:33:58 title issue#show
37911:34:07 updates the title
38011:34:07# [RSpecRunTime] RSpec elapsed time: 6 minutes 1.8 seconds. Current RSS: ~1406M. Threads: 27. load average: 1.81 1.79 1.72 1/386 704
38111:34:07.
38211:34:07 confidential issue#show
38311:34:21 shows confidential sidebar information as confidential and can be turned off
38411:34:21# [RSpecRunTime] RSpec elapsed time: 6 minutes 16.03 seconds. Current RSS: ~1397M. Threads: 27. load average: 2.07 1.85 1.75 1/387 709
38511:34:21.
38611:34:21# [RSpecRunTime] Finishing example group spec/features/issues/user_sees_live_update_spec.rb. It took 23.9 seconds. Expected to take 24.62 seconds.
38711:34:21# [RSpecRunTime] Starting example group spec/features/projects/labels/issues_sorted_by_priority_spec.rb. Expected to take 10.41 seconds.
38811:34:21Issue prioritization
38911:34:22 when issues have one label
39011:34:26 are sorted properly
39111:34:26# [RSpecRunTime] RSpec elapsed time: 6 minutes 21.13 seconds. Current RSS: ~1397M. Threads: 27. load average: 2.15 1.87 1.75 1/388 713
39211:34:26.
39311:34:26 when issues have multiple labels
39411:34:31 are sorted properly
39511:34:31# [RSpecRunTime] RSpec elapsed time: 6 minutes 25.82 seconds. Current RSS: ~1385M. Threads: 27. load average: 2.05 1.86 1.75 1/388 716
39611:34:31.
39711:34:31# [RSpecRunTime] Finishing example group spec/features/projects/labels/issues_sorted_by_priority_spec.rb. It took 9.78 seconds. Expected to take 10.41 seconds.
39811:34:31# [RSpecRunTime] Starting example group spec/features/atom/issues_spec.rb. Expected to take 9.57 seconds.
39911:34:31Issues Feed
40011:34:31 GET /issues
40111:34:33 renders atom feed with url parameters for project issues
40211:34:33 renders atom feed with url parameters for group issues
40311:34:33 when authenticated
40411:34:33 behaves like an authenticated issuable atom feed
40511:34:34 renders atom feed with common issuable information
40611:34:34# [RSpecRunTime] RSpec elapsed time: 6 minutes 28.81 seconds. Current RSS: ~1386M. Threads: 27. load average: 1.97 1.84 1.74 1/387 717
40711:34:34.
40811:34:34 behaves like an authenticated issue atom feed
40911:34:34 renders atom feed with additional issue information
41011:34:34 when authenticated via personal access token
41111:34:34 behaves like an authenticated issuable atom feed
41211:34:35 renders atom feed with common issuable information
41311:34:35 behaves like an authenticated issue atom feed
41411:34:35 renders atom feed with additional issue information
41511:34:35 when authenticated via feed token
41611:34:35 behaves like an authenticated issuable atom feed
41711:34:36 renders atom feed with common issuable information
41811:34:36 behaves like an authenticated issue atom feed
41911:34:36 renders atom feed with additional issue information
42011:34:36 when not authenticated
42111:34:36 and the project is private
42211:34:38 redirects to login page
42311:34:38# [RSpecRunTime] RSpec elapsed time: 6 minutes 32.59 seconds. Current RSS: ~1406M. Threads: 27. load average: 1.97 1.84 1.74 1/387 718
42411:34:38.
42511:34:38 and the project is public
42611:34:38 behaves like an authenticated issuable atom feed
42711:34:39 renders atom feed with common issuable information
42811:34:39 behaves like an authenticated issue atom feed
42911:34:39 renders atom feed with additional issue information
43011:34:39# [RSpecRunTime] Finishing example group spec/features/atom/issues_spec.rb. It took 7.93 seconds. Expected to take 9.57 seconds.
43111:34:44auto_explain log contains 896 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-system-predictive-10-17.331.main.ndjson.gz
43211:34:44took 4.434345779
43311:34:48auto_explain log contains 896 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-system-predictive-10-17.331.ci.ndjson.gz
43411:34:48took 4.288782447
43511:34:48[TEST PROF INFO] EventProf results for sql.active_record
43611:34:48Total time: 01:44.186 of 06:28.473 (26.82%)
43711:34:48Total events: 39897
43811:34:48Top 5 slowest suites (by time):
43911:34:48Thread Comments Issue (./spec/features/discussion_comments/issue_spec.rb:5) – 00:47.087 (18443 / 17) of 02:52.178 (27.35%)
44011:34:48User comments on issue (./spec/features/issues/user_comments_on_issue_spec.rb:5) – 00:21.626 (7866 / 10) of 01:21.800 (26.44%)
44111:34:48viewing an issue (./spec/features/projects/issues/email_participants_spec.rb:5) – 00:12.610 (4731 / 6) of 00:39.732 (31.74%)
44211:34:48Work item keyboard shortcuts (./spec/features/projects/work_items/shortcuts_work_item_spec.rb:5) – 00:12.159 (3978 / 8) of 00:53.147 (22.88%)
44311:34:48Issues > User sees live update (./spec/features/issues/user_sees_live_update_spec.rb:5) – 00:06.665 (2598 / 2) of 00:23.903 (27.89%)
44411:34:48Knapsack report was generated. Preview:
44511:34:48{
44611:34:48 "spec/features/discussion_comments/issue_spec.rb": 172.17761945799975,
44711:34:48 "spec/features/issues/user_comments_on_issue_spec.rb": 81.80023489100131,
44811:34:48 "spec/features/projects/work_items/shortcuts_work_item_spec.rb": 53.14723577999939,
44911:34:48 "spec/features/projects/issues/email_participants_spec.rb": 39.73253097899942,
45011:34:48 "spec/features/issues/user_sees_live_update_spec.rb": 23.903175613000712,
45111:34:48 "spec/features/projects/labels/issues_sorted_by_priority_spec.rb": 9.775510202000078,
45211:34:48 "spec/features/atom/issues_spec.rb": 7.933650905999457
45311:34:48}
45411:34:48Knapsack global time execution for tests: 06m 28s
45511:34:48Finished in 6 minutes 43 seconds (files took 57.81 seconds to load)
45611:34:4856 examples, 0 failures
45711:34:48Randomized with seed 39004
45811:34:56Failed to write to log, write log/workhorse-test.log: file already closed
45911:34:57RSpec exited with 0.
46011:34:57No examples to retry, congrats!
461 11:34:57 Running after_script 00:13
46211:34:57Running after script...
46311:34:57$ source scripts/utils.sh
46411:34:57$ log_disk_usage after_script
46511:34:57[log_disk_usage after_script] start
46611:34:57df -h
46711:34:57Filesystem Size Used Avail Use% Mounted on
46811:34:57overlay 46G 31G 15G 68% /
46911:34:57tmpfs 64M 0 64M 0% /dev
47011:34:57shm 256M 0 256M 0% /dev/shm
47111:34:57/dev/sda1 46G 31G 15G 68% /cache
47211:34:57tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
47311:34:57tmpfs 3.9G 0 3.9G 0% /proc/acpi
47411:34:57tmpfs 3.9G 0 3.9G 0% /proc/scsi
47511:34:57tmpfs 3.9G 0 3.9G 0% /sys/firmware
47611:34:57du -h -d 1
47711:34:577.3M ./workhorse
47811:34:57148K ./bin
47911:34:571.2M ./.gitlab
48011:34:57159M ./.git
48111:34:574.0K ./builds
48211:34:5716M ./coverage
48311:34:5827M ./lib
48411:34:588.0K ./.vscode
48511:34:58516K ./storybook
48611:34:5811M ./log
48711:34:581.8M ./data
48811:34:589.9M ./qa
48911:34:582.9M ./.rubocop_todo
49011:35:00822M ./vendor
49111:35:00609M ./public
49211:35:0058M ./doc
49311:35:00480K ./danger
49411:35:00204K ./rspec
49511:35:008.0K ./.bundle
49611:35:00536K ./tooling
49711:35:01138M ./ee
49811:35:011.3M ./knapsack
49911:35:01296K ./patches
50011:35:014.3M ./changelogs
50111:35:0120K ./haml_lint
50211:35:0128K ./metrics_server
50311:35:0188K ./keeps
50411:35:0148K ./shared
50511:35:0144K ./generator_templates
50611:35:011.3M ./scripts
50711:35:011.2G ./tmp
50811:35:02125M ./spec
50911:35:0220K ./file_hooks
51011:35:0232K ./crystalball
51111:35:0216K ./sidekiq_cluster
51211:35:0216M ./gems
51311:35:021.2M ./fixtures
51411:35:02948K ./rubocop
51511:35:024.0K ./.go
51611:35:0229M ./db
51711:35:02188K ./auto_explain
51811:35:0212K ./.github
51911:35:02118M ./locale
52011:35:021.9M ./glfm_specification
52111:35:0217M ./config
52211:35:0380M ./app
52311:35:0316K ./.lefthook
52411:35:033.4G .
52511:35:03[log_disk_usage after_script] end
52611:35:03$ bundle exec gem list gitlab_quality-test_tooling
52711:35:05gitlab_quality-test_tooling (1.37.0)
52811:35:05$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
529 11:35:05 Report test failures 00:00
53111:35:05$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
532 11:35:05 Report test flakiness 00:00
53411:35:05$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
535 11:35:05 Report test slowness 00:00
53711:35:05$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
538 11:35:05 Report test slowness in MR note 00:02
54111:35:07$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
542 11:35:07 Report test files close to timing out 00:02
54511:35:09$ tooling/bin/push_job_metrics || true
54611:35:10[job-metrics] Pushing job metrics file for the CI/CD job.
54711:35:10[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
548 11:35:10 Saving cache for successful job 00:01
54911:35:11Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-18 due to policy
550 11:35:11 Uploading artifacts for successful job 00:06
55111:35:12Uploading artifacts...
55211:35:12auto_explain/: found 3 matching artifact files and directories
55311:35:12coverage/: found 5 matching artifact files and directories
55411:35:12crystalball/: found 2 matching artifact files and directories
55511:35:12WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
55611:35:12knapsack/: found 4 matching artifact files and directories
55711:35:12rspec/: found 16 matching artifact files and directories
55811:35:12WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
55911:35:12log/*.log: found 15 matching artifact files and directories
56011:35:13WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7753125135/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
56111:35:13WARNING: Retrying... context=artifacts-uploader error=request redirected
56211:35:15Uploading artifacts as "archive" to coordinator... 201 Created id=7753125135 responseStatus=201 Created token=glcbt-66
56311:35:15Uploading artifacts...
56411:35:15rspec/rspec-*.xml: found 1 matching artifact files and directories
56511:35:15WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7753125135/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
56611:35:15WARNING: Retrying... context=artifacts-uploader error=request redirected
56711:35:17Uploading artifacts as "junit" to coordinator... 201 Created id=7753125135 responseStatus=201 Created token=glcbt-66
568 11:35:17 Cleaning up project directory and file based variables 00:01
56911:35:26Job succeeded