Skip to content
Snippets Groups Projects

rspec system predictive 10/48

Passed Started by
Shane Maglangit
@smaglangit
Log timestamps in UTC.
114:34:35Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
214:34:35 on blue-4.shared-gitlab-org.runners-manager.gitlab.com/default 8cwZ3F43, system ID: s_df0f7e24ca8b
314:34:35 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
414:34:35Resolving secrets
5 14:34:35 Preparing the "docker+machine" executor 00:08
614:34:35Using 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 ...
714:34:37Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
814:34:37Authenticating with credentials from job payload (GitLab Registry)
914:34:37Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1014:34:37Using 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 ...
1114:34:38WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1214:34:38WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1314:34:38Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1414:34:38Authenticating with credentials from job payload (GitLab Registry)
1514:34:38Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1614:34: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 ...
1714:34:38Starting service redis:6.2-alpine...
1814:34:38Pulling docker image redis:6.2-alpine ...
1914:34:39Using docker image sha256:7de0dedd123b8cb2b105ace4e8d00b8bba5ad7be39617dfa229acff315fe4fbf for redis:6.2-alpine with digest redis@sha256:2d1463258f2764328496376f5d965f20c6a67f66ea2b06dc42af351f75248792 ...
2014:34:40Waiting for services to be up and running (timeout 30 seconds)...
2114:34:43Authenticating with credentials from job payload (GitLab Registry)
2214:34:43Pulling 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 ...
2314:34:43Using docker image sha256:ab7961692458f506f4ffa89e6e60bf1fc6de783a0d358136ca0b3e84c63c84a8 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:8ec7e4c24be92019bc9072c7049231de71d9a56301d7562522d046f1494470be ...
24 14:34:43 Preparing environment 00:01
2514:34:44Running on runner-8cwz3f43-project-278964-concurrent-0 via runner-8cwz3f43-shared-gitlab-org-1727963573-d10b6677...
26 14:34:44 Getting source from Git repository 00:00
2714:34:44Skipping Git repository setup
2814:34:44Skipping Git checkout
2914:34:44Skipping Git submodules setup
30 14:34:44 Restoring cache 00:18
3114:34:45Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19...
3214:34:45Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19 ETag="14fbc93778b726d9de5f670de248350a"
3314:34:56Successfully extracted cache
34 14:35:02 Downloading artifacts 00:42
3514:35:02Downloading artifacts for clone-gitlab-repo (7986567460)...
3614:35:07Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7986567460 responseStatus=200 OK token=glcbt-66
3714:35:07WARNING: Part of .git directory is on the list of files to extract
3814:35:07WARNING: This may introduce unexpected problems
3914:35:21Downloading artifacts for compile-test-assets (7986567654)...
4014:35:23Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7986567654 responseStatus=200 OK token=glcbt-66
4114:35:28Downloading artifacts for detect-tests (7986567701)...
4214:35:28Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7986567701 responseStatus=200 OK token=glcbt-66
4314:35:28Downloading artifacts for retrieve-tests-metadata (7986567720)...
4414:35:29Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7986567720 responseStatus=200 OK token=glcbt-66
4514:35:29Downloading artifacts for setup-test-env (7986567668)...
4614:35:32Downloading artifacts from coordinator... ok host=storage.googleapis.com id=7986567668 responseStatus=200 OK token=glcbt-66
47 14:35:44 Executing "step_script" stage of the job script
4814:35:44Using docker image sha256:ab7961692458f506f4ffa89e6e60bf1fc6de783a0d358136ca0b3e84c63c84a8 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:8ec7e4c24be92019bc9072c7049231de71d9a56301d7562522d046f1494470be ...
4914:35:44$ echo $FOSS_ONLY
5014:35:44$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5114:35:44$ export GOPATH=$CI_PROJECT_DIR/.go
5214:35:44$ mkdir -p $GOPATH
5314:35:44$ source scripts/utils.sh
5414:35:44$ log_disk_usage before_script
5514:35:44[log_disk_usage before_script] start
5614:35:44df -h
5714:35:44Filesystem Size Used Avail Use% Mounted on
5814:35:44overlay 46G 16G 30G 34% /
5914:35:44tmpfs 64M 0 64M 0% /dev
6014:35:44shm 256M 0 256M 0% /dev/shm
6114:35:44/dev/sda1 46G 16G 30G 34% /cache
6214:35:44tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6314:35:44tmpfs 3.9G 0 3.9G 0% /proc/acpi
6414:35:44tmpfs 3.9G 0 3.9G 0% /proc/scsi
6514:35:44tmpfs 3.9G 0 3.9G 0% /sys/firmware
6614:35:44du -h -d 1
6714:35:447.4M ./workhorse
6814:35:44148K ./bin
6914:35:441.2M ./.gitlab
7014:35:44173M ./.git
7114:35:444.0K ./builds
7214:35:4428M ./lib
7314:35:448.0K ./.vscode
7414:35:44532K ./storybook
7514:35:4424K ./log
7614:35:441.8M ./data
7714:35:4410M ./qa
7814:35:442.9M ./.rubocop_todo
7914:35:45828M ./vendor
8014:35:45638M ./public
8114:35:4559M ./doc
8214:35:45492K ./danger
8314:35:45200K ./rspec
8414:35:45552K ./tooling
8514:35:45141M ./ee
8614:35:451.3M ./knapsack
8714:35:45296K ./patches
8814:35:454.3M ./changelogs
8914:35:4520K ./haml_lint
9014:35:4528K ./metrics_server
9114:35:4588K ./keeps
9214:35:4548K ./shared
9314:35:4544K ./generator_templates
9414:35:451.2M ./scripts
9514:35:45619M ./tmp
9614:35:45126M ./spec
9714:35:4520K ./file_hooks
9814:35:4532K ./crystalball
9914:35:4516K ./sidekiq_cluster
10014:35:4516M ./gems
10114:35:451.2M ./fixtures
10214:35:45948K ./rubocop
10314:35:454.0K ./.go
10414:35:4532M ./db
10514:35:4512K ./.github
10614:35:45125M ./locale
10714:35:4519M ./config
10814:35:4581M ./app
10914:35:4516K ./.lefthook
11014:35:452.9G .
11114:35:45[log_disk_usage before_script] end
11214:35:45$ source scripts/prepare_build.sh
113 14:35:45 Installing gems 00:17
14414:36:02Using decomposed database config (config/database.yml.decomposed-postgresql)
14514:36:02Geo DB won't be set up.
14614:36:02Embedding DB won't be set up.
147 14:36:02 Setting up DBs 01:18
15914:37:20$ source ./scripts/rspec_helpers.sh
16014:37:20$ run_timed_command "gem install knapsack --no-document"
16114:37:20$ gem install knapsack --no-document
16214:37:21Successfully installed knapsack-4.0.0
16314:37:211 gem installed
16414:37:20==> 'gem install knapsack --no-document' succeeded in 1 seconds.
16514:37:21$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
166 14:37:21 Spawning Gitaly 00:05
17014:37:26$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17114:37:26$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17214:37:26$ tooling/bin/create_job_metrics_file || true
17314:37:28[job-metrics] Creating the job metrics file for the CI/CD job.
17414:37:28$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~level:background_migration --tag ~click_house"
175 14:37:28 RSpec 09:06
17614:37:28[14:37:28] Starting rspec_parallelized_job
17714:37:21RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
17814:37:29KNAPSACK_GENERATE_REPORT: true
17914:37:29FLAKY_RSPEC_GENERATE_REPORT: true
18014:37:29KNAPSACK_TEST_FILE_PATTERN: spec/{features}{,/**/}*_spec.rb
18114:37:29KNAPSACK_LOG_LEVEL: debug
18214:37:29KNAPSACK_REPORT_PATH: knapsack/rspec_system_predictive_10_48_278964_report.json
18314:37:29FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18414:37:29FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_system_predictive_10_48_278964_report.json
18514:37:29NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_system_predictive_10_48_278964_report.json
18614:37:29RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-7986687155.txt
18714:37:29CRYSTALBALL:
18814:37:29RSPEC_TESTS_MAPPING_ENABLED: true
18914:37:29RSPEC_TESTS_FILTER_FILE: rspec/matching_tests-foss.txt
19014:37:29Shell set options (set -o) enabled:
19114:37:29braceexpand on
19214:37:29hashall on
19314:37:29interactive-comments on
19414:37:29pipefail on
19514:37:36Knapsack report generator started!
19614:37:29/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!
19714:38:46warning: parser/current is loading parser/ruby32, which recognizes 3.2.4-compliant syntax, but you are running 3.2.5.
19814:38:46Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
19914:38:52Run options: exclude {:quarantine=>true, :level=>"background_migration", :click_house=>true}
20014:38:52# [RSpecRunTime] Starting RSpec timer...
20114:38:52[TEST PROF INFO] EventProf enabled (sql.active_record)
20214:38:52unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20314:38:52unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20414:38:59Test environment set up in 2.64889547 seconds
20514:38:59# [RSpecRunTime] Starting example group spec/features/admin/users/users_spec.rb. Expected to take 5 minutes 11.11 seconds.
20614:38:59Admin::Users
20714:39:00 GET /admin/users
20814:39:00Starting the Capybara driver server...
20914:39:05Capybara starting Puma...
21014:39:05* Version 6.4.3, codename: The Eagle of Durango
21114:39:05* Min threads: 0, max threads: 4
21214:39:05* Listening on unix:///tmp/20241003-332-czqh1w
21314:39:20 is ok
21414:39:23 has users list
21514:39:26 clicking edit user takes us to edit page
21614:39:29 shows the user popover on hover
21714:39:29 user project count
21814:39:33 displays count of users projects
21914:39:33 search and sort
22014:40:06 searches users by name
22114:40:09 sorts users by name
22214:40:41 sorts search results only
22314:40:44 searches with respect of sorting
22414:40:48 sorts users by recent last activity
22514:40:51 sorts users by oldest last activity
22614:40:51# [RSpecRunTime] RSpec elapsed time: 1 minute 59.01 seconds. Current RSS: ~1313M. Threads: 13. load average: 1.54 1.29 1.12 1/363 520
22714:40:51.
22814:40:51 Two-factor Authentication filters
22914:40:54 filters by users who have enabled 2FA
23014:40:56 filters users who have not enabled 2FA
23114:40:56# [RSpecRunTime] RSpec elapsed time: 2 minutes 3.96 seconds. Current RSS: ~1311M. Threads: 13. load average: 1.57 1.30 1.12 1/362 521
23214:40:56.
23314:40:56 Pending approval filter
23414:40:59 counts users who are pending approval
23514:41:01 filters by users who are pending approval
23614:41:01# [RSpecRunTime] RSpec elapsed time: 2 minutes 9.14 seconds. Current RSS: ~1310M. Threads: 13. load average: 1.53 1.29 1.12 1/363 523
23714:41:01.
23814:41:01 when blocking/unblocking a user
23914:42:08 shows confirmation and allows blocking and unblocking
24014:42:08# [RSpecRunTime] RSpec elapsed time: 3 minutes 15.72 seconds. Current RSS: ~1292M. Threads: 11. load average: 1.06 1.24 1.12 1/358 525
24114:42:08.
24214:42:08 when deactivating/re-activating a user
24314:42:44 shows confirmation and allows deactivating and re-activating
24414:42:44# [RSpecRunTime] RSpec elapsed time: 3 minutes 52.07 seconds. Current RSS: ~1287M. Threads: 11. load average: 1.08 1.24 1.12 1/357 528
24514:42:44.
24614:42:44 when a user is locked
24714:42:46 displays `Locked` badge next to user
24814:42:49 allows a user to be unlocked from the `User administration dropdown
24914:42:49# [RSpecRunTime] RSpec elapsed time: 3 minutes 56.93 seconds. Current RSS: ~1310M. Threads: 12. load average: 1.08 1.24 1.12 1/360 532
25014:42:49.
25114:42:49 users pending approval
25214:42:54 sends a welcome email and a password reset email to the user upon admin approval
25314:42:54# [RSpecRunTime] RSpec elapsed time: 4 minutes 1.57 seconds. Current RSS: ~1322M. Threads: 13. load average: 1.08 1.23 1.12 1/362 535
25414:42:54.
25514:42:54 internal users
25614:42:54 when showing a `Ghost User`
25714:42:55 does not render actions dropdown
25814:42:55# [RSpecRunTime] RSpec elapsed time: 4 minutes 3.1 seconds. Current RSS: ~1324M. Threads: 13. load average: 1.07 1.23 1.12 1/362 536
25914:42:55.
26014:42:55 when showing a `Bot User`
26114:42:57 does not render actions dropdown
26214:42:57# [RSpecRunTime] RSpec elapsed time: 4 minutes 4.61 seconds. Current RSS: ~1325M. Threads: 13. load average: 1.07 1.23 1.12 1/362 537
26314:42:57.
26414:42:57 user group count
26514:43:00 displays count of the users authorized groups
26614:43:00# [RSpecRunTime] RSpec elapsed time: 4 minutes 8.24 seconds. Current RSS: ~1330M. Threads: 13. load average: 0.98 1.21 1.11 2/363 539
26714:43:00.
26814:43:00 user pending approval
26914:43:06 shows user info
27014:43:06# [RSpecRunTime] RSpec elapsed time: 4 minutes 13.99 seconds. Current RSS: ~1325M. Threads: 13. load average: 0.99 1.20 1.11 1/364 542
27114:43:06.
27214:43:06 GET /admin/users/new
27314:43:07Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
27414:43:07See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
27514:43:07To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
27614:43:07Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
27714:43:07See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
27814:43:07To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
27914:43:07 creates new user
28014:43:08Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
28114:43:08See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
28214:43:08To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
28314:43:08Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
28414:43:08See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
28514:43:08To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
28614:43:08 applies defaults to user
28714:43:08Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
28814:43:08See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
28914:43:08To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
29014:43:09Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
29114:43:09See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
29214:43:09To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
29314:43:09 creates user with valid data
29414:43:09Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
29514:43:09See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
29614:43:09To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
29714:43:10Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
29814:43:10See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
29914:43:10To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
30014:43:10 calls send mail
30114:43:10Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
30214:43:10See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
30314:43:10To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
30414:43:11Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
30514:43:11See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
30614:43:11To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
30714:43:11 sends valid email to user with email & password
30814:43:11 username contains spaces
30914:43:11Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
31014:43:11See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
31114:43:11To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
31214:43:12Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
31314:43:12See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
31414:43:12To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
31514:43:12 doesn't create the user and shows an error message
31614:43:12# [RSpecRunTime] RSpec elapsed time: 4 minutes 19.61 seconds. Current RSS: ~1348M. Threads: 13. load average: 0.99 1.20 1.11 1/362 543
31714:43:12.
31814:43:12 with new users set to external enabled
31914:43:12 with regex to match internal user email address set
32014:43:16 automatically unchecks external for matching email
32114:43:19 creates an internal user
32214:43:19# [RSpecRunTime] RSpec elapsed time: 4 minutes 27.15 seconds. Current RSS: ~1345M. Threads: 13. load average: 1.30 1.26 1.13 1/363 545
32314:43:19.
32414:43:19 GET /admin/users/:id/projects
32514:43:20Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
32614:43:20See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
32714:43:20To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
32814:43:20 lists groups
32914:43:21Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
33014:43:21See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
33114:43:21To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
33214:43:22Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
33314:43:22See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
33414:43:22To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
33514:43:22 allows navigation to the group details
33614:43:22Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
33714:43:22See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
33814:43:22To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
33914:43:22 shows the group access level
34014:43:24Job arguments to MergeRequests::RemoveUserApprovalRulesWorker must be native JSON types, but {"project_id"=>3, "user_ids"=>[1]} is a ActiveSupport::HashWithIndifferentAccess.
34114:43:24See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
34214:43:24To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
34314:43:24Job arguments to Groups::ResetSeatCalloutsWorker must be native JSON types, but #<Group id:27 @group3> is a Group.
34414:43:24See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
34514:43:24To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
34614:43:25 allows group membership to be revoked
34714:43:25# [RSpecRunTime] RSpec elapsed time: 4 minutes 33.02 seconds. Current RSS: ~1348M. Threads: 13. load average: 1.28 1.25 1.13 1/363 547
34814:43:25.
34914:43:25 show breadcrumbs
35014:43:33 is expected to have text "Edit Identity"
35114:43:33# [RSpecRunTime] RSpec elapsed time: 4 minutes 41.06 seconds. Current RSS: ~1341M. Threads: 13. load average: 1.34 1.27 1.14 1/363 548
35214:43:33.
35314:43:33 GET /admin/users/:id/edit
35414:43:34 shows all breadcrumbs
35514:43:34 Update user
35614:43:35Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
35714:43:35See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
35814:43:35To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
35914:43:35Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
36014:43:35See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
36114:43:35To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
36214:43:35 shows page with new data
36314:43:36Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
36414:43:36See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
36514:43:36To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
36614:43:36Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
36714:43:36See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
36814:43:36To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
36914:43:36 changes user entry
37014:43:36# [RSpecRunTime] RSpec elapsed time: 4 minutes 44.06 seconds. Current RSS: ~1349M. Threads: 12. load average: 1.31 1.26 1.13 1/363 549
37114:43:36.
37214:43:36 update username to non ascii char
37314:43:37Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
37414:43:37See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
37514:43:37To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
37614:43:37Job arguments to ExternalServiceReactiveCachingWorker must be native JSON types, but VersionCheck is a Class.
37714:43:37See https://github.com/sidekiq/sidekiq/wiki/Best-Practices
37814:43:37To disable this error, add `Sidekiq.strict_args!(false)` to your initializer.
37914:43:37 is expected to have visible css "form[action=\"/admin/users/user1\"]"
38014:43:37# [RSpecRunTime] Finishing example group spec/features/admin/users/users_spec.rb. It took 4 minutes 38.39 seconds. Expected to take 5 minutes 11.11 seconds.
38114:43:37# [RSpecRunTime] Starting example group spec/features/merge_request/user_sees_check_out_branch_modal_spec.rb. Expected to take 22.78 seconds.
38214:43:37Merge request > User sees check out branch modal
38314:43:53 shows the check out branch modal
38414:44:01 closes the check out branch modal with the close action
38514:44:01# [RSpecRunTime] Finishing example group spec/features/merge_request/user_sees_check_out_branch_modal_spec.rb. It took 23.38 seconds. Expected to take 22.78 seconds.
38614:44:01# [RSpecRunTime] RSpec elapsed time: 5 minutes 8.6 seconds. Current RSS: ~1373M. Threads: 27. load average: 1.54 1.32 1.16 1/383 741
38714:44:01.
38814:44:01# [RSpecRunTime] Starting example group spec/features/projects/activity/user_sees_design_activity_spec.rb. Expected to take 17.25 seconds.
38914:44:01Projects > Activity > User sees design Activity
39014:44:01 the project is public
39114:44:02 behaves like being able to see design activity
39214:44:06 shows the design comment action in the activity page
39314:44:07 allows filtering out the design events
39414:44:10 allows filtering in the design events
39514:44:10# [RSpecRunTime] RSpec elapsed time: 5 minutes 17.43 seconds. Current RSS: ~1377M. Threads: 26. load average: 1.53 1.33 1.16 1/380 784
39614:44:10.
39714:44:10 the project is private
39814:44:11 behaves like being able to see design activity
39914:44:13 shows the design comment action in the activity page
40014:44:15 allows filtering out the design events
40114:44:17 allows filtering in the design events
40214:44:17# [RSpecRunTime] RSpec elapsed time: 5 minutes 24.83 seconds. Current RSS: ~1380M. Threads: 26. load average: 1.57 1.34 1.17 1/380 827
40314:44:17.
40414:44:17# [RSpecRunTime] Finishing example group spec/features/projects/activity/user_sees_design_activity_spec.rb. It took 16.24 seconds. Expected to take 17.25 seconds.
40514:44:17# [RSpecRunTime] Starting example group spec/features/merge_request/user_expands_diff_spec.rb. Expected to take 11.38 seconds.
40614:44:17User expands diff
40714:44:28 allows user to expand diff
40814:44:28# [RSpecRunTime] Finishing example group spec/features/merge_request/user_expands_diff_spec.rb. It took 10.83 seconds. Expected to take 11.38 seconds.
40914:44:28# [RSpecRunTime] RSpec elapsed time: 5 minutes 35.73 seconds. Current RSS: ~1391M. Threads: 29. load average: 1.55 1.34 1.17 1/386 1108
41014:44:28.
41114:44:28# [RSpecRunTime] Starting example group spec/features/projects/integrations/user_activates_irker_spec.rb. Expected to take 7.7 seconds.
41214:44:28User activates irker (IRC gateway)
41314:44:35 activates integration
41414:44:35# [RSpecRunTime] Finishing example group spec/features/projects/integrations/user_activates_irker_spec.rb. It took 7.15 seconds. Expected to take 7.7 seconds.
41514:44:35# [RSpecRunTime] RSpec elapsed time: 5 minutes 42.95 seconds. Current RSS: ~1381M. Threads: 28. load average: 1.55 1.35 1.17 1/387 1113
41614:44:35.
41714:44:35# [RSpecRunTime] Starting example group spec/features/snippets/user_snippets_spec.rb. Expected to take 4.44 seconds.
41814:44:35User Snippets
41914:44:36 view all of my snippets
42014:44:37 view my public snippets
42114:44:38 view my internal snippets
42214:44:39 view my private snippets
42314:44:39# [RSpecRunTime] Finishing example group spec/features/snippets/user_snippets_spec.rb. It took 3.86 seconds. Expected to take 4.44 seconds.
42414:44:39# [RSpecRunTime] RSpec elapsed time: 5 minutes 46.86 seconds. Current RSS: ~1392M. Threads: 28. load average: 1.55 1.35 1.17 1/380 1114
42514:44:39.
42614:44:41auto_explain log contains 924 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-system-predictive-10-48.332.main.ndjson.gz
42714:44:41took 1.784739963
42814:44:43auto_explain log contains 925 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-system-predictive-10-48.332.ci.ndjson.gz
42914:44:43took 1.744743976
43014:44:43[TEST PROF INFO] EventProf results for sql.active_record
43114:44:43Total time: 00:27.170 of 05:40.080 (7.99%)
43214:44:43Total events: 12251
43314:44:43Top 5 slowest suites (by time):
43414:44:43Admin::Users (./spec/features/admin/users/users_spec.rb:5) – 00:13.969 (7245 / 41) of 04:38.386 (5.02%)
43514:44:43Merge request...t branch modal (./spec/features/merge_request/user_sees_check_out_branch_modal_spec.rb:5) – 00:06.576 (2000 / 2) of 00:23.436 (28.06%)
43614:44:43Projects > Ac...esign Activity (./spec/features/projects/activity/user_sees_design_activity_spec.rb:5) – 00:02.497 (1251 / 6) of 00:16.243 (15.37%)
43714:44:43User expands diff (./spec/features/merge_request/user_expands_diff_spec.rb:5) – 00:02.419 (792 / 1) of 00:10.888 (22.22%)
43814:44:43User Snippets (./spec/features/snippets/user_snippets_spec.rb:5) – 00:00.942 (481 / 4) of 00:03.916 (24.07%)
43914:44:43Knapsack report was generated. Preview:
44014:44:43{
44114:44:43 "spec/features/admin/users/users_spec.rb": 278.38594137200016,
44214:44:43 "spec/features/merge_request/user_sees_check_out_branch_modal_spec.rb": 23.37576097300007,
44314:44:43 "spec/features/projects/activity/user_sees_design_activity_spec.rb": 16.243401685000208,
44414:44:43 "spec/features/merge_request/user_expands_diff_spec.rb": 10.826390184999582,
44514:44:43 "spec/features/projects/integrations/user_activates_irker_spec.rb": 7.149772278,
44614:44:43 "spec/features/snippets/user_snippets_spec.rb": 3.856268420000106
44714:44:43}
44814:44:43Knapsack global time execution for tests: 05m 39s
44914:44:43Finished in 5 minutes 51 seconds (files took 1 minute 20 seconds to load)
45014:44:4355 examples, 0 failures
45114:44:49Failed to write to log, write log/workhorse-test.log: file already closed
45214:44:50RSpec exited with 0.
45314:44:50No examples to retry, congrats!
454 14:44:50 Running after_script 00:14
45514:44:51Running after script...
45614:44:51$ source scripts/utils.sh
45714:44:51$ log_disk_usage after_script
45814:44:51[log_disk_usage after_script] start
45914:44:51df -h
46014:44:51Filesystem Size Used Avail Use% Mounted on
46114:44:51overlay 46G 16G 30G 36% /
46214:44:51tmpfs 64M 0 64M 0% /dev
46314:44:51shm 256M 0 256M 0% /dev/shm
46414:44:51/dev/sda1 46G 16G 30G 36% /cache
46514:44:51tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
46614:44:51tmpfs 3.9G 0 3.9G 0% /proc/acpi
46714:44:51tmpfs 3.9G 0 3.9G 0% /proc/scsi
46814:44:51tmpfs 3.9G 0 3.9G 0% /sys/firmware
46914:44:51du -h -d 1
47014:44:517.4M ./workhorse
47114:44:51148K ./bin
47214:44:511.2M ./.gitlab
47314:44:51173M ./.git
47414:44:514.0K ./builds
47514:44:5116M ./coverage
47614:44:5128M ./lib
47714:44:518.0K ./.vscode
47814:44:51532K ./storybook
47914:44:513.5M ./log
48014:44:511.8M ./data
48114:44:5110M ./qa
48214:44:512.9M ./.rubocop_todo
48314:44:53828M ./vendor
48414:44:54638M ./public
48514:44:5459M ./doc
48614:44:54492K ./danger
48714:44:54268K ./rspec
48814:44:548.0K ./.bundle
48914:44:54552K ./tooling
49014:44:54141M ./ee
49114:44:541.3M ./knapsack
49214:44:54296K ./patches
49314:44:544.3M ./changelogs
49414:44:5420K ./haml_lint
49514:44:5428K ./metrics_server
49614:44:5488K ./keeps
49714:44:5448K ./shared
49814:44:5444K ./generator_templates
49914:44:541.2M ./scripts
50014:44:551.1G ./tmp
50114:44:55126M ./spec
50214:44:5520K ./file_hooks
50314:44:5532K ./crystalball
50414:44:5516K ./sidekiq_cluster
50514:44:5516M ./gems
50614:44:551.2M ./fixtures
50714:44:55948K ./rubocop
50814:44:554.0K ./.go
50914:44:5532M ./db
51014:44:55220K ./auto_explain
51114:44:5512K ./.github
51214:44:55125M ./locale
51314:44:5519M ./config
51414:44:5581M ./app
51514:44:5516K ./.lefthook
51614:44:553.4G .
51714:44:55[log_disk_usage after_script] end
51814:44:55$ bundle exec gem list gitlab_quality-test_tooling
51914:44:57gitlab_quality-test_tooling (1.38.1)
52014:44:57$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
521 14:44:57 Report test failures 00:00
52314:44:57$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
524 14:44:57 Report test flakiness 00:00
52614:44:57$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
527 14:44:57 Report test slowness 00:00
52914:44:57$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
530 14:44:57 Report test slowness in MR note 00:04
53414:45:01$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
535 14:45:01 Report test files close to timing out 00:02
53814:45:03$ tooling/bin/push_job_metrics || true
53914:45:04[job-metrics] Pushing job metrics file for the CI/CD job.
54014:45:04[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
541 14:45:04 Saving cache for successful job 00:01
54214:45:05Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-19 due to policy
543 14:45:05 Uploading artifacts for successful job 00:05
54414:45:05Uploading artifacts...
54514:45:06auto_explain/: found 3 matching artifact files and directories
54614:45:06coverage/: found 5 matching artifact files and directories
54714:45:06crystalball/: found 2 matching artifact files and directories
54814:45:06WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
54914:45:06knapsack/: found 4 matching artifact files and directories
55014:45:06rspec/: found 16 matching artifact files and directories
55114:45:06WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
55214:45:06log/*.log: found 16 matching artifact files and directories
55314:45:06WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7986687155/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
55414:45:06WARNING: Retrying... context=artifacts-uploader error=request redirected
55514:45:08Uploading artifacts as "archive" to coordinator... 201 Created id=7986687155 responseStatus=201 Created token=glcbt-66
55614:45:08Uploading artifacts...
55714:45:08rspec/rspec-*.xml: found 1 matching artifact files and directories
55814:45:08WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/7986687155/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
55914:45:08WARNING: Retrying... context=artifacts-uploader error=request redirected
56014:45:10Uploading artifacts as "junit" to coordinator... 201 Created id=7986687155 responseStatus=201 Created token=glcbt-66
561 14:45:10 Cleaning up project directory and file based variables 00:00
56214:45:18Job succeeded