Skip to content
Snippets Groups Projects

rspec fail-fast

Passed Started by
Michał Wielich
@michold
Log timestamps in UTC.
117:23:48Running with gitlab-runner 17.7.0~pre.103.g896916a8 (896916a8)
217:23:48 on green-4.private.runners-manager.gitlab.com/gitlab.com/gitlab-org rpvz2FF9, system ID: s_d704414ba02a
317:23:48 feature flags: FF_NETWORK_PER_BUILD:true, FF_USE_FASTZIP:true, FF_TIMESTAMPS:true
417:23:48Resolving secrets
5 17:23:48 Preparing the "docker+machine" executor 00:09
617:23:48Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.3.7-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.6-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
717:23:50Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
817:23:50Authenticating with credentials from job payload (GitLab Registry)
917:23:50Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1017:23:51Using 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 ...
1117:23:51WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.0 is already created. Ignoring.
1217:23:51WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.0 is already created. Ignoring.
1317:23:51Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.0...
1417:23:51Authenticating with credentials from job payload (GitLab Registry)
1517:23:51Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.0 ...
1617:23:51Using docker image sha256:f9eec8a3cadee4fcc9706eb472f243abd0229ef6ee5bcabc905cdc95323054d9 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.0 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:670a6e27ed3606e57d4946906eba86847a99aa5e42f9a3717af982467cdb236d ...
1717:23:52Starting service redis:6.0-alpine...
1817:23:52Pulling docker image redis:6.0-alpine ...
1917:23:52Using docker image sha256:19150f5e615e35503bf28b1e8f215ed641da1460a168f39038c754d07315d4c6 for redis:6.0-alpine with digest redis@sha256:2b35fc7d2908e25aa6aa197f97882c8a67829d3b106ad5ea5c8028f816f26aa8 ...
2017:23:53Waiting for services to be up and running (timeout 30 seconds)...
2117:23:56Authenticating with credentials from job payload (GitLab Registry)
2217:23:56Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.3.7-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.6-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
2317:23:57Using docker image sha256:a45f075f58a88b1d9b2648f62bc1d3fe67b7141448c81ee7ecb8fe8582298f50 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.3.7-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.6-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.3.7-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:f00994f76a386288e3b7cfcc4efd1c46c98d1b3f2a0eda55dd31aac29933f3a8 ...
24 17:23:57 Preparing environment 00:00
2517:23:57Running on runner-rpvz2ff9-project-278964-concurrent-0 via runner-rpvz2ff9-private-1740406346-e5493e49...
26 17:23:57 Getting source from Git repository 00:01
2717:23:58Skipping Git repository setup
2817:23:58Skipping Git checkout
2917:23:58Skipping Git submodules setup
30 17:23:58 Restoring cache 00:16
3117:23:59Checking cache for ruby-gems-debian-bookworm-ruby-3.3.7-gemfile-Gemfile-21...
3217:23:59cache.zip is up to date
3317:24:08Successfully extracted cache
34 17:24:14 Downloading artifacts 00:42
3517:24:15Downloading artifacts for clone-gitlab-repo (9229131147)...
3617:24:21Downloading artifacts from coordinator... ok host=storage.googleapis.com id=9229131147 responseStatus=200 OK token=glcbt-66
3717:24:30Downloading artifacts for compile-test-assets (9229131452)...
3817:24:33Downloading artifacts from coordinator... ok host=storage.googleapis.com id=9229131452 responseStatus=200 OK token=glcbt-66
3917:24:36Downloading artifacts for db:setup pg14 (9229131450)...
4017:24:37Downloading artifacts from coordinator... ok host=storage.googleapis.com id=9229131450 responseStatus=200 OK token=glcbt-66
4117:24:37Downloading artifacts for detect-tests (9229131497)...
4217:24:37Downloading artifacts from coordinator... ok host=storage.googleapis.com id=9229131497 responseStatus=200 OK token=glcbt-66
4317:24:37Downloading artifacts for retrieve-tests-metadata (9229131509)...
4417:24:38Downloading artifacts from coordinator... ok host=storage.googleapis.com id=9229131509 responseStatus=200 OK token=glcbt-66
4517:24:38Downloading artifacts for setup-test-env (9229131457)...
4617:24:44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=9229131457 responseStatus=200 OK token=glcbt-66
47 17:24:56 Executing "step_script" stage of the job script
4817:24:56Using docker image sha256:a45f075f58a88b1d9b2648f62bc1d3fe67b7141448c81ee7ecb8fe8582298f50 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.3.7-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.6-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.3.7-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:f00994f76a386288e3b7cfcc4efd1c46c98d1b3f2a0eda55dd31aac29933f3a8 ...
4917:24:56$ echo $FOSS_ONLY
5017:24:56$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5117:24:56$ export GOPATH=$CI_PROJECT_DIR/.go
5217:24:56$ mkdir -p $GOPATH
5317:24:56$ source scripts/utils.sh
5417:24:56$ log_disk_usage "true"
5517:24:56*******************************************************
5617:24:56This runner currently has 71G free disk space.
5717:24:56*******************************************************
58 17:24:56 Disk usage detail 00:06
5917:24:56df -h
6017:24:56Filesystem Size Used Avail Use% Mounted on
6117:24:56overlay 95G 24G 71G 26% /
6217:24:56tmpfs 64M 0 64M 0% /dev
6317:24:56shm 64M 0 64M 0% /dev/shm
6417:24:56/dev/sda1 95G 24G 71G 26% /cache
6517:24:56tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6617:24:56du -h -d 1
6717:24:567.4M ./workhorse
6817:24:56148K ./bin
6917:24:561.2M ./.gitlab
7017:24:56216M ./.git
7117:24:564.0K ./builds
7217:24:5629M ./lib
7317:24:568.0K ./.vscode
7417:24:56588K ./storybook
7517:24:5620K ./log
7617:24:562.1M ./data
7717:24:5611M ./qa
7817:24:563.0M ./.rubocop_todo
7917:24:56858M ./vendor
8017:24:57660M ./public
8117:24:5763M ./doc
8217:24:57508K ./danger
8317:24:5732K ./rspec
8417:24:57580K ./tooling
8517:24:57154M ./ee
8617:24:571.4M ./knapsack
8717:24:5768K ./patches
8817:24:575.7M ./changelogs
8917:24:5720K ./haml_lint
9017:24:5728K ./metrics_server
9117:24:57116K ./keeps
9217:24:5736K ./shared
9317:24:5744K ./generator_templates
9417:24:571.3M ./scripts
9517:24:57773M ./tmp
9617:24:57132M ./spec
9717:24:5720K ./file_hooks
9817:24:5736K ./crystalball
9917:24:5716K ./sidekiq_cluster
10017:24:574.3M ./gems
10117:24:571.2M ./fixtures
10217:24:57972K ./rubocop
10317:24:574.0K ./.go
10417:24:5736M ./db
10517:24:5712K ./.github
10617:24:5764K ./.eslint_todo
10717:24:57137M ./locale
10817:24:5720M ./config
10917:24:5789M ./app
11017:24:5720K ./.lefthook
11117:24:573.2G .
11217:24:57$ source scripts/prepare_build.sh
113 17:24:57 Installing gems 00:20
14417:25:17Using SEC decomposed database config (config/database.yml.decomposed-sec-postgresql)
14517:25:17Geo DB won't be set up.
14617:25:17Embedding DB won't be set up.
147 17:25:17 Setting up DBs 00:24
15017:25:41$ source ./scripts/rspec_helpers.sh
15117:25:41$ run_timed_command "gem install knapsack --no-document"
15217:25:41$ gem install knapsack --no-document
15317:25:41Successfully installed knapsack-4.0.0
15417:25:411 gem installed
15517:25:41==> 'gem install knapsack --no-document' succeeded in 0 seconds.
15617:25:41$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
157 17:25:41 Spawning Gitaly 00:05
16117:25:46$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
16217:25:46$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
16317:25:46$ tooling/bin/create_job_metrics_file || true
16417:25:50[job-metrics] Creating the job metrics file for the CI/CD job.
16517:25:50$ rspec_section rspec_fail_fast "${MATCHING_TESTS_PATH}" "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house --tag ~real_ai_request"
166 17:25:50 RSpec 02:46
16717:25:41Running RSpec command: bin/rspec -Ispec -rspec_helper --color --failure-exit-code 1 --error-exit-code 2 --format documentation --format Support::Formatters::JsonFormatter --out rspec/rspec-9229132014.json --format RspecJunitFormatter --out rspec/rspec-9229132014.xml --fail-fast=20 --tag ~quarantine --tag ~zoekt --tag ~click_house --tag ~real_ai_request spec/scripts/internal_events/cli_spec.rb spec/support_specs/matchers/internal_events_matchers_spec.rb
16817:27:08Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true, :real_ai_request=>true}
16917:27:08# [RSpecRunTime] Starting RSpec timer...
17017:27:08[TEST PROF INFO] EventProf enabled (sql.active_record)
17117:25:50unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
17217:27:08unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
17317:27:08unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
17417:27:17Test environment set up in 3.106798199 seconds
17517:27:18# [RSpecRunTime] Starting example group spec/scripts/internal_events/cli_spec.rb. Expected to take 3.67 seconds.
17617:27:18Cli
17717:27:18 when offline
17817:27:18 behaves like creates the right definition files
17917:27:19tput: No value for $TERM and no -T specified
18017:27:19 in scenario: Creates a new event with product stage/section/group input manually
18117:27:19 behaves like creates the right definition files
18217:27:19tput: No value for $TERM and no -T specified
18317:27:20tput: No value for $TERM and no -T specified
18417:27:20 in scenario: Creates a new metric with product stage/section/group input manually
18517:27:20# [RSpecRunTime] RSpec elapsed time: 12.2 seconds. Current RSS: ~1324M. Threads: 2. load average: 1.24 1.06 1.09 1/278 506
18617:27:20.
18717:27:20 when window size is unavailable
18817:27:20 behaves like creates the right definition files
18917:27:20 in scenario: Terminal size does not prevent file creation
19017:27:20 behaves like definition fixtures are valid
19117:27:20 for events
19217:27:20 behaves like definition fixtures are valid
19317:27:20 for metrics
19417:27:21# [RSpecRunTime] Finishing example group spec/scripts/internal_events/cli_spec.rb. It took 3.63 seconds. Expected to take 3.67 seconds.
19517:27:21# [RSpecRunTime] RSpec elapsed time: 13.74 seconds. Current RSS: ~1346M. Threads: 2. load average: 1.22 1.05 1.09 1/278 508
19617:27:21.
19717:27:21# [RSpecRunTime] Starting example group spec/support_specs/matchers/internal_events_matchers_spec.rb. Expected to take 4.49 seconds.
19817:27:21Internal Events matchers
19917:27:24 when chaining both matchers
20017:27:24 handles triggering events that increment metrics
20117:27:24 handles triggering events that do not increment metrics
20217:27:24 handles multiple triggered events and incremented metrics
20317:27:24 handles chaining multiple triggered events and incremented metrics with different args
20417:27:24 accepts other chained matchers with #and
20517:27:25# [RSpecRunTime] RSpec elapsed time: 16.84 seconds. Current RSS: ~1373M. Threads: 4. load average: 1.22 1.05 1.09 1/288 519
20617:27:25.
20717:27:25 when using the 'internal event tracking' shared example
20817:27:25 with identifiers
20917:27:25 behaves like internal event tracking
21017:27:25 logs to Snowplow, Redis, and product analytics tooling
21117:27:25 with additional properties
21217:27:25 behaves like internal event tracking
21317:27:25 logs to Snowplow, Redis, and product analytics tooling
21417:27:25 behaves like internal event tracking
21517:27:25 logs to Snowplow, Redis, and product analytics tooling
21617:27:25 behaves like internal event tracking
21717:27:25 logs to Snowplow, Redis, and product analytics tooling
21817:27:25 with incorrect value being provided in additional_properties.
21917:27:25 behaves like internal event tracking
22017:27:25 logs to Snowplow, Redis, and product analytics tooling (PENDING: This example should always fail. Protects against false positives.)
22117:27:25 behaves like internal event tracking
22217:27:25 logs to Snowplow, Redis, and product analytics tooling (PENDING: This example should always fail. Protects against false positives.)
22317:27:25 behaves like internal event tracking
22417:27:25 logs to Snowplow, Redis, and product analytics tooling (PENDING: This example should always fail. Protects against false positives.)
22517:27:25 :increment_usage_metrics
22617:27:27 handles database-based metrics
22717:27:27 accepts chained metric counts like #change
22817:27:27 handles non-integer values [ALPHA] (not strictly supported)
22917:27:27 behaves like matcher and negated matcher both raise expected error
23017:27:27 is expected to raise ArgumentError with "increment_usage_metrics matcher requires key_paths argument"
23117:27:27# [RSpecRunTime] RSpec elapsed time: 19.49 seconds. Current RSS: ~1379M. Threads: 4. load average: 1.20 1.05 1.09 1/289 521
23217:27:27.
23317:27:27 behaves like matcher and negated matcher both raise expected error
23417:27:27 is expected to raise ArgumentError with "Cannot find metric definition for 'redis_hll_counters.bad_metric_name'!"
23517:27:27 behaves like matcher and negated matcher both raise expected error
23617:27:27 is expected to raise ArgumentError with "Cannot find metric definition for 'g_edit_by_sfe' -- did you mean 'redis_hll_counters.ide_edit.g_edit_by_sfe_monthly'?"
23717:27:27 when :increment_usage_metrics should fail
23817:27:27 with a single metric failure
23917:27:27 returns a meaningful failure message for :increment_usage_metrics
24017:27:27 with a multiple metric failures
24117:27:27 returns a meaningful failure message for :increment_usage_metrics
24217:27:27 with a multiple metric failures across chained assertions
24317:27:27 returns a meaningful failure message for :increment_usage_metrics
24417:27:27 when :not_increment_usage_metrics should fail
24517:27:27 with a single metric failure
24617:27:28 returns a meaningful failure message for :increment_usage_metrics
24717:27:28 with a multiple metric failures
24817:27:28 returns a meaningful failure message for :increment_usage_metrics
24917:27:28 with a multiple metric failures across chained assertions
25017:27:28 returns a meaningful failure message for :increment_usage_metrics
25117:27:28 :trigger_internal_events
25217:27:28 when testing HTML with data attributes
25317:27:28 html_input: rendered_html
25417:27:28 when using positive matcher
25517:27:28 matches elements with correct tracking attribute
25617:27:28 raises error when multiple events are provided
25717:27:28 raises error when using incompatible chain methods
25817:27:28 with incorrect tracking attribute
25917:27:28 does not match elements
26017:27:28 with non existing tracking event
26117:27:28 does not match elements
26217:27:28 with additional properties
26317:27:28 matches elements
26417:27:28 with tracking-load attribute
26517:27:28 matches elements
26617:27:28 when using negated matcher
26717:27:28 matches elements without tracking attribute
26817:27:28 raises error when passing events to negated matcher
26917:27:28 raises error when using chain methods with negated matcher
27017:27:28 html_input: capybara_node
27117:27:28 when using positive matcher
27217:27:28 matches elements with correct tracking attribute
27317:27:28 raises error when multiple events are provided
27417:27:28 raises error when using incompatible chain methods
27517:27:28 with incorrect tracking attribute
27617:27:28 does not match elements
27717:27:28 with non existing tracking event
27817:27:28 does not match elements
27917:27:28 with additional properties
28017:27:28 matches elements
28117:27:28 with tracking-load attribute
28217:27:28 matches elements
28317:27:28 when using negated matcher
28417:27:28 matches elements without tracking attribute
28517:27:28 raises error when passing events to negated matcher
28617:27:29 raises error when using chain methods with negated matcher
28717:27:29 with backend events
28817:27:29 raises error if no events are passed to :trigger_internal_events
28917:27:29 does not raises error if no events are passed to :not_trigger_internal_events
29017:27:29 bubbles up failure messages
29117:27:29 bubbles up failure messages for negated matcher
29217:27:29 handles events that should not be triggered
29317:27:29 ignores extra/irrelevant triggered events
29417:27:29 accepts chained event counts like #receive for multiple different events
29517:27:29 behaves like matcher and negated matcher both raise expected error
29617:27:29 is expected to raise ArgumentError with "Unknown event 'bad_event_name'! trigger_internal_events matcher accepts only existing events"
29717:27:29 with additional properties
29817:27:29 accepts correct additional properties
29917:27:29 with extra attributes
30017:27:29 accepts correct extra attributes
30117:27:29 with wrong label value
30217:27:29 behaves like raises error for unexpected event args
30317:27:29 is expected to raise RSpec::Expectations::ExpectationNotMetError with message matching /received :event with unexpected arguments/
30417:27:29 with extra attributes expected but not tracked
30517:27:29 behaves like raises error for unexpected event args
30617:27:29 is expected to raise RSpec::Expectations::ExpectationNotMetError with message matching /received :event with unexpected arguments/
30717:27:29 with extra attributes tracked but not expected
30817:27:29 behaves like raises error for unexpected event args
30917:27:29 is expected to raise RSpec::Expectations::ExpectationNotMetError with message matching /received :event with unexpected arguments/
31017:27:29# [RSpecRunTime] Finishing example group spec/support_specs/matchers/internal_events_matchers_spec.rb. It took 7.97 seconds. Expected to take 4.49 seconds.
31117:27:30auto_explain log contains 893 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-fail-fast.343.main.ndjson.gz
31217:27:30took 0.49346616
31317:27:31auto_explain log contains 894 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-fail-fast.343.ci.ndjson.gz
31417:27:31took 0.49256552
31517:27:31auto_explain log contains 894 entries for sec, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-fail-fast.343.sec.ndjson.gz
31617:27:31took 0.433491474
31717:27:31[TEST PROF INFO] EventProf results for sql.active_record
31817:27:31Total time: 00:02.195 of 00:11.639 (18.86%)
31917:27:31Total events: 966
32017:27:31Top 5 slowest suites (by time):
32117:27:31Internal Events matchers (./spec/support_specs/matchers/internal_events_matchers_spec.rb:5) – 00:02.105 (924 / 57) of 00:07.969 (26.43%)
32217:27:31Cli (./spec/scripts/internal_events/cli_spec.rb:8) – 00:00.089 (42 / 5) of 00:03.670 (2.43%)
32317:27:31Pending: (Failures listed here are expected and do not affect your suite's status)
32417:27:31 1) Internal Events matchers when using the 'internal event tracking' shared example with additional properties with incorrect value being provided in additional_properties. behaves like internal event tracking logs to Snowplow, Redis, and product analytics tooling
32517:27:31 # This example should always fail. Protects against false positives.
32617:27:31 Got 1 failure:
32717:27:31 Shared Example Group: "internal event tracking" called from ./spec/support_specs/matchers/internal_events_matchers_spec.rb:527
32817:27:31 1.1) Failure/Error:
32917:27:31 expect { subject }
33017:27:31 .to trigger_internal_events(event)
33117:27:31 .with(expected_attributes)
33217:27:31 .and increment_usage_metrics(*all_metrics)
33317:27:31 #<InstanceDouble(Gitlab::Tracking::Destinations::Snowplow) (anonymous)> received :event with unexpected arguments
33417:27:31 expected: ("InternalEventTracking", "push_package_to_registry", include {:context => (contain exactly (have attributes {:class => SnowplowTracker::SelfDescribingJson...", :data => (include {:event_name => "push_package_to_registry"})})})), :label => "BAD label value"})
33517:27:31 got: ("InternalEventTracking", "push_package_to_registry", {:context=>[#<SnowplowTracker::SelfDescribingJson:0x00007e0b863daf08 @schema="iglu:com.gitlab/gitlab_...:data_source=>:redis_hll, :event_name=>"push_package_to_registry"}>], :label=>"Awesome label value"})
33617:27:31 Diff:
33717:27:31 @@ -1,4 +1,7 @@
33817:27:31 ["InternalEventTracking",
33917:27:31 "push_package_to_registry",
34017:27:31 - "include {:context => (contain exactly (have attributes {:class => SnowplowTracker::SelfDescribingJson, :to_json => (include {:schema => \"ig..., :project_id => 1, :namespace_id => 1, :feature_enabled_by_namespace_ids => nil, :extra => {}})})}) and (have attributes {:class => SnowplowTracker::SelfDescribingJson, :to_json => (include {:schema => \"ig...b_service_ping/jsonschema/1-0-1\", :data => (include {:event_name => \"push_package_to_registry\"})})})), :label => \"BAD label value\"}"]
34117:27:31 + {:context=>
34217:27:31 + [#<SnowplowTracker::SelfDescribingJson:0x00007e0b863daf08 @schema="iglu:com.gitlab/gitlab_standard/jsonschema/1-1-1", @data={:environment=>"development", :source=>"gitlab-rails", :correlation_id=>"307e011e3255db8a42c01ad03d4be886", :plan=>"default", :extra=>{}, :user_id=>1, :global_user_id=>"QWe9ebiJPrQUPqG/tkOT+K7s5hny4VoaidCKveIcP+w=", :is_gitlab_team_member=>nil, :namespace_id=>1, :project_id=>1, :feature_enabled_by_namespace_ids=>nil, :realm=>"self-managed", :instance_id=>"uuid-not-set", :host_name=>"runner-rpvz2ff9-project-278964-concurrent-0", :instance_version=>"17.10.0", :context_generated_at=>Mon, 24 Feb 2025 17:27:25.578708314 UTC +00:00}>,
34317:27:31 + #<SnowplowTracker::SelfDescribingJson:0x00007e0b863d93b0 @schema="iglu:com.gitlab/gitlab_service_ping/jsonschema/1-0-1", @data={:data_source=>:redis_hll, :event_name=>"push_package_to_registry"}>],
34417:27:31 + :label=>"Awesome label value"}]
34517:27:31 # ./spec/support/shared_examples/controllers/internal_event_tracking_examples.rb:59:in `block (2 levels) in <top (required)>'
34617:27:31 # ./spec/spec_helper.rb:457:in `block (3 levels) in <top (required)>'
34717:27:31 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
34817:27:31 # ./spec/spec_helper.rb:456:in `block (2 levels) in <top (required)>'
34917:27:31 # ./spec/spec_helper.rb:451:in `block (3 levels) in <top (required)>'
35017:27:31 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
35117:27:31 # ./spec/spec_helper.rb:442:in `block (2 levels) in <top (required)>'
35217:27:31 # ./spec/spec_helper.rb:438:in `block (3 levels) in <top (required)>'
35317:27:31 # ./lib/gitlab/application_context.rb:100:in `with_raw_context'
35417:27:31 # ./spec/spec_helper.rb:438:in `block (2 levels) in <top (required)>'
35517:27:31 # ./spec/spec_helper.rb:409:in `block (3 levels) in <top (required)>'
35617:27:31 # ./lib/gitlab/ci/config/feature_flags.rb:38:in `ensure_correct_usage'
35717:27:31 # ./spec/spec_helper.rb:408:in `block (2 levels) in <top (required)>'
35817:27:31 # ./spec/spec_helper.rb:273:in `block (2 levels) in <top (required)>'
35917:27:31 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
36017:27:31 # ./spec/support/redis.rb:18:in `block (3 levels) in <top (required)>'
36117:27:31 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
36217:27:31 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
36317:27:31 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
36417:27:31 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
36517:27:31 2) Internal Events matchers when using the 'internal event tracking' shared example with additional properties with incorrect value being provided in additional_properties. behaves like internal event tracking logs to Snowplow, Redis, and product analytics tooling
36617:27:31 # This example should always fail. Protects against false positives.
36717:27:31 Got 1 failure:
36817:27:31 Shared Example Group: "internal event tracking" called from ./spec/support_specs/matchers/internal_events_matchers_spec.rb:531
36917:27:31 2.1) Failure/Error:
37017:27:31 expect { subject }
37117:27:31 .to trigger_internal_events(event)
37217:27:31 .with(expected_attributes)
37317:27:31 .and increment_usage_metrics(*all_metrics)
37417:27:31 #<InstanceDouble(Gitlab::Tracking::Destinations::Snowplow) (anonymous)> received :event with unexpected arguments
37517:27:31 expected: ("InternalEventTracking", "push_package_to_registry", include {:context => (contain exactly (have attributes {:class => SnowplowTracker::SelfDescribingJson...", :data => (include {:event_name => "push_package_to_registry"})})})), :label => "BAD label value"})
37617:27:31 got: ("InternalEventTracking", "push_package_to_registry", {:context=>[#<SnowplowTracker::SelfDescribingJson:0x00007e0b8600c750 @schema="iglu:com.gitlab/gitlab_...:data_source=>:redis_hll, :event_name=>"push_package_to_registry"}>], :label=>"Awesome label value"})
37717:27:31 Diff:
37817:27:31 @@ -1,4 +1,7 @@
37917:27:31 ["InternalEventTracking",
38017:27:31 "push_package_to_registry",
38117:27:31 - "include {:context => (contain exactly (have attributes {:class => SnowplowTracker::SelfDescribingJson, :to_json => (include {:schema => \"ig..., :project_id => 1, :namespace_id => 1, :feature_enabled_by_namespace_ids => nil, :extra => {}})})}) and (have attributes {:class => SnowplowTracker::SelfDescribingJson, :to_json => (include {:schema => \"ig...b_service_ping/jsonschema/1-0-1\", :data => (include {:event_name => \"push_package_to_registry\"})})})), :label => \"BAD label value\"}"]
38217:27:31 + {:context=>
38317:27:31 + [#<SnowplowTracker::SelfDescribingJson:0x00007e0b8600c750 @schema="iglu:com.gitlab/gitlab_standard/jsonschema/1-1-1", @data={:environment=>"development", :source=>"gitlab-rails", :correlation_id=>"044d81cc1161f316f299956c197e6e7e", :plan=>"default", :extra=>{}, :user_id=>1, :global_user_id=>"QWe9ebiJPrQUPqG/tkOT+K7s5hny4VoaidCKveIcP+w=", :is_gitlab_team_member=>nil, :namespace_id=>1, :project_id=>1, :feature_enabled_by_namespace_ids=>nil, :realm=>"self-managed", :instance_id=>"uuid-not-set", :host_name=>"runner-rpvz2ff9-project-278964-concurrent-0", :instance_version=>"17.10.0", :context_generated_at=>Mon, 24 Feb 2025 17:27:25.677562918 UTC +00:00}>,
38417:27:31 + #<SnowplowTracker::SelfDescribingJson:0x00007e0b8600b670 @schema="iglu:com.gitlab/gitlab_service_ping/jsonschema/1-0-1", @data={:data_source=>:redis_hll, :event_name=>"push_package_to_registry"}>],
38517:27:31 + :label=>"Awesome label value"}]
38617:27:31 # ./spec/support/shared_examples/controllers/internal_event_tracking_examples.rb:59:in `block (2 levels) in <top (required)>'
38717:27:31 # ./spec/spec_helper.rb:457:in `block (3 levels) in <top (required)>'
38817:27:31 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
38917:27:31 # ./spec/spec_helper.rb:456:in `block (2 levels) in <top (required)>'
39017:27:31 # ./spec/spec_helper.rb:451:in `block (3 levels) in <top (required)>'
39117:27:31 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
39217:27:31 # ./spec/spec_helper.rb:442:in `block (2 levels) in <top (required)>'
39317:27:31 # ./spec/spec_helper.rb:438:in `block (3 levels) in <top (required)>'
39417:27:31 # ./lib/gitlab/application_context.rb:100:in `with_raw_context'
39517:27:31 # ./spec/spec_helper.rb:438:in `block (2 levels) in <top (required)>'
39617:27:31 # ./spec/spec_helper.rb:409:in `block (3 levels) in <top (required)>'
39717:27:31 # ./lib/gitlab/ci/config/feature_flags.rb:38:in `ensure_correct_usage'
39817:27:31 # ./spec/spec_helper.rb:408:in `block (2 levels) in <top (required)>'
39917:27:31 # ./spec/spec_helper.rb:273:in `block (2 levels) in <top (required)>'
40017:27:31 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
40117:27:31 # ./spec/support/redis.rb:18:in `block (3 levels) in <top (required)>'
40217:27:31 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
40317:27:31 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
40417:27:31 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
40517:27:31 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
40617:27:31 3) Internal Events matchers when using the 'internal event tracking' shared example with additional properties with incorrect value being provided in additional_properties. behaves like internal event tracking logs to Snowplow, Redis, and product analytics tooling
40717:27:31 # This example should always fail. Protects against false positives.
40817:27:31 Got 1 failure:
40917:27:31 Shared Example Group: "internal event tracking" called from ./spec/support_specs/matchers/internal_events_matchers_spec.rb:535
41017:27:31 3.1) Failure/Error:
41117:27:31 expect { subject }
41217:27:31 .to trigger_internal_events(event)
41317:27:31 .with(expected_attributes)
41417:27:31 .and increment_usage_metrics(*all_metrics)
41517:27:31 #<InstanceDouble(Gitlab::Tracking::Destinations::Snowplow) (anonymous)> received :event with unexpected arguments
41617:27:31 expected: ("InternalEventTracking", "push_package_to_registry", include {:context => (contain exactly (have attributes {:class => SnowplowTracker::SelfDescribingJson...", :data => (include {:event_name => "push_package_to_registry"})})})), :label => "BAD label value"})
41717:27:31 got: ("InternalEventTracking", "push_package_to_registry", {:context=>[#<SnowplowTracker::SelfDescribingJson:0x00007e0b85cd8ae8 @schema="iglu:com.gitlab/gitlab_...:data_source=>:redis_hll, :event_name=>"push_package_to_registry"}>], :label=>"Awesome label value"})
41817:27:31 Diff:
41917:27:31 @@ -1,4 +1,7 @@
42017:27:31 ["InternalEventTracking",
42117:27:31 "push_package_to_registry",
42217:27:31 - "include {:context => (contain exactly (have attributes {:class => SnowplowTracker::SelfDescribingJson, :to_json => (include {:schema => \"ig..., :project_id => 1, :namespace_id => 1, :feature_enabled_by_namespace_ids => nil, :extra => {}})})}) and (have attributes {:class => SnowplowTracker::SelfDescribingJson, :to_json => (include {:schema => \"ig...b_service_ping/jsonschema/1-0-1\", :data => (include {:event_name => \"push_package_to_registry\"})})})), :label => \"BAD label value\"}"]
42317:27:31 + {:context=>
42417:27:31 + [#<SnowplowTracker::SelfDescribingJson:0x00007e0b85cd8ae8 @schema="iglu:com.gitlab/gitlab_standard/jsonschema/1-1-1", @data={:environment=>"development", :source=>"gitlab-rails", :correlation_id=>"a92795bed9a4acf20948deb372e575fd", :plan=>"default", :extra=>{}, :user_id=>1, :global_user_id=>"QWe9ebiJPrQUPqG/tkOT+K7s5hny4VoaidCKveIcP+w=", :is_gitlab_team_member=>nil, :namespace_id=>1, :project_id=>1, :feature_enabled_by_namespace_ids=>nil, :realm=>"self-managed", :instance_id=>"uuid-not-set", :host_name=>"runner-rpvz2ff9-project-278964-concurrent-0", :instance_version=>"17.10.0", :context_generated_at=>Mon, 24 Feb 2025 17:27:25.785664571 UTC +00:00}>,
42517:27:31 + #<SnowplowTracker::SelfDescribingJson:0x00007e0b85cd7eb8 @schema="iglu:com.gitlab/gitlab_service_ping/jsonschema/1-0-1", @data={:data_source=>:redis_hll, :event_name=>"push_package_to_registry"}>],
42617:27:31 + :label=>"Awesome label value"}]
42717:27:31 # ./spec/support/shared_examples/controllers/internal_event_tracking_examples.rb:59:in `block (2 levels) in <top (required)>'
42817:27:31 # ./spec/spec_helper.rb:457:in `block (3 levels) in <top (required)>'
42917:27:31 # ./lib/gitlab/sidekiq_sharding/validator.rb:42:in `enabled'
43017:27:31 # ./spec/spec_helper.rb:456:in `block (2 levels) in <top (required)>'
43117:27:31 # ./spec/spec_helper.rb:451:in `block (3 levels) in <top (required)>'
43217:27:31 # ./spec/support/sidekiq_middleware.rb:9:in `with_sidekiq_server_middleware'
43317:27:31 # ./spec/spec_helper.rb:442:in `block (2 levels) in <top (required)>'
43417:27:31 # ./spec/spec_helper.rb:438:in `block (3 levels) in <top (required)>'
43517:27:31 # ./lib/gitlab/application_context.rb:100:in `with_raw_context'
43617:27:31 # ./spec/spec_helper.rb:438:in `block (2 levels) in <top (required)>'
43717:27:31 # ./spec/spec_helper.rb:409:in `block (3 levels) in <top (required)>'
43817:27:31 # ./lib/gitlab/ci/config/feature_flags.rb:38:in `ensure_correct_usage'
43917:27:31 # ./spec/spec_helper.rb:408:in `block (2 levels) in <top (required)>'
44017:27:31 # ./spec/spec_helper.rb:273:in `block (2 levels) in <top (required)>'
44117:27:31 # ./spec/support/system_exit_detected.rb:7:in `block (2 levels) in <top (required)>'
44217:27:31 # ./spec/support/redis.rb:18:in `block (3 levels) in <top (required)>'
44317:27:31 # ./spec/support/fast_quarantine.rb:22:in `block (2 levels) in <top (required)>'
44417:27:31 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (3 levels) in <top (required)>'
44517:27:31 # ./spec/support/database/prevent_cross_joins.rb:60:in `with_cross_joins_prevented'
44617:27:31 # ./spec/support/database/prevent_cross_joins.rb:106:in `block (2 levels) in <top (required)>'
44717:27:31Finished in 23.64 seconds (files took 1 minute 14.26 seconds to load)
44817:27:3162 examples, 0 failures, 3 pending
44917:27:31Randomized with seed 3029
45017:27:41[TEST PROF INFO] Time spent in factories: 00:03.882 (14.3% of total time)
45117:27:41No examples to retry, congrats!
452 17:27:42 Running after_script
45317:27:42Running after script...
45417:27:42$ source scripts/utils.sh
45517:27:42$ log_disk_usage
45617:27:42*******************************************************
45717:27:42This runner currently has 70G free disk space.
45817:27:42*******************************************************
459 17:27:42 Disk usage detail 00:06
51617:27:48$ bundle exec gem list gitlab_quality-test_tooling
51717:27:50gitlab_quality-test_tooling (2.8.0)
51817:27:50$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
519 17:27:50 Report test failures 00:00
52117:27:50$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
522 17:27:50 Report test flakiness 00:00
52417:27:50$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
525 17:27:50 Report test slowness 00:00
52717:27:50$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
528 17:27:50 Report test slowness in MR note 00:02
53117:27:52$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
532 17:27:52 Report test files close to timing out 00:12
53417:27:54WARNING: after_script failed, but job will continue unaffected: exit code 1
535 17:27:54 Saving cache for successful job 00:01
53617:27:55Not uploading cache ruby-gems-debian-bookworm-ruby-3.3.7-gemfile-Gemfile-21 due to policy
537 17:27:55 Uploading artifacts for successful job 00:07
53817:27:57Uploading artifacts...
53917:27:57auto_explain/: found 4 matching artifact files and directories
54017:27:57coverage/: found 5 matching artifact files and directories
54117:27:57crystalball/: found 2 matching artifact files and directories
54217:27:57WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
54317:27:57knapsack/: found 2 matching artifact files and directories
54417:27:57rspec/: found 14 matching artifact files and directories
54517:27:57WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
54617:27:57log/*.log: found 11 matching artifact files and directories
54717:27:57WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/9229132014/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
54817:27:57WARNING: Retrying... context=artifacts-uploader error=request redirected
54917:28:00Uploading artifacts as "archive" to coordinator... 201 Created id=9229132014 responseStatus=201 Created token=glcbt-66
55017:28:00Uploading artifacts...
55117:28:00rspec/rspec-*.xml: found 1 matching artifact files and directories
55217:28:00WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/9229132014/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
55317:28:00WARNING: Retrying... context=artifacts-uploader error=request redirected
55417:28:01Uploading artifacts as "junit" to coordinator... 201 Created id=9229132014 responseStatus=201 Created token=glcbt-66
555 17:28:02 Cleaning up project directory and file based variables 00:00
55617:28:11Job succeeded