debian-mirror-gitlab/spec/lib/gitlab/error_tracking_spec.rb

Ignoring revisions in .git-blame-ignore-revs. Click here to bypass and see the normal blame view.

518 lines
16 KiB
Ruby
Raw Normal View History

2020-01-01 13:55:28 +05:30
# frozen_string_literal: true
require 'spec_helper'
2020-06-23 00:09:42 +05:30
require 'raven/transports/dummy'
2022-05-07 20:08:51 +05:30
require 'sentry/transport/dummy_transport'
2020-06-23 00:09:42 +05:30
2020-07-28 23:09:34 +05:30
RSpec.describe Gitlab::ErrorTracking do
2020-01-01 13:55:28 +05:30
let(:exception) { RuntimeError.new('boom') }
let(:issue_url) { 'http://gitlab.com/gitlab-org/gitlab-foss/issues/1' }
2021-04-29 21:17:54 +05:30
let(:extra) { { issue_url: issue_url, some_other_info: 'info' } }
2020-01-01 13:55:28 +05:30
2022-05-07 20:08:51 +05:30
let_it_be(:user) { create(:user) }
2021-04-17 20:07:23 +05:30
let(:sentry_payload) do
{
tags: {
program: 'test',
locale: 'en',
feature_category: 'feature_a',
correlation_id: 'cid'
},
user: {
username: user.username
},
extra: {
some_other_info: 'info',
issue_url: 'http://gitlab.com/gitlab-org/gitlab-foss/issues/1'
}
}
2020-01-01 13:55:28 +05:30
end
2021-04-17 20:07:23 +05:30
let(:logger_payload) do
{
'exception.class' => 'RuntimeError',
'exception.message' => 'boom',
'tags.program' => 'test',
'tags.locale' => 'en',
'tags.feature_category' => 'feature_a',
'tags.correlation_id' => 'cid',
'user.username' => user.username,
'extra.some_other_info' => 'info',
'extra.issue_url' => 'http://gitlab.com/gitlab-org/gitlab-foss/issues/1'
}
end
2020-07-28 23:09:34 +05:30
2022-05-07 20:08:51 +05:30
let(:raven_event) do
event = Raven.client.transport.events.last[1]
Gitlab::Json.parse(event)
end
let(:sentry_event) do
Sentry.get_current_client.transport.events.last
end
2021-04-29 21:17:54 +05:30
2020-01-01 13:55:28 +05:30
before do
2022-05-07 20:08:51 +05:30
stub_feature_flags(enable_old_sentry_integration: true)
stub_feature_flags(enable_new_sentry_integration: true)
2020-01-01 13:55:28 +05:30
stub_sentry_settings
2022-05-07 20:08:51 +05:30
allow(described_class).to receive(:sentry_configurable?) { true }
2020-01-01 13:55:28 +05:30
allow(Labkit::Correlation::CorrelationId).to receive(:current_id).and_return('cid')
2021-04-17 20:07:23 +05:30
allow(I18n).to receive(:locale).and_return('en')
2020-01-01 13:55:28 +05:30
2020-06-23 00:09:42 +05:30
described_class.configure do |config|
2022-05-07 20:08:51 +05:30
config.encoding = 'json' if config.respond_to?(:encoding=)
config.transport.transport_class = Sentry::DummyTransport if config.respond_to?(:transport)
2020-06-23 00:09:42 +05:30
end
2020-01-01 13:55:28 +05:30
end
2021-04-17 20:07:23 +05:30
around do |example|
Gitlab::ApplicationContext.with_context(user: user, feature_category: 'feature_a') do
example.run
2020-01-01 13:55:28 +05:30
end
end
2022-05-07 20:08:51 +05:30
after do
Sentry.get_current_scope.clear
end
2020-01-01 13:55:28 +05:30
describe '.track_and_raise_for_dev_exception' do
context 'when exceptions for dev should be raised' do
before do
expect(described_class).to receive(:should_raise_for_dev?).and_return(true)
end
it 'raises the exception' do
2021-04-17 20:07:23 +05:30
expect(Raven).to receive(:capture_exception).with(exception, sentry_payload)
2022-05-07 20:08:51 +05:30
expect(Sentry).to receive(:capture_exception).with(exception, sentry_payload)
2021-04-17 20:07:23 +05:30
expect do
described_class.track_and_raise_for_dev_exception(
exception,
issue_url: issue_url,
some_other_info: 'info'
)
end.to raise_error(RuntimeError, /boom/)
2020-01-01 13:55:28 +05:30
end
end
context 'when exceptions for dev should not be raised' do
before do
expect(described_class).to receive(:should_raise_for_dev?).and_return(false)
end
it 'logs the exception with all attributes passed' do
2021-04-17 20:07:23 +05:30
expect(Raven).to receive(:capture_exception).with(exception, sentry_payload)
2022-05-07 20:08:51 +05:30
expect(Sentry).to receive(:capture_exception).with(exception, sentry_payload)
2020-01-01 13:55:28 +05:30
described_class.track_and_raise_for_dev_exception(
exception,
issue_url: issue_url,
some_other_info: 'info'
)
end
it 'calls Gitlab::ErrorTracking::Logger.error with formatted payload' do
2021-04-17 20:07:23 +05:30
expect(Gitlab::ErrorTracking::Logger).to receive(:error).with(logger_payload)
2020-01-01 13:55:28 +05:30
described_class.track_and_raise_for_dev_exception(
exception,
issue_url: issue_url,
some_other_info: 'info'
)
end
end
end
describe '.track_and_raise_exception' do
it 'always raises the exception' do
2021-04-17 20:07:23 +05:30
expect(Raven).to receive(:capture_exception).with(exception, sentry_payload)
2022-05-07 20:08:51 +05:30
expect(Sentry).to receive(:capture_exception).with(exception, sentry_payload)
2020-01-01 13:55:28 +05:30
2021-04-17 20:07:23 +05:30
expect do
described_class.track_and_raise_for_dev_exception(
exception,
issue_url: issue_url,
some_other_info: 'info'
)
end.to raise_error(RuntimeError, /boom/)
2020-01-01 13:55:28 +05:30
end
it 'calls Gitlab::ErrorTracking::Logger.error with formatted payload' do
2021-04-17 20:07:23 +05:30
expect(Gitlab::ErrorTracking::Logger).to receive(:error).with(logger_payload)
2020-01-01 13:55:28 +05:30
expect do
described_class.track_and_raise_exception(
exception,
issue_url: issue_url,
some_other_info: 'info'
)
end.to raise_error(RuntimeError)
end
end
2023-03-17 16:20:25 +05:30
describe '.log_and_raise_exception' do
subject(:log_and_raise_exception) do
described_class.log_and_raise_exception(exception, extra)
end
it 'only logs and raises the exception' do
expect(Raven).not_to receive(:capture_exception)
expect(Sentry).not_to receive(:capture_exception)
expect(Gitlab::ErrorTracking::Logger).to receive(:error).with(logger_payload)
expect { log_and_raise_exception }.to raise_error(RuntimeError)
end
context 'when extra details are provided' do
let(:extra) { { test: 1, my_token: 'test' } }
it 'filters parameters' do
expect(Gitlab::ErrorTracking::Logger).to receive(:error).with(
hash_including({ 'extra.test' => 1, 'extra.my_token' => '[FILTERED]' })
)
expect { log_and_raise_exception }.to raise_error(RuntimeError)
end
end
end
2020-01-01 13:55:28 +05:30
describe '.track_exception' do
2022-05-07 20:08:51 +05:30
subject(:track_exception) do
described_class.track_exception(exception, extra)
end
2020-01-01 13:55:28 +05:30
2021-01-29 00:20:46 +05:30
before do
allow(Raven).to receive(:capture_exception).and_call_original
2022-05-07 20:08:51 +05:30
allow(Sentry).to receive(:capture_exception).and_call_original
2021-01-29 00:20:46 +05:30
allow(Gitlab::ErrorTracking::Logger).to receive(:error)
end
it 'calls Raven.capture_exception' do
track_exception
2022-05-07 20:08:51 +05:30
expect(Raven)
.to have_received(:capture_exception).with(exception, sentry_payload)
expect(Sentry)
.to have_received(:capture_exception).with(exception, sentry_payload)
2020-01-01 13:55:28 +05:30
end
it 'calls Gitlab::ErrorTracking::Logger.error with formatted payload' do
2021-01-29 00:20:46 +05:30
track_exception
2020-01-01 13:55:28 +05:30
2021-04-17 20:07:23 +05:30
expect(Gitlab::ErrorTracking::Logger).to have_received(:error).with(logger_payload)
2020-01-01 13:55:28 +05:30
end
2020-03-13 15:44:24 +05:30
context 'with filterable parameters' do
let(:extra) { { test: 1, my_token: 'test' } }
it 'filters parameters' do
2021-01-29 00:20:46 +05:30
track_exception
2020-03-13 15:44:24 +05:30
2021-04-17 20:07:23 +05:30
expect(Gitlab::ErrorTracking::Logger).to have_received(:error).with(
hash_including({ 'extra.test' => 1, 'extra.my_token' => '[FILTERED]' })
)
2020-03-13 15:44:24 +05:30
end
end
2020-01-01 13:55:28 +05:30
context 'the exception implements :sentry_extra_data' do
let(:extra_info) { { event: 'explosion', size: :massive } }
2022-05-07 20:08:51 +05:30
before do
allow(exception).to receive(:sentry_extra_data).and_return(extra_info)
end
2020-01-01 13:55:28 +05:30
it 'includes the extra data from the exception in the tracking information' do
2021-01-29 00:20:46 +05:30
track_exception
2020-01-01 13:55:28 +05:30
2021-04-17 20:07:23 +05:30
expect(Raven).to have_received(:capture_exception).with(
exception, a_hash_including(extra: a_hash_including(extra_info))
)
2022-05-07 20:08:51 +05:30
expect(Sentry).to have_received(:capture_exception).with(
exception, a_hash_including(extra: a_hash_including(extra_info))
)
2020-01-01 13:55:28 +05:30
end
end
context 'the exception implements :sentry_extra_data, which returns nil' do
2021-01-29 00:20:46 +05:30
let(:extra) { { issue_url: issue_url } }
2020-01-01 13:55:28 +05:30
2022-05-07 20:08:51 +05:30
before do
allow(exception).to receive(:sentry_extra_data).and_return(nil)
end
2020-01-01 13:55:28 +05:30
it 'just includes the other extra info' do
2021-01-29 00:20:46 +05:30
track_exception
2020-01-01 13:55:28 +05:30
2021-04-17 20:07:23 +05:30
expect(Raven).to have_received(:capture_exception).with(
exception, a_hash_including(extra: a_hash_including(extra))
)
2020-06-23 00:09:42 +05:30
2022-05-07 20:08:51 +05:30
expect(Sentry).to have_received(:capture_exception).with(
exception, a_hash_including(extra: a_hash_including(extra))
)
2021-06-08 01:23:25 +05:30
end
2021-04-29 21:17:54 +05:30
end
end
2021-06-08 01:23:25 +05:30
context 'event processors' do
2021-04-29 21:17:54 +05:30
subject(:track_exception) { described_class.track_exception(exception, extra) }
before do
allow(Raven).to receive(:capture_exception).and_call_original
2022-05-07 20:08:51 +05:30
allow(Sentry).to receive(:capture_exception).and_call_original
2021-04-29 21:17:54 +05:30
allow(Gitlab::ErrorTracking::Logger).to receive(:error)
end
context 'custom GitLab context when using Raven.capture_exception directly' do
2022-05-07 20:08:51 +05:30
subject(:track_exception) { Raven.capture_exception(exception) }
2021-04-29 21:17:54 +05:30
it 'merges a default set of tags into the existing tags' do
allow(Raven.context).to receive(:tags).and_return(foo: 'bar')
2022-05-07 20:08:51 +05:30
track_exception
2021-04-29 21:17:54 +05:30
2022-05-07 20:08:51 +05:30
expect(raven_event['tags']).to include('correlation_id', 'feature_category', 'foo', 'locale', 'program')
2021-04-29 21:17:54 +05:30
end
it 'merges the current user information into the existing user information' do
Raven.user_context(id: -1)
2022-05-07 20:08:51 +05:30
track_exception
expect(raven_event['user']).to eq('id' => -1, 'username' => user.username)
end
end
context 'custom GitLab context when using Sentry.capture_exception directly' do
subject(:track_exception) { Sentry.capture_exception(exception) }
it 'merges a default set of tags into the existing tags' do
Sentry.set_tags(foo: 'bar')
track_exception
expect(sentry_event.tags).to include(:correlation_id, :feature_category, :foo, :locale, :program)
end
it 'merges the current user information into the existing user information' do
Sentry.set_user(id: -1)
2021-04-29 21:17:54 +05:30
2022-05-07 20:08:51 +05:30
track_exception
expect(sentry_event.user).to eq(id: -1, username: user.username)
2021-04-29 21:17:54 +05:30
end
end
2020-06-23 00:09:42 +05:30
context 'with sidekiq args' do
2021-01-29 00:20:46 +05:30
context 'when the args does not have anything sensitive' do
2022-05-07 20:08:51 +05:30
let(:extra) do
{
sidekiq: {
'class' => 'PostReceive',
'args' => [
1,
{ 'id' => 2, 'name' => 'hello' },
'some-value',
'another-value'
]
}
}
end
2020-06-23 00:09:42 +05:30
2021-01-29 00:20:46 +05:30
it 'ensures extra.sidekiq.args is a string' do
track_exception
2020-06-23 00:09:42 +05:30
2021-01-29 00:20:46 +05:30
expect(Gitlab::ErrorTracking::Logger).to have_received(:error).with(
2021-04-17 20:07:23 +05:30
hash_including(
'extra.sidekiq' => {
'class' => 'PostReceive',
'args' => ['1', '{"id"=>2, "name"=>"hello"}', 'some-value', 'another-value']
}
)
)
2021-01-29 00:20:46 +05:30
end
2021-04-29 21:17:54 +05:30
it 'does not filter parameters when sending to Sentry' do
track_exception
2022-05-07 20:08:51 +05:30
expected_data = [1, { 'id' => 2, 'name' => 'hello' }, 'some-value', 'another-value']
2021-04-29 21:17:54 +05:30
2022-05-07 20:08:51 +05:30
expect(raven_event.dig('extra', 'sidekiq', 'args')).to eq(expected_data)
expect(sentry_event.extra[:sidekiq]['args']).to eq(expected_data)
2021-04-29 21:17:54 +05:30
end
2020-06-23 00:09:42 +05:30
end
2021-01-29 00:20:46 +05:30
context 'when the args has sensitive information' do
let(:extra) { { sidekiq: { 'class' => 'UnknownWorker', 'args' => ['sensitive string', 1, 2] } } }
2021-04-29 21:17:54 +05:30
it 'filters sensitive arguments before sending and logging' do
2021-01-29 00:20:46 +05:30
track_exception
2022-05-07 20:08:51 +05:30
expect(raven_event.dig('extra', 'sidekiq', 'args')).to eq(['[FILTERED]', 1, 2])
expect(sentry_event.extra[:sidekiq]['args']).to eq(['[FILTERED]', 1, 2])
2021-01-29 00:20:46 +05:30
expect(Gitlab::ErrorTracking::Logger).to have_received(:error).with(
2021-04-17 20:07:23 +05:30
hash_including(
'extra.sidekiq' => {
'class' => 'UnknownWorker',
'args' => ['[FILTERED]', '1', '2']
}
)
)
2021-01-29 00:20:46 +05:30
end
end
end
2020-06-23 00:09:42 +05:30
2021-04-29 21:17:54 +05:30
context 'when the error is a GRPC error' do
context 'when the GRPC error contains a debug_error_string value' do
let(:exception) { GRPC::DeadlineExceeded.new('unknown cause', {}, '{"hello":1}') }
2020-06-23 00:09:42 +05:30
2021-04-29 21:17:54 +05:30
it 'sets the GRPC debug error string in the Sentry event and adds a custom fingerprint' do
track_exception
2022-05-07 20:08:51 +05:30
expect(raven_event.dig('extra', 'grpc_debug_error_string')).to eq('{"hello":1}')
expect(raven_event['fingerprint']).to eq(['GRPC::DeadlineExceeded', '4:unknown cause.'])
expect(sentry_event.extra[:grpc_debug_error_string]).to eq('{"hello":1}')
expect(sentry_event.fingerprint).to eq(['GRPC::DeadlineExceeded', '4:unknown cause.'])
2021-03-08 18:12:59 +05:30
end
2021-04-29 21:17:54 +05:30
end
2021-03-08 18:12:59 +05:30
2021-04-29 21:17:54 +05:30
context 'when the GRPC error does not contain a debug_error_string value' do
let(:exception) { GRPC::DeadlineExceeded.new }
it 'does not do any processing on the event' do
track_exception
2022-05-07 20:08:51 +05:30
expect(raven_event['extra']).not_to include('grpc_debug_error_string')
expect(raven_event['fingerprint']).to eq(['GRPC::DeadlineExceeded', '4:unknown cause'])
expect(sentry_event.extra).not_to include(:grpc_debug_error_string)
expect(sentry_event.fingerprint).to eq(['GRPC::DeadlineExceeded', '4:unknown cause'])
2021-04-29 21:17:54 +05:30
end
2021-03-08 18:12:59 +05:30
end
end
2022-04-01 21:47:47 +05:30
2023-01-13 00:05:48 +05:30
context 'when exception is excluded' do
before do
stub_const('SubclassRetryError', Class.new(Gitlab::SidekiqMiddleware::RetryError))
end
['Gitlab::SidekiqMiddleware::RetryError', 'SubclassRetryError'].each do |ex|
let(:exception) { ex.constantize.new }
it "does not report #{ex} exception to Sentry" do
expect(Gitlab::ErrorTracking::Logger).to receive(:error)
track_exception
expect(Raven.client.transport.events).to eq([])
expect(Sentry.get_current_client.transport.events).to eq([])
end
end
end
2022-04-01 21:47:47 +05:30
context 'when processing invalid URI exceptions' do
let(:invalid_uri) { 'http://foo:bar' }
2022-05-07 20:08:51 +05:30
let(:raven_exception_values) { raven_event['exception']['values'] }
let(:sentry_exception_values) { sentry_event.exception.to_hash[:values] }
2022-04-01 21:47:47 +05:30
context 'when the error is a URI::InvalidURIError' do
let(:exception) do
URI.parse(invalid_uri)
rescue URI::InvalidURIError => error
error
end
it 'filters the URI from the error message' do
track_exception
2022-05-07 20:08:51 +05:30
expect(raven_exception_values).to include(
2022-04-01 21:47:47 +05:30
hash_including(
'type' => 'URI::InvalidURIError',
'value' => 'bad URI(is not URI?): [FILTERED]'
)
)
2022-05-07 20:08:51 +05:30
expect(sentry_exception_values).to include(
hash_including(
type: 'URI::InvalidURIError',
value: 'bad URI(is not URI?): [FILTERED]'
)
)
2022-04-01 21:47:47 +05:30
end
end
context 'when the error is a Addressable::URI::InvalidURIError' do
let(:exception) do
Addressable::URI.parse(invalid_uri)
rescue Addressable::URI::InvalidURIError => error
error
end
it 'filters the URI from the error message' do
track_exception
2022-05-07 20:08:51 +05:30
expect(raven_exception_values).to include(
2022-04-01 21:47:47 +05:30
hash_including(
'type' => 'Addressable::URI::InvalidURIError',
'value' => 'Invalid port number: [FILTERED]'
)
)
2022-05-07 20:08:51 +05:30
expect(sentry_exception_values).to include(
hash_including(
type: 'Addressable::URI::InvalidURIError',
value: 'Invalid port number: [FILTERED]'
)
)
2022-04-01 21:47:47 +05:30
end
end
end
2022-08-13 15:12:31 +05:30
context 'when request contains sensitive information' do
before do
Sentry.get_current_scope.set_rack_env({
'HTTP_AUTHORIZATION' => 'Bearer 123456',
'HTTP_PRIVATE_TOKEN' => 'abcdef',
'HTTP_JOB_TOKEN' => 'secret123'
})
end
it 'filters sensitive data' do
track_exception
expect(sentry_event.to_hash[:request][:headers]).to include(
'Authorization' => '[FILTERED]',
'Private-Token' => '[FILTERED]',
'Job-Token' => '[FILTERED]'
)
end
end
2021-04-29 21:17:54 +05:30
end
2023-04-23 21:23:45 +05:30
context 'Sentry performance monitoring' do
context 'when ENABLE_SENTRY_PERFORMANCE_MONITORING env is disabled' do
before do
stub_env('ENABLE_SENTRY_PERFORMANCE_MONITORING', false)
described_class.configure_sentry # Force re-initialization to reset traces_sample_rate setting
end
it 'does not set traces_sample_rate' do
expect(Sentry.get_current_client.configuration.traces_sample_rate.present?).to eq false
end
end
context 'when ENABLE_SENTRY_PERFORMANCE_MONITORING env is enabled' do
before do
stub_env('ENABLE_SENTRY_PERFORMANCE_MONITORING', true)
described_class.configure_sentry # Force re-initialization to reset traces_sample_rate setting
end
it 'sets traces_sample_rate' do
expect(Sentry.get_current_client.configuration.traces_sample_rate.present?).to eq true
end
end
end
2020-01-01 13:55:28 +05:30
end