rspec system pg14 9/32
Passed Started
by
@dblessing
Drew Blessing
1Running with gitlab-runner 16.8.0~beta.67.gb5664e8e (b5664e8e)2 on blue-1.shared-gitlab-org.runners-manager.gitlab.com/default KzYhZxBv, system ID: s_e6befde69e263 feature flags: FF_NETWORK_PER_BUILD:true, FF_USE_IMPROVED_URL_MASKING:true6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 ...7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...8Authenticating with credentials from job payload (GitLab Registry)9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...10Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...14Authenticating with credentials from job payload (GitLab Registry)15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...16Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...17Starting service redis:6.2-alpine ...18Pulling docker image redis:6.2-alpine ...19Using docker image sha256:b6c412dc298714b5c26feca1330d8f743b48caf0b1feeadfd554d7d446f985a5 for redis:6.2-alpine with digest redis@sha256:51d6c56749a4243096327e3fb964a48ed92254357108449cb6e23999c37773c5 ...20Waiting for services to be up and running (timeout 30 seconds)...21Authenticating with credentials from job payload (GitLab Registry)22Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 ...23Using docker image sha256:0704d2ebff510f3d6f608cd8e9064640a9dd041c4e3ac1e1ff2afb5fe22850bc for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14@sha256:bbf2c8a67a31469f488a4541826628901b6eb5c0a4d68147919aca0b98107df6 ...25Running on runner-kzyhzxbv-project-278964-concurrent-0 via runner-kzyhzxbv-shared-gitlab-org-1708468044-4f4fb491...27Skipping Git repository setup28Skipping Git checkout29Skipping Git submodules setup31Checking cache for ruby-gems-debian-bookworm-ruby-3.1-16...32Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.1-16 33Successfully extracted cache35Downloading artifacts for clone-gitlab-repo (6218670526)...36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6218670526 responseStatus=200 OK token=glcbt-6537WARNING: Part of .git directory is on the list of files to extract 38WARNING: This may introduce unexpected problems 39Downloading artifacts for compile-test-assets (6218670555)...40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6218670555 responseStatus=200 OK token=glcbt-6541Downloading artifacts for retrieve-tests-metadata (6218670576)...42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6218670576 responseStatus=200 OK token=glcbt-6543Downloading artifacts for setup-test-env (6218670568)...44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6218670568 responseStatus=200 OK token=glcbt-6546Using docker image sha256:0704d2ebff510f3d6f608cd8e9064640a9dd041c4e3ac1e1ff2afb5fe22850bc for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14@sha256:bbf2c8a67a31469f488a4541826628901b6eb5c0a4d68147919aca0b98107df6 ...47$ echo $FOSS_ONLY48$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb49$ export GOPATH=$CI_PROJECT_DIR/.go50$ mkdir -p $GOPATH51$ source scripts/utils.sh52$ source scripts/prepare_build.sh731Using decomposed database config (config/database.yml.decomposed-postgresql)732Geo DB won't be set up.733Embedding DB won't be set up.785$ source ./scripts/rspec_helpers.sh786$ run_timed_command "gem install knapsack --no-document"787$ gem install knapsack --no-document788Successfully installed knapsack-4.0.07891 gem installed790==> 'gem install knapsack --no-document' succeeded in 1 seconds.791$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"796$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"797$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"798$ tooling/bin/create_job_metrics_file || true799[job-metrics] Creating the job metrics file for the CI/CD job.800$ rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~level:background_migration --tag ~click_house --tag ~real_ai_request"801[23:18:59] Starting rspec_parallelized_job802RETRY_FAILED_TESTS_IN_NEW_PROCESS: true803KNAPSACK_GENERATE_REPORT: true804FLAKY_RSPEC_GENERATE_REPORT: true805KNAPSACK_TEST_FILE_PATTERN: spec/{features}{,/**/}*_spec.rb806KNAPSACK_LOG_LEVEL: debug807KNAPSACK_REPORT_PATH: knapsack/rspec_system_pg14_9_32_278964_report.json808FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json809FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_system_pg14_9_32_278964_report.json810NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_system_pg14_9_32_278964_report.json811RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6218670852.txt812CRYSTALBALL: 813RSPEC_TESTS_MAPPING_ENABLED: 814RSPEC_TESTS_FILTER_FILE: 815Shell set options (set -o) enabled:816braceexpand on817hashall on818interactive-comments on819pipefail on820Knapsack report generator started!821Run options: exclude {:quarantine=>true, :level=>"background_migration", :click_house=>true, :real_ai_request=>true}822# [RSpecRunTime] Starting RSpec timer...823[TEST PROF INFO] EventProf enabled (sql.active_record)824unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.825unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.826Test environment set up in 1.145192485 seconds827# [RSpecRunTime] Starting example group spec/features/merge_request/user_sees_merge_request_pipelines_spec.rb. Expected to take 8 minutes 27.11 seconds.828Merge request > User sees pipelines triggered by merge request829Starting the Capybara driver server...830 with feature flag `mr_pipelines_graphql turned off`831 when a user created a merge request in the parent project832Capybara starting Puma...833* Version 6.4.0, codename: The Eagle of Durango834* Min threads: 0, max threads: 4835* Listening on unix:///tmp/20240220-339-rszjx1836 sees branch pipelines and detached merge request pipelines in correct order837 sees the latest detached merge request pipeline as the head pipeline838 when a user updated a merge request in the parent project839 sees branch pipelines and detached merge request pipelines in correct order840 sees detached tag for detached merge request pipelines841 sees the latest detached merge request pipeline as the head pipeline842 when a user created a merge request in the parent project843 when a user merges a merge request in the parent project844 when detached merge request pipeline is pending845 waits the head pipeline846# [RSpecRunTime] RSpec elapsed time: 2 minutes 35.85 seconds. Current RSS: ~1453M. load average: 2.09 1.23 0.88 1/388 1248847 when branch pipeline succeeds848 waits the head pipeline849# [RSpecRunTime] RSpec elapsed time: 3 minutes 1.7 seconds. Current RSS: ~1455M. load average: 2.13 1.31 0.91 1/389 1370850 when there are no `merge_requests` keyword in .gitlab-ci.yml851 sees a branch pipeline in pipeline tab852 sees the latest branch pipeline as the head pipeline853# [RSpecRunTime] RSpec elapsed time: 3 minutes 25.5 seconds. Current RSS: ~1457M. load average: 1.87 1.31 0.92 1/389 1522854 when a user created a merge request from a forked project to the parent project855 sees branch pipelines and detached merge request pipelines in correct order856 sees the latest detached merge request pipeline as the head pipeline857 sees pipeline list in forked project858 when a user updated a merge request from a forked project to the parent project859 sees branch pipelines and detached merge request pipelines in correct order860 sees detached tag for detached merge request pipelines861 sees the latest detached merge request pipeline as the head pipeline862 sees pipeline list in forked project863# [RSpecRunTime] RSpec elapsed time: 6 minutes 2.58 seconds. Current RSS: ~1460M. load average: 2.00 1.55 1.07 3/388 2526864 when the latest pipeline is running in the parent project865 when the previous pipeline failed in the fork project866 when the parent project enables pipeline must succeed867 shows Set to auto-merge button868# [RSpecRunTime] RSpec elapsed time: 6 minutes 26.77 seconds. Current RSS: ~1465M. load average: 2.28 1.65 1.12 1/391 2671869 when a user merges a merge request from a forked project to the parent project870 when detached merge request pipeline is pending871 waits the head pipeline872# [RSpecRunTime] RSpec elapsed time: 6 minutes 43.68 seconds. Current RSS: ~1498M. load average: 2.07 1.63 1.12 1/390 2794873 when detached merge request pipeline succeeds874 merges the merge request875# [RSpecRunTime] RSpec elapsed time: 7 minutes 21.2 seconds. Current RSS: ~1583M. load average: 1.86 1.65 1.15 1/387 2937876 when branch pipeline succeeds877 waits the head pipeline878# [RSpecRunTime] RSpec elapsed time: 7 minutes 39.77 seconds. Current RSS: ~1479M. load average: 1.67 1.61 1.15 1/390 3064879# [RSpecRunTime] Finishing example group spec/features/merge_request/user_sees_merge_request_pipelines_spec.rb. It took 7 minutes 36.03 seconds. Expected to take 8 minutes 27.11 seconds.880# [RSpecRunTime] Starting example group spec/features/merge_request/batch_comments_spec.rb. Expected to take 3 minutes 13.83 seconds.881Merge request > Batch comments882 adds draft note883 publishes review884 deletes draft note885 edits draft note886 multiple times on the same diff line887 shows both drafts at once888# [RSpecRunTime] RSpec elapsed time: 8 minutes 31.87 seconds. Current RSS: ~1477M. load average: 1.55 1.57 1.16 1/393 3441889 with image and file draft note890 does not show in overview891# [RSpecRunTime] RSpec elapsed time: 8 minutes 47.65 seconds. Current RSS: ~1474M. load average: 2.21 1.71 1.21 1/392 3555892 adding single comment to review893 at first does not show `Add to review` and `Add comment now` buttons894 when review has started895 can add comment to review896 can add comment right away897# [RSpecRunTime] RSpec elapsed time: 10 minutes 8.78 seconds. Current RSS: ~1480M. load average: 2.27 1.85 1.30 1/391 3874898 in parallel diff899 adds draft comments to both sides900# [RSpecRunTime] RSpec elapsed time: 10 minutes 20.66 seconds. Current RSS: ~1482M. load average: 2.07 1.82 1.30 1/389 3951901 thread is unresolved902 publishes comment right away and resolves the thread903 publishes review and resolves the thread904# [RSpecRunTime] RSpec elapsed time: 10 minutes 54.81 seconds. Current RSS: ~1475M. load average: 1.94 1.83 1.32 1/389 4191905# [RSpecRunTime] Finishing example group spec/features/merge_request/batch_comments_spec.rb. It took 3 minutes 14.23 seconds. Expected to take 3 minutes 13.83 seconds.906# [RSpecRunTime] Starting example group spec/features/protected_branches_spec.rb. Expected to take 2 minutes 26.27 seconds.907Protected Branches908 logged in as developer909 Delete protected branch910 does not allow developer to remove protected branch911# [RSpecRunTime] RSpec elapsed time: 11 minutes 1.86 seconds. Current RSS: ~1536M. load average: 1.80 1.80 1.32 1/389 4255912 logged in as maintainer913 allows to create a protected branch with name containing HTML tags914 Delete protected branch915 removes branch after modal confirmation916# [RSpecRunTime] RSpec elapsed time: 11 minutes 21.42 seconds. Current RSS: ~1591M. load average: 1.77 1.79 1.32 1/389 4386917 logged in as admin918 explicit protected branches919 allows creating explicit protected branches920 shows success alert once protected branch is created921 displays the last commit on the matching branch if it exists922 displays an error message if the named branch does not exist923# [RSpecRunTime] RSpec elapsed time: 11 minutes 55.42 seconds. Current RSS: ~1662M. load average: 2.20 1.89 1.37 1/390 4546924 wildcard protected branches925 allows creating protected branches with a wildcard926 displays all the branches matching the wildcard927# [RSpecRunTime] RSpec elapsed time: 12 minutes 16.71 seconds. Current RSS: ~1656M. load average: 1.98 1.87 1.38 1/390 4644928 access control929 allows creating protected branches that Developers + Maintainers can merge to930 allows creating protected branches that Maintainers can merge to931 allows creating protected branches that Instance admins can merge to932 allows creating protected branches that No one can merge to933# [RSpecRunTime] RSpec elapsed time: 12 minutes 53.27 seconds. Current RSS: ~1675M. load average: 1.63 1.79 1.37 1/391 4805934 when the users for protected branches feature is off935 when deploy keys are enabled to this project936 when only one deploy key can push937 shows all dropdown sections in the 'Allowed to push' main dropdown, with only one deploy key938 shows all sections but not deploy keys in the 'Allowed to merge' main dropdown939 shows all sections in the 'Allowed to push' update dropdown940# [RSpecRunTime] RSpec elapsed time: 13 minutes 11.03 seconds. Current RSS: ~1641M. load average: 1.92 1.84 1.39 5/392 4907941 when no deploy key can push942 just shows all sections but not deploy keys in the 'Allowed to push' dropdown943 just shows all sections but not deploy keys in the 'Allowed to push' update dropdown944# [RSpecRunTime] RSpec elapsed time: 13 minutes 22.0 seconds. Current RSS: ~1634M. load average: 1.94 1.84 1.40 5/391 4975945# [RSpecRunTime] Finishing example group spec/features/protected_branches_spec.rb. It took 2 minutes 27.28 seconds. Expected to take 2 minutes 26.27 seconds.946# [RSpecRunTime] Starting example group spec/features/projects/blobs/edit_spec.rb. Expected to take 1 minute 45.1 seconds.947Editing file blob948 as a developer949 updates the content of file with a number as file path950 editing a template file in a sub directory does not change path951 updating file path updates syntax highlighting952 from MR diff953 returns me to the mr954# [RSpecRunTime] RSpec elapsed time: 13 minutes 52.5 seconds. Current RSS: ~1516M. load average: 1.87 1.84 1.41 1/393 5204955 blob edit toolbar956 has defined set of toolbar buttons when the flag is on957 has defined set of toolbar buttons when the flag is off958# [RSpecRunTime] RSpec elapsed time: 13 minutes 58.51 seconds. Current RSS: ~1513M. load average: 1.80 1.83 1.41 1/392 5218959 from blob file path960 updates content961 previews content962# [RSpecRunTime] RSpec elapsed time: 14 minutes 16.65 seconds. Current RSS: ~1512M. load average: 1.92 1.85 1.43 2/397 5374963 when rendering the preview964 renders content with CommonMark965# [RSpecRunTime] RSpec elapsed time: 14 minutes 50.28 seconds. Current RSS: ~1508M. load average: 1.83 1.84 1.44 1/388 5381966 visit blob edit967 redirects to sign in and returns968 as developer969 redirects to sign in and returns970# [RSpecRunTime] RSpec elapsed time: 14 minutes 56.45 seconds. Current RSS: ~1514M. load average: 2.02 1.88 1.45 1/389 5393971 as guest972 redirects to sign in and returns973# [RSpecRunTime] RSpec elapsed time: 15 minutes 2.92 seconds. Current RSS: ~1523M. load average: 2.09 1.89 1.46 1/393 5428974 as developer975 on some branch976 shows blob editor with same branch977# [RSpecRunTime] RSpec elapsed time: 15 minutes 6.15 seconds. Current RSS: ~1516M. load average: 2.17 1.91 1.47 1/394 5447978 with protected branch979 shows blob editor with patch branch980# [RSpecRunTime] RSpec elapsed time: 15 minutes 9.08 seconds. Current RSS: ~1515M. load average: 2.17 1.91 1.47 1/394 5463981 as maintainer982 shows blob editor with same branch983# [RSpecRunTime] RSpec elapsed time: 15 minutes 12.45 seconds. Current RSS: ~1515M. load average: 2.23 1.93 1.48 1/393 5475984# [RSpecRunTime] Finishing example group spec/features/projects/blobs/edit_spec.rb. It took 1 minute 50.36 seconds. Expected to take 1 minute 45.1 seconds.985# [RSpecRunTime] Starting example group spec/features/merge_request/user_sees_diff_spec.rb. Expected to take 1 minute 31.24 seconds.986Merge request > User sees diff987 when linking to note988 with unresolved note989 shows expanded note990# [RSpecRunTime] RSpec elapsed time: 15 minutes 22.49 seconds. Current RSS: ~1531M. load average: 2.49 2.00 1.51 1/392 5582991 when linking to a line992 shows the linked line993# [RSpecRunTime] RSpec elapsed time: 15 minutes 31.05 seconds. Current RSS: ~1523M. load average: 2.50 2.02 1.52 1/393 5690994 when merge request has overflow995 displays warning996# [RSpecRunTime] RSpec elapsed time: 15 minutes 37.35 seconds. Current RSS: ~1532M. load average: 2.38 2.00 1.51 1/393 5762997 when editing file998 as author999 contains direct edit link1000# [RSpecRunTime] RSpec elapsed time: 15 minutes 46.67 seconds. Current RSS: ~1554M. load average: 2.24 1.98 1.51 1/394 58401001 when file contains html1002 escapes any HTML special characters in the diff chunk header1003# [RSpecRunTime] RSpec elapsed time: 16 minutes 21.58 seconds. Current RSS: ~1514M. load average: 1.66 1.87 1.49 1/389 59571004 when file is stored in LFS1005 when LFS is enabled on the project1006 when file is an image1007 shows an error message1008# [RSpecRunTime] RSpec elapsed time: 16 minutes 29.8 seconds. Current RSS: ~1536M. load average: 1.61 1.86 1.49 1/396 60671009 when file is not an image1010 shows an error message1011# [RSpecRunTime] RSpec elapsed time: 16 minutes 37.86 seconds. Current RSS: ~1536M. load average: 1.91 1.91 1.51 1/396 61751012 when LFS is not enabled1013 displays the diff1014# [RSpecRunTime] RSpec elapsed time: 16 minutes 45.89 seconds. Current RSS: ~1540M. load average: 1.92 1.91 1.52 1/396 62811015# [RSpecRunTime] Finishing example group spec/features/merge_request/user_sees_diff_spec.rb. It took 1 minute 33.45 seconds. Expected to take 1 minute 31.24 seconds.1016# [RSpecRunTime] Starting example group spec/features/merge_request/user_closes_reopens_merge_request_state_spec.rb. Expected to take 1 minute 9.64 seconds.1017User closes/reopens a merge request1018 when open1019 when clicking the top `Close merge request` button1020 closes the merge request1021# [RSpecRunTime] RSpec elapsed time: 17 minutes 6.65 seconds. Current RSS: ~1503M. load average: 1.81 1.89 1.52 1/399 63601022 when clicking the bottom `Close merge request` button1023 closes the merge request1024# [RSpecRunTime] RSpec elapsed time: 17 minutes 17.14 seconds. Current RSS: ~1510M. load average: 1.98 1.92 1.54 1/397 64081025 when closed1026 when clicking the top `Reopen merge request` button1027 reopens the merge request1028# [RSpecRunTime] RSpec elapsed time: 17 minutes 36.33 seconds. Current RSS: ~1509M. load average: 2.09 1.95 1.55 1/399 65101029 when clicking the bottom `Reopen merge request` button1030 reopens the merge request1031# [RSpecRunTime] RSpec elapsed time: 17 minutes 46.64 seconds. Current RSS: ~1518M. load average: 1.92 1.91 1.54 1/398 65901032# [RSpecRunTime] Finishing example group spec/features/merge_request/user_closes_reopens_merge_request_state_spec.rb. It took 1 minute 0.73 second. Expected to take 1 minute 9.64 seconds.1033# [RSpecRunTime] Starting example group spec/features/discussion_comments/commit_spec.rb. Expected to take 58.85 seconds.1034Thread Comments Commit1035 has class .js-note-emoji1036 behaves like thread comments for commit and snippet1037 clicking "Comment" will post a comment1038 when the toggle is clicked1039 has a "Comment" item (selected by default) and "Start thread" item1040 closes the menu when clicking the toggle or body1041 when selecting "Start thread"1042 updates the submit button text and closes the dropdown1043 creating a thread1044 clicking "Start thread" will post a thread1045# [RSpecRunTime] RSpec elapsed time: 18 minutes 24.02 seconds. Current RSS: ~1529M. load average: 1.95 1.92 1.56 1/396 69381046 when opening the menu1047 has "Start thread" selected1048 when selecting "Comment"1049 updates the submit button text and closes the dropdown1050# [RSpecRunTime] RSpec elapsed time: 18 minutes 36.0 seconds. Current RSS: ~1533M. load average: 1.95 1.93 1.57 1/396 70531051# [RSpecRunTime] Finishing example group spec/features/discussion_comments/commit_spec.rb. It took 49.38 seconds. Expected to take 58.85 seconds.1052# [RSpecRunTime] Starting example group spec/features/reportable_note/snippets_spec.rb. Expected to take 50.2 seconds.1053Reportable note on snippets1054 on project snippet1055 behaves like reportable note1056 has an edit button1057 has a `More actions` dropdown1058 dropdown has Report and Delete links1059 report button links to a report page1060# [RSpecRunTime] RSpec elapsed time: 19 minutes 20.51 seconds. Current RSS: ~1510M. load average: 2.09 1.97 1.60 1/394 75591061# [RSpecRunTime] Finishing example group spec/features/reportable_note/snippets_spec.rb. It took 44.48 seconds. Expected to take 50.2 seconds.1062# [RSpecRunTime] Starting example group spec/features/user_can_display_performance_bar_spec.rb. Expected to take 44.57 seconds.1063User can display performance bar1064 when user is logged-out1065 when the performance_bar feature is disabled1066 behaves like performance bar cannot be displayed1067 does not show the performance bar by default1068 when user press `pb`1069 does not show the performance bar by default1070# [RSpecRunTime] RSpec elapsed time: 19 minutes 24.8 seconds. Current RSS: ~1501M. load average: 2.00 1.95 1.59 1/393 75601071 when the performance_bar feature is enabled1072 behaves like performance bar cannot be displayed1073 does not show the performance bar by default1074 when user press `pb`1075 does not show the performance bar by default1076# [RSpecRunTime] RSpec elapsed time: 19 minutes 28.73 seconds. Current RSS: ~1501M. load average: 1.92 1.93 1.59 1/388 75611077 when user is logged-in1078 when the performance_bar feature is disabled1079 behaves like performance bar cannot be displayed1080 does not show the performance bar by default1081 when user press `pb`1082 does not show the performance bar by default1083# [RSpecRunTime] RSpec elapsed time: 19 minutes 34.73 seconds. Current RSS: ~1497M. load average: 1.85 1.92 1.59 1/389 75631084 behaves like performance bar is enabled by default in development1085 shows the performance bar by default1086# [RSpecRunTime] RSpec elapsed time: 19 minutes 38.76 seconds. Current RSS: ~1488M. load average: 1.78 1.90 1.58 1/389 75641087 when the performance_bar feature is enabled1088 does not show Stats link by default1089 behaves like performance bar is enabled by default in development1090 shows the performance bar by default1091# [RSpecRunTime] RSpec elapsed time: 19 minutes 47.59 seconds. Current RSS: ~1485M. load average: 1.81 1.91 1.59 1/388 75651092 behaves like performance bar can be displayed1093 does not show the performance bar by default1094 when user press `pb`1095 shows the performance bar1096# [RSpecRunTime] RSpec elapsed time: 19 minutes 53.64 seconds. Current RSS: ~1486M. load average: 1.74 1.89 1.58 1/388 75661097 when GITLAB_PERFORMANCE_BAR_STATS_URL environment variable is set1098 shows Stats link1099# [RSpecRunTime] RSpec elapsed time: 19 minutes 57.74 seconds. Current RSS: ~1489M. load average: 1.68 1.88 1.58 1/388 75671100 flamegraphs1101 when user has access1102 renders flamegraph when requested1103# [RSpecRunTime] RSpec elapsed time: 20 minutes 0.79 second. Current RSS: ~1486M. load average: 1.68 1.88 1.58 1/388 75681104 when user does not have access1105 renders the original page1106# [RSpecRunTime] RSpec elapsed time: 20 minutes 2.38 seconds. Current RSS: ~1486M. load average: 1.63 1.86 1.58 1/387 75691107# [RSpecRunTime] Finishing example group spec/features/user_can_display_performance_bar_spec.rb. It took 41.88 seconds. Expected to take 44.57 seconds.1108# [RSpecRunTime] Starting example group spec/features/merge_request/user_views_open_merge_request_spec.rb. Expected to take 37.13 seconds.1109User views an open merge request1110 when a merge request does not have repository1111 renders both the title and the description1112 has reviewers in sidebar1113# [RSpecRunTime] RSpec elapsed time: 20 minutes 6.22 seconds. Current RSS: ~1520M. load average: 1.58 1.85 1.57 1/393 76631114 when a merge request has repository1115 when rendering description preview1116 renders empty description preview1117 renders description preview1118# [RSpecRunTime] RSpec elapsed time: 20 minutes 16.39 seconds. Current RSS: ~1533M. load average: 1.63 1.85 1.58 1/392 77561119 when the branch is rebased on the target1120 does not show diverged commits count1121# [RSpecRunTime] RSpec elapsed time: 20 minutes 26.4 seconds. Current RSS: ~1528M. load average: 1.54 1.82 1.57 1/395 78211122 when the assignee's availability set1123 exposes the availability in the data-availability attribute1124# [RSpecRunTime] RSpec elapsed time: 20 minutes 33.93 seconds. Current RSS: ~1517M. load average: 1.57 1.83 1.57 1/398 78911125 when user preferred language has changed1126 renders edit button in preferred language1127# [RSpecRunTime] RSpec elapsed time: 20 minutes 37.69 seconds. Current RSS: ~1515M. load average: 1.53 1.81 1.57 1/398 79411128# [RSpecRunTime] Finishing example group spec/features/merge_request/user_views_open_merge_request_spec.rb. It took 35.32 seconds. Expected to take 37.13 seconds.1129# [RSpecRunTime] Starting example group spec/features/projects/blobs/blob_line_permalink_updater_spec.rb. Expected to take 33.74 seconds.1130Blob button line permalinks (BlobLinePermalinkUpdater)1131 On a file(blob)1132 Click "Permalink" button1133 works with no initial line number fragment hash1134 maintains intitial fragment hash1135 changes fragment hash if line number clicked1136 with initial fragment hash, changes fragment hash if line number clicked1137# [RSpecRunTime] RSpec elapsed time: 20 minutes 54.0 seconds. Current RSS: ~1531M. load average: 1.55 1.80 1.57 1/397 81971138 Click "Blame" button1139 works with no initial line number fragment hash1140 maintains intitial fragment hash1141 changes fragment hash if line number clicked1142 with initial fragment hash, changes fragment hash if line number clicked1143# [RSpecRunTime] RSpec elapsed time: 21 minutes 9.92 seconds. Current RSS: ~1525M. load average: 1.71 1.83 1.58 1/398 84461144# [RSpecRunTime] Finishing example group spec/features/projects/blobs/blob_line_permalink_updater_spec.rb. It took 32.22 seconds. Expected to take 33.74 seconds.1145# [RSpecRunTime] Starting example group spec/features/admin/admin_hooks_spec.rb. Expected to take 27.64 seconds.1146Admin::Hooks1147 GET /admin/hooks1148 is ok1149 has hooks list1150 renders plugins list as well1151# [RSpecRunTime] RSpec elapsed time: 21 minutes 15.53 seconds. Current RSS: ~1516M. load average: 1.81 1.85 1.59 1/398 84481152 New Hook1153 adds new hook1154 Update existing hook1155 updates existing hook1156# [RSpecRunTime] RSpec elapsed time: 21 minutes 17.56 seconds. Current RSS: ~1526M. load average: 1.75 1.84 1.59 1/396 84491157 Remove existing hook1158 removes existing hook1159 from hooks list page1160 from hook edit page1161# [RSpecRunTime] RSpec elapsed time: 21 minutes 27.67 seconds. Current RSS: ~1499M. load average: 1.63 1.81 1.58 1/393 84511162 Test1163 is expected to have current path "/admin/hooks"1164# [RSpecRunTime] RSpec elapsed time: 21 minutes 31.75 seconds. Current RSS: ~1500M. load average: 1.66 1.81 1.58 6/393 84521165 Merge request hook1166 New Hook1167 adds new hook1168# [RSpecRunTime] RSpec elapsed time: 21 minutes 32.96 seconds. Current RSS: ~1506M. load average: 1.66 1.81 1.58 1/393 84531169 Test1170 succeeds if the user has a repository with a merge request1171# [RSpecRunTime] RSpec elapsed time: 21 minutes 39.19 seconds. Current RSS: ~1514M. load average: 1.61 1.80 1.58 1/398 84991172# [RSpecRunTime] Finishing example group spec/features/admin/admin_hooks_spec.rb. It took 29.27 seconds. Expected to take 27.64 seconds.1173# [RSpecRunTime] Starting example group spec/features/boards/reload_boards_on_browser_back_spec.rb. Expected to take 24.83 seconds.1174Ensure Boards do not show stale data on browser back1175 authorized user1176 created issue is listed on board1177# [RSpecRunTime] RSpec elapsed time: 22 minutes 2.68 seconds. Current RSS: ~1502M. load average: 1.74 1.81 1.59 1/390 85091178# [RSpecRunTime] Finishing example group spec/features/boards/reload_boards_on_browser_back_spec.rb. It took 23.48 seconds. Expected to take 24.83 seconds.1179# [RSpecRunTime] Starting example group spec/features/issues/filtered_search/dropdown_hint_spec.rb. Expected to take 20.21 seconds.1180Dropdown hint1181 when user not logged in1182 does not exist my-reaction dropdown item1183# [RSpecRunTime] RSpec elapsed time: 22 minutes 7.13 seconds. Current RSS: ~1496M. load average: 1.68 1.80 1.59 1/390 85111184 when user logged in1185 behavior1186 opens when the search bar is first focused1187# [RSpecRunTime] RSpec elapsed time: 22 minutes 11.27 seconds. Current RSS: ~1496M. load average: 1.63 1.79 1.58 1/390 85131188 filtering1189 filters with text1190# [RSpecRunTime] RSpec elapsed time: 22 minutes 15.12 seconds. Current RSS: ~1495M. load average: 1.63 1.79 1.58 1/390 85161191 selecting from dropdown with no input1192 opens the token dropdown when you click on it1193# [RSpecRunTime] RSpec elapsed time: 22 minutes 19.33 seconds. Current RSS: ~1497M. load average: 1.58 1.77 1.58 1/390 85191194 reselecting from dropdown1195 reuses existing token text1196# [RSpecRunTime] RSpec elapsed time: 22 minutes 23.74 seconds. Current RSS: ~1498M. load average: 1.53 1.76 1.58 1/391 85221197# [RSpecRunTime] Finishing example group spec/features/issues/filtered_search/dropdown_hint_spec.rb. It took 21.07 seconds. Expected to take 20.21 seconds.1198# [RSpecRunTime] Starting example group spec/features/projects/settings/forked_project_settings_spec.rb. Expected to take 18.37 seconds.1199Projects > Settings > For a forked project1200 behaves like project settings for a forked projects1201 allows deleting the link to the forked project1202# [RSpecRunTime] RSpec elapsed time: 22 minutes 32.13 seconds. Current RSS: ~1495M. load average: 1.53 1.75 1.58 1/391 85271203 when the original project is deleted1204 behaves like project settings for a forked projects1205 allows deleting the link to the forked project1206# [RSpecRunTime] RSpec elapsed time: 22 minutes 40.17 seconds. Current RSS: ~1490M. load average: 1.81 1.81 1.59 1/390 85311207# [RSpecRunTime] Finishing example group spec/features/projects/settings/forked_project_settings_spec.rb. It took 16.42 seconds. Expected to take 18.37 seconds.1208# [RSpecRunTime] Starting example group spec/features/issues/user_sees_empty_state_spec.rb. Expected to take 14.12 seconds.1209Issues > User sees empty state1210 while user is signed out1211 empty state1212 user sees empty state1213 behaves like empty state with filters1214 user sees empty state with filters1215# [RSpecRunTime] RSpec elapsed time: 22 minutes 46.4 seconds. Current RSS: ~1487M. load average: 2.08 1.86 1.61 1/390 85331216 while user is signed in1217 empty state1218 user sees empty state1219 behaves like empty state with filters1220 user sees empty state with filters1221# [RSpecRunTime] RSpec elapsed time: 22 minutes 53.08 seconds. Current RSS: ~1493M. load average: 2.00 1.85 1.61 1/391 85381222# [RSpecRunTime] Finishing example group spec/features/issues/user_sees_empty_state_spec.rb. It took 12.91 seconds. Expected to take 14.12 seconds.1223# [RSpecRunTime] Starting example group spec/features/uploads/user_uploads_avatar_to_profile_spec.rb. Expected to take 12.38 seconds.1224User uploads avatar to profile1225 with "edit_user_profile_vue" turned on1226 behaves like upload avatar1227 shows the new avatar immediately in the header and setting sidebar1228# [RSpecRunTime] RSpec elapsed time: 22 minutes 58.11 seconds. Current RSS: ~1481M. load average: 1.92 1.83 1.61 1/390 85401229 with "edit_user_profile_vue" turned off1230 they see their new avatar on their profile1231 behaves like upload avatar1232 shows the new avatar immediately in the header and setting sidebar1233# [RSpecRunTime] RSpec elapsed time: 23 minutes 5.81 seconds. Current RSS: ~1478M. load average: 1.84 1.82 1.60 1/390 85471234# [RSpecRunTime] Finishing example group spec/features/uploads/user_uploads_avatar_to_profile_spec.rb. It took 12.74 seconds. Expected to take 12.38 seconds.1235# [RSpecRunTime] Starting example group spec/features/groups/milestones/milestone_showing_spec.rb. Expected to take 10.8 seconds.1236Group milestone1237 behaves like milestone with interactive markdown task list items in description1238 renders task list in description1239 allows interaction with task list item checkboxes1240# [RSpecRunTime] RSpec elapsed time: 23 minutes 15.29 seconds. Current RSS: ~1486M. load average: 1.71 1.79 1.60 1/390 85491241# [RSpecRunTime] Finishing example group spec/features/groups/milestones/milestone_showing_spec.rb. It took 9.47 seconds. Expected to take 10.8 seconds.1242# [RSpecRunTime] Starting example group spec/features/projects/deploy_keys_spec.rb. Expected to take 9.54 seconds.1243Project deploy keys1244 editing key1245 shows fingerprints1246# [RSpecRunTime] RSpec elapsed time: 23 minutes 18.97 seconds. Current RSS: ~1485M. load average: 1.65 1.78 1.59 1/390 85601247 removing key1248 removes association between project and deploy key1249# [RSpecRunTime] RSpec elapsed time: 23 minutes 23.42 seconds. Current RSS: ~1496M. load average: 1.84 1.82 1.61 1/389 85681250# [RSpecRunTime] Finishing example group spec/features/projects/deploy_keys_spec.rb. It took 8.14 seconds. Expected to take 9.54 seconds.1251# [RSpecRunTime] Starting example group spec/features/groups/import_export/export_file_spec.rb. Expected to take 8.09 seconds.1252Group Export1253 when the signed in user has the required permission level1254 allows the user to export the group1255# [RSpecRunTime] RSpec elapsed time: 23 minutes 30.27 seconds. Current RSS: ~1494M. load average: 2.10 1.87 1.63 1/392 85771256 when the signed in user does not have the required permission level1257 does not let the user export the group1258# [RSpecRunTime] Finishing example group spec/features/groups/import_export/export_file_spec.rb. It took 7.44 seconds. Expected to take 8.09 seconds.1259# [RSpecRunTime] Starting example group spec/features/issues/filtered_search/dropdown_label_spec.rb. Expected to take 6.3 seconds.1260Dropdown label1261 behavior1262 loads all the labels when opened1263# [RSpecRunTime] RSpec elapsed time: 23 minutes 37.14 seconds. Current RSS: ~1488M. load average: 2.01 1.86 1.62 1/390 85801264# [RSpecRunTime] Finishing example group spec/features/issues/filtered_search/dropdown_label_spec.rb. It took 6.28 seconds. Expected to take 6.3 seconds.1265# [RSpecRunTime] Starting example group spec/features/commits/user_view_commits_spec.rb. Expected to take 6.25 seconds.1266Commit > User view commits1267 Commits List1268 when project is public1269 behaves like can view commits1270 lists the correct number of commits1271# [RSpecRunTime] RSpec elapsed time: 23 minutes 40.08 seconds. Current RSS: ~1512M. load average: 2.01 1.86 1.62 1/391 85981272 when project is public with private repository1273 and user is an inherited member from the group1274 and user is a guest1275 behaves like can view commits1276 lists the correct number of commits1277# [RSpecRunTime] RSpec elapsed time: 23 minutes 41.99 seconds. Current RSS: ~1528M. load average: 1.93 1.84 1.62 1/391 86161278 when project is private1279 and user is an inherited member from the group1280 and user is a guest1281 renders not found1282# [RSpecRunTime] RSpec elapsed time: 23 minutes 43.12 seconds. Current RSS: ~1530M. load average: 1.93 1.84 1.62 1/392 86291283# [RSpecRunTime] Finishing example group spec/features/commits/user_view_commits_spec.rb. It took 5.98 seconds. Expected to take 6.25 seconds.1284# [RSpecRunTime] Starting example group spec/features/milestones/user_edits_milestone_spec.rb. Expected to take 4.34 seconds.1285User edits milestone1286 shows the right start date and due date1287# [RSpecRunTime] Finishing example group spec/features/milestones/user_edits_milestone_spec.rb. It took 3.75 seconds. Expected to take 4.34 seconds.1288# [RSpecRunTime] RSpec elapsed time: 23 minutes 47.02 seconds. Current RSS: ~1526M. load average: 1.85 1.83 1.62 1/393 86311289# [RSpecRunTime] Starting example group spec/features/groups/dependency_proxy_for_containers_spec.rb. Expected to take 2.97 seconds.1290Group Dependency Proxy for containers1291Capybara starting Puma...1292* Version 6.4.0, codename: The Eagle of Durango1293* Min threads: 0, max threads: 41294* Listening on http://127.0.0.1:427651295 fetching a blob1296 when the blob is cached for the group1297 behaves like responds with the file1298 sends file1299 when the blob must be downloaded1300 behaves like responds with the file1301 sends file1302 behaves like caches the file1303 caches the file1304# [RSpecRunTime] Finishing example group spec/features/groups/dependency_proxy_for_containers_spec.rb. It took 2.22 seconds. Expected to take 2.97 seconds.1305# [RSpecRunTime] Starting example group spec/features/users/confirmation_spec.rb. Expected to take 0.91 second.1306User confirmation1307 resend confirmation instructions1308 when recaptcha is enabled1309 renders recaptcha1310 when recaptcha is not enabled1311 does not render recaptcha1312# [RSpecRunTime] Finishing example group spec/features/users/confirmation_spec.rb. It took 0.57 second. Expected to take 0.91 second.1313auto_explain log contains 1059 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-system-pg14-9-32.339.main.ndjson.gz1314took 17.6868024971315auto_explain log contains 1060 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-system-pg14-9-32.339.ci.ndjson.gz1316took 12.8054513111317[TEST PROF INFO] EventProf results for sql.active_record1318Total time: 04:42.859 of 23:45.144 (19.85%)1319Total events: 1174601320Top 5 slowest suites (by time):1321Merge request... merge request (./spec/features/merge_request/user_sees_merge_request_pipelines_spec.rb:5) – 02:01.777 (48071 / 20) of 07:36.032 (26.7%)1322Merge request > Batch comments (./spec/features/merge_request/batch_comments_spec.rb:5) – 00:45.015 (17829 / 12) of 03:14.234 (23.18%)1323Protected Branches (./spec/features/protected_branches_spec.rb:5) – 00:20.148 (9646 / 18) of 02:27.281 (13.68%)1324User closes/r... merge request (./spec/features/merge_request/user_closes_reopens_merge_request_state_spec.rb:5) – 00:18.493 (6422 / 4) of 01:00.730 (30.45%)1325Merge request > User sees diff (./spec/features/merge_request/user_sees_diff_spec.rb:5) – 00:16.806 (5627 / 8) of 01:33.449 (17.98%)1326Knapsack report was generated. Preview:1327{1328 "spec/features/merge_request/user_sees_merge_request_pipelines_spec.rb": 456.032132111,1329 "spec/features/merge_request/batch_comments_spec.rb": 194.23436529799983,1330 "spec/features/protected_branches_spec.rb": 147.28109302799976,1331 "spec/features/projects/blobs/edit_spec.rb": 110.35658706000004,1332 "spec/features/merge_request/user_sees_diff_spec.rb": 93.44931408299954,1333 "spec/features/merge_request/user_closes_reopens_merge_request_state_spec.rb": 60.73041648899925,1334 "spec/features/discussion_comments/commit_spec.rb": 49.37714891500036,1335 "spec/features/reportable_note/snippets_spec.rb": 44.48335889600003,1336 "spec/features/user_can_display_performance_bar_spec.rb": 41.87749090299985,1337 "spec/features/merge_request/user_views_open_merge_request_spec.rb": 35.31575560699912,1338 "spec/features/projects/blobs/blob_line_permalink_updater_spec.rb": 32.22056733299996,1339 "spec/features/admin/admin_hooks_spec.rb": 29.266601266999714,1340 "spec/features/boards/reload_boards_on_browser_back_spec.rb": 23.477585337000164,1341 "spec/features/issues/filtered_search/dropdown_hint_spec.rb": 21.070285726000293,1342 "spec/features/projects/settings/forked_project_settings_spec.rb": 16.417844231000345,1343 "spec/features/issues/user_sees_empty_state_spec.rb": 12.912728021000476,1344 "spec/features/uploads/user_uploads_avatar_to_profile_spec.rb": 12.737560735999978,1345 "spec/features/groups/milestones/milestone_showing_spec.rb": 9.46647376600049,1346 "spec/features/projects/deploy_keys_spec.rb": 8.136730436000107,1347 "spec/features/groups/import_export/export_file_spec.rb": 7.439905113999885,1348 "spec/features/issues/filtered_search/dropdown_label_spec.rb": 6.280037540000194,1349 "spec/features/commits/user_view_commits_spec.rb": 5.9765713950000645,1350 "spec/features/milestones/user_edits_milestone_spec.rb": 3.748097880000387,1351 "spec/features/groups/dependency_proxy_for_containers_spec.rb": 2.2231846860004225,1352 "spec/features/users/confirmation_spec.rb": 0.56703920299969471353}1354Knapsack global time execution for tests: 23m 45s1355Finished in 24 minutes 21 seconds (files took 49.84 seconds to load)1356158 examples, 0 failures1357Randomized with seed 352051358Failed to write to log, write log/workhorse-test.log: file already closed1359[TEST PROF INFO] Time spent in factories: 01:52.498 (7.68% of total time)1360RSpec exited with 0.1361No examples to retry, congrats!1363Running after script...1364$ echo -e "\e[0Ksection_start:`date +%s`:report_results_section[collapsed=true]\r\e[0KReport results"1376$ tooling/bin/push_job_metrics || true1377[job-metrics] Pushing job metrics file for the CI/CD job.1378[job-metrics] Pushed 4 CI job metric entries to InfluxDB.1380Not uploading cache ruby-gems-debian-bookworm-ruby-3.1-16 due to policy1382Uploading artifacts...1383auto_explain/: found 3 matching artifact files and directories 1384coverage/: found 5 matching artifact files and directories 1385WARNING: crystalball/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1386WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1387knapsack/: found 4 matching artifact files and directories 1388rspec/: found 12 matching artifact files and directories 1389WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1390log/*.log: found 19 matching artifact files and directories 1391WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6218670852/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com1392WARNING: Retrying... context=artifacts-uploader error=request redirected1393Uploading artifacts as "archive" to coordinator... 201 Created id=6218670852 responseStatus=201 Created token=glcbt-651394Uploading artifacts...1395rspec/rspec-*.xml: found 1 matching artifact files and directories 1396WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6218670852/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com1397WARNING: Retrying... context=artifacts-uploader error=request redirected1398Uploading artifacts as "junit" to coordinator... 201 Created id=6218670852 responseStatus=201 Created token=glcbt-651400Job succeeded