2019-07-31 22:56:46 +05:30
|
|
|
# frozen_string_literal: true
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
require 'spec_helper'
|
|
|
|
|
2020-07-28 23:09:34 +05:30
|
|
|
RSpec.describe WebHookService do
|
2019-06-05 12:25:43 +05:30
|
|
|
include StubRequests
|
|
|
|
|
2021-06-08 01:23:25 +05:30
|
|
|
let_it_be(:project) { create(:project) }
|
|
|
|
let_it_be_with_reload(:project_hook) { create(:project_hook, project: project) }
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
let(:headers) do
|
|
|
|
{
|
|
|
|
'Content-Type' => 'application/json',
|
2021-01-29 00:20:46 +05:30
|
|
|
'User-Agent' => "GitLab/#{Gitlab::VERSION}",
|
2017-09-10 17:25:29 +05:30
|
|
|
'X-Gitlab-Event' => 'Push Hook'
|
|
|
|
}
|
|
|
|
end
|
2020-10-24 23:57:45 +05:30
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
let(:data) do
|
|
|
|
{ before: 'oldrev', after: 'newrev', ref: 'ref' }
|
|
|
|
end
|
2020-10-24 23:57:45 +05:30
|
|
|
|
2018-03-17 18:26:18 +05:30
|
|
|
let(:service_instance) { described_class.new(project_hook, data, :push_hooks) }
|
2017-09-10 17:25:29 +05:30
|
|
|
|
2021-06-08 01:23:25 +05:30
|
|
|
around do |example|
|
|
|
|
travel_to(Time.current) { example.run }
|
|
|
|
end
|
|
|
|
|
2018-03-26 14:24:53 +05:30
|
|
|
describe '#initialize' do
|
2019-10-12 21:52:04 +05:30
|
|
|
before do
|
|
|
|
stub_application_setting(setting_name => setting)
|
2018-03-26 14:24:53 +05:30
|
|
|
end
|
|
|
|
|
2019-10-12 21:52:04 +05:30
|
|
|
shared_examples_for 'respects outbound network setting' do
|
|
|
|
context 'when local requests are allowed' do
|
|
|
|
let(:setting) { true }
|
|
|
|
|
|
|
|
it { expect(hook.request_options[:allow_local_requests]).to be_truthy }
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when local requests are not allowed' do
|
|
|
|
let(:setting) { false }
|
|
|
|
|
|
|
|
it { expect(hook.request_options[:allow_local_requests]).to be_falsey }
|
2018-03-26 14:24:53 +05:30
|
|
|
end
|
|
|
|
end
|
2019-10-12 21:52:04 +05:30
|
|
|
|
|
|
|
context 'when SystemHook' do
|
|
|
|
let(:setting_name) { :allow_local_requests_from_system_hooks }
|
|
|
|
let(:hook) { described_class.new(build(:system_hook), data, :system_hook) }
|
|
|
|
|
|
|
|
include_examples 'respects outbound network setting'
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when ProjectHook' do
|
|
|
|
let(:setting_name) { :allow_local_requests_from_web_hooks_and_services }
|
|
|
|
let(:hook) { described_class.new(build(:project_hook), data, :project_hook) }
|
|
|
|
|
|
|
|
include_examples 'respects outbound network setting'
|
|
|
|
end
|
2018-03-26 14:24:53 +05:30
|
|
|
end
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
describe '#execute' do
|
|
|
|
context 'when token is defined' do
|
2021-06-08 01:23:25 +05:30
|
|
|
let_it_be(:project_hook) { create(:project_hook, :token) }
|
2017-09-10 17:25:29 +05:30
|
|
|
|
|
|
|
it 'POSTs to the webhook URL' do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post)
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
service_instance.execute
|
2019-09-04 21:01:54 +05:30
|
|
|
|
|
|
|
expect(WebMock).to have_requested(:post, stubbed_hostname(project_hook.url)).with(
|
2017-09-10 17:25:29 +05:30
|
|
|
headers: headers.merge({ 'X-Gitlab-Token' => project_hook.token })
|
|
|
|
).once
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'POSTs the data as JSON' do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post)
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
service_instance.execute
|
2019-09-04 21:01:54 +05:30
|
|
|
|
|
|
|
expect(WebMock).to have_requested(:post, stubbed_hostname(project_hook.url)).with(
|
2017-09-10 17:25:29 +05:30
|
|
|
headers: headers
|
|
|
|
).once
|
|
|
|
end
|
|
|
|
|
2018-11-08 19:23:39 +05:30
|
|
|
context 'when auth credentials are present' do
|
2021-06-08 01:23:25 +05:30
|
|
|
let_it_be(:url) {'https://example.org'}
|
|
|
|
let_it_be(:project_hook) { create(:project_hook, url: 'https://demo:demo@example.org/') }
|
2018-11-08 19:23:39 +05:30
|
|
|
|
|
|
|
it 'uses the credentials' do
|
2019-06-05 12:25:43 +05:30
|
|
|
stub_full_request(url, method: :post)
|
2018-11-08 19:23:39 +05:30
|
|
|
|
|
|
|
service_instance.execute
|
|
|
|
|
2019-06-05 12:25:43 +05:30
|
|
|
expect(WebMock).to have_requested(:post, stubbed_hostname(url)).with(
|
2018-11-08 19:23:39 +05:30
|
|
|
headers: headers.merge('Authorization' => 'Basic ZGVtbzpkZW1v')
|
|
|
|
).once
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when auth credentials are partial present' do
|
2021-06-08 01:23:25 +05:30
|
|
|
let_it_be(:url) {'https://example.org'}
|
|
|
|
let_it_be(:project_hook) { create(:project_hook, url: 'https://demo@example.org/') }
|
2018-11-08 19:23:39 +05:30
|
|
|
|
|
|
|
it 'uses the credentials anyways' do
|
2019-06-05 12:25:43 +05:30
|
|
|
stub_full_request(url, method: :post)
|
2018-11-08 19:23:39 +05:30
|
|
|
|
|
|
|
service_instance.execute
|
|
|
|
|
2019-06-05 12:25:43 +05:30
|
|
|
expect(WebMock).to have_requested(:post, stubbed_hostname(url)).with(
|
2018-11-08 19:23:39 +05:30
|
|
|
headers: headers.merge('Authorization' => 'Basic ZGVtbzo=')
|
|
|
|
).once
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
it 'catches exceptions' do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_raise(StandardError.new('Some error'))
|
2017-09-10 17:25:29 +05:30
|
|
|
|
|
|
|
expect { service_instance.execute }.to raise_error(StandardError)
|
|
|
|
end
|
|
|
|
|
2021-06-08 01:23:25 +05:30
|
|
|
it 'does not execute disabled hooks' do
|
|
|
|
project_hook.update!(recent_failures: 4)
|
|
|
|
|
|
|
|
expect(service_instance.execute).to eq({ status: :error, message: 'Hook disabled' })
|
|
|
|
end
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
it 'handles exceptions' do
|
2021-06-08 01:23:25 +05:30
|
|
|
exceptions = [
|
|
|
|
SocketError, OpenSSL::SSL::SSLError, Errno::ECONNRESET, Errno::ECONNREFUSED,
|
|
|
|
Errno::EHOSTUNREACH, Net::OpenTimeout, Net::ReadTimeout,
|
|
|
|
Gitlab::HTTP::BlockedUrlError, Gitlab::HTTP::RedirectionTooDeep
|
|
|
|
]
|
2017-09-10 17:25:29 +05:30
|
|
|
exceptions.each do |exception_class|
|
|
|
|
exception = exception_class.new('Exception message')
|
2021-06-08 01:23:25 +05:30
|
|
|
project_hook.enable!
|
2017-09-10 17:25:29 +05:30
|
|
|
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_raise(exception)
|
2019-07-07 11:18:12 +05:30
|
|
|
expect(service_instance.execute).to eq({ status: :error, message: exception.to_s })
|
2017-09-10 17:25:29 +05:30
|
|
|
expect { service_instance.execute }.not_to raise_error
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
2021-03-08 18:12:59 +05:30
|
|
|
context 'when url is not encoded' do
|
2021-06-08 01:23:25 +05:30
|
|
|
let_it_be(:project_hook) { create(:project_hook, url: 'http://server.com/my path/') }
|
2021-03-08 18:12:59 +05:30
|
|
|
|
|
|
|
it 'handles exceptions' do
|
|
|
|
expect(service_instance.execute).to eq(status: :error, message: 'bad URI(is not URI?): "http://server.com/my path/"')
|
|
|
|
expect { service_instance.execute }.not_to raise_error
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
2020-10-24 23:57:45 +05:30
|
|
|
context 'when request body size is too big' do
|
|
|
|
it 'does not perform the request' do
|
|
|
|
stub_const("#{described_class}::REQUEST_BODY_SIZE_LIMIT", 10.bytes)
|
|
|
|
|
|
|
|
expect(service_instance.execute).to eq({ status: :error, message: "Gitlab::Json::LimitedEncoder::LimitExceeded" })
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
it 'handles 200 status code' do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_return(status: 200, body: 'Success')
|
2017-09-10 17:25:29 +05:30
|
|
|
|
|
|
|
expect(service_instance.execute).to include({ status: :success, http_status: 200, message: 'Success' })
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'handles 2xx status codes' do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_return(status: 201, body: 'Success')
|
2017-09-10 17:25:29 +05:30
|
|
|
|
|
|
|
expect(service_instance.execute).to include({ status: :success, http_status: 201, message: 'Success' })
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'execution logging' do
|
|
|
|
let(:hook_log) { project_hook.web_hook_logs.last }
|
|
|
|
|
|
|
|
context 'with success' do
|
|
|
|
before do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_return(status: 200, body: 'Success')
|
2017-09-10 17:25:29 +05:30
|
|
|
end
|
|
|
|
|
|
|
|
it 'log successful execution' do
|
2021-06-08 01:23:25 +05:30
|
|
|
service_instance.execute
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
expect(hook_log.trigger).to eq('push_hooks')
|
|
|
|
expect(hook_log.url).to eq(project_hook.url)
|
|
|
|
expect(hook_log.request_headers).to eq(headers)
|
|
|
|
expect(hook_log.response_body).to eq('Success')
|
|
|
|
expect(hook_log.response_status).to eq('200')
|
|
|
|
expect(hook_log.execution_duration).to be > 0
|
|
|
|
expect(hook_log.internal_error_message).to be_nil
|
|
|
|
end
|
2021-06-08 01:23:25 +05:30
|
|
|
|
|
|
|
it 'does not increment the failure count' do
|
|
|
|
expect { service_instance.execute }.not_to change(project_hook, :recent_failures)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'does not change the disabled_until attribute' do
|
|
|
|
expect { service_instance.execute }.not_to change(project_hook, :disabled_until)
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when the hook had previously failed' do
|
|
|
|
before do
|
|
|
|
project_hook.update!(recent_failures: 2)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'resets the failure count' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :recent_failures).to(0)
|
|
|
|
end
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'with bad request' do
|
|
|
|
before do
|
|
|
|
stub_full_request(project_hook.url, method: :post).to_return(status: 400, body: 'Bad request')
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'logs failed execution' do
|
|
|
|
service_instance.execute
|
|
|
|
|
|
|
|
expect(hook_log).to have_attributes(
|
|
|
|
trigger: eq('push_hooks'),
|
|
|
|
url: eq(project_hook.url),
|
|
|
|
request_headers: eq(headers),
|
|
|
|
response_body: eq('Bad request'),
|
|
|
|
response_status: eq('400'),
|
|
|
|
execution_duration: be > 0,
|
|
|
|
internal_error_message: be_nil
|
|
|
|
)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'increments the failure count' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :recent_failures).by(1)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'does not change the disabled_until attribute' do
|
|
|
|
expect { service_instance.execute }.not_to change(project_hook, :disabled_until)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'does not allow the failure count to overflow' do
|
|
|
|
project_hook.update!(recent_failures: 32767)
|
|
|
|
|
|
|
|
expect { service_instance.execute }.not_to change(project_hook, :recent_failures)
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when the web_hooks_disable_failed FF is disabled' do
|
|
|
|
before do
|
|
|
|
# Hook will only be executed if the flag is disabled.
|
|
|
|
stub_feature_flags(web_hooks_disable_failed: false)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'does not allow the failure count to overflow' do
|
|
|
|
project_hook.update!(recent_failures: 32767)
|
|
|
|
|
|
|
|
expect { service_instance.execute }.not_to change(project_hook, :recent_failures)
|
|
|
|
end
|
|
|
|
end
|
2017-09-10 17:25:29 +05:30
|
|
|
end
|
|
|
|
|
|
|
|
context 'with exception' do
|
|
|
|
before do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_raise(SocketError.new('Some HTTP Post error'))
|
2017-09-10 17:25:29 +05:30
|
|
|
end
|
|
|
|
|
|
|
|
it 'log failed execution' do
|
2021-06-08 01:23:25 +05:30
|
|
|
service_instance.execute
|
|
|
|
|
2017-09-10 17:25:29 +05:30
|
|
|
expect(hook_log.trigger).to eq('push_hooks')
|
|
|
|
expect(hook_log.url).to eq(project_hook.url)
|
|
|
|
expect(hook_log.request_headers).to eq(headers)
|
|
|
|
expect(hook_log.response_body).to eq('')
|
|
|
|
expect(hook_log.response_status).to eq('internal error')
|
|
|
|
expect(hook_log.execution_duration).to be > 0
|
|
|
|
expect(hook_log.internal_error_message).to eq('Some HTTP Post error')
|
|
|
|
end
|
2021-06-08 01:23:25 +05:30
|
|
|
|
|
|
|
it 'does not increment the failure count' do
|
|
|
|
expect { service_instance.execute }.not_to change(project_hook, :recent_failures)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'sets the disabled_until attribute' do
|
|
|
|
expect { service_instance.execute }
|
|
|
|
.to change(project_hook, :disabled_until).to(project_hook.next_backoff.from_now)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'increases the backoff count' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :backoff_count).by(1)
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when the previous cool-off was near the maximum' do
|
|
|
|
before do
|
|
|
|
project_hook.update!(disabled_until: 5.minutes.ago, backoff_count: 8)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'sets the disabled_until attribute' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :disabled_until).to(1.day.from_now)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'sets the last_backoff attribute' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :backoff_count).by(1)
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when we have backed-off many many times' do
|
|
|
|
before do
|
|
|
|
project_hook.update!(disabled_until: 5.minutes.ago, backoff_count: 365)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'sets the disabled_until attribute' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :disabled_until).to(1.day.from_now)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'sets the last_backoff attribute' do
|
|
|
|
expect { service_instance.execute }.to change(project_hook, :backoff_count).by(1)
|
|
|
|
end
|
|
|
|
end
|
2017-09-10 17:25:29 +05:30
|
|
|
end
|
|
|
|
|
|
|
|
context 'with unsafe response body' do
|
|
|
|
before do
|
2019-09-04 21:01:54 +05:30
|
|
|
stub_full_request(project_hook.url, method: :post).to_return(status: 200, body: "\xBB")
|
2017-09-10 17:25:29 +05:30
|
|
|
service_instance.execute
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'log successful execution' do
|
|
|
|
expect(hook_log.trigger).to eq('push_hooks')
|
|
|
|
expect(hook_log.url).to eq(project_hook.url)
|
|
|
|
expect(hook_log.request_headers).to eq(headers)
|
|
|
|
expect(hook_log.response_body).to eq('')
|
|
|
|
expect(hook_log.response_status).to eq('200')
|
|
|
|
expect(hook_log.execution_duration).to be > 0
|
|
|
|
expect(hook_log.internal_error_message).to be_nil
|
|
|
|
end
|
|
|
|
end
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
describe '#async_execute' do
|
2021-06-08 01:23:25 +05:30
|
|
|
def expect_to_perform_worker(hook)
|
|
|
|
expect(WebHookWorker).to receive(:perform_async).with(hook.id, data, 'push_hooks')
|
|
|
|
end
|
|
|
|
|
|
|
|
def expect_to_rate_limit(hook, threshold:, throttled: false)
|
|
|
|
expect(Gitlab::ApplicationRateLimiter).to receive(:throttled?)
|
|
|
|
.with(:web_hook_calls, scope: [hook], threshold: threshold)
|
|
|
|
.and_return(throttled)
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when rate limiting is not configured' do
|
|
|
|
it 'queues a worker without tracking the call' do
|
|
|
|
expect(Gitlab::ApplicationRateLimiter).not_to receive(:throttled?)
|
|
|
|
expect_to_perform_worker(project_hook)
|
|
|
|
|
|
|
|
service_instance.async_execute
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when rate limiting is configured' do
|
|
|
|
let_it_be(:threshold) { 3 }
|
|
|
|
let_it_be(:plan_limits) { create(:plan_limits, :default_plan, web_hook_calls: threshold) }
|
|
|
|
|
|
|
|
it 'queues a worker and tracks the call' do
|
|
|
|
expect_to_rate_limit(project_hook, threshold: threshold)
|
|
|
|
expect_to_perform_worker(project_hook)
|
|
|
|
|
|
|
|
service_instance.async_execute
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when the hook is throttled (via mock)' do
|
|
|
|
before do
|
|
|
|
expect_to_rate_limit(project_hook, threshold: threshold, throttled: true)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'does not queue a worker and logs an error' do
|
|
|
|
expect(WebHookWorker).not_to receive(:perform_async)
|
2018-03-17 18:26:18 +05:30
|
|
|
|
2021-06-08 01:23:25 +05:30
|
|
|
payload = {
|
|
|
|
message: 'Webhook rate limit exceeded',
|
|
|
|
hook_id: project_hook.id,
|
|
|
|
hook_type: 'ProjectHook',
|
|
|
|
hook_name: 'push_hooks'
|
|
|
|
}
|
2017-09-10 17:25:29 +05:30
|
|
|
|
2021-06-08 01:23:25 +05:30
|
|
|
expect(Gitlab::AuthLogger).to receive(:error).with(payload)
|
|
|
|
expect(Gitlab::AppLogger).to receive(:error).with(payload)
|
|
|
|
|
|
|
|
service_instance.async_execute
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when the hook is throttled (via Redis)', :clean_gitlab_redis_cache do
|
|
|
|
before do
|
|
|
|
# Set a high interval to avoid intermittent failures in CI
|
|
|
|
allow(Gitlab::ApplicationRateLimiter).to receive(:rate_limits).and_return(
|
|
|
|
web_hook_calls: { interval: 1.day }
|
|
|
|
)
|
|
|
|
|
|
|
|
expect_to_perform_worker(project_hook).exactly(threshold).times
|
|
|
|
|
|
|
|
threshold.times { service_instance.async_execute }
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'stops queueing workers and logs errors' do
|
|
|
|
expect(Gitlab::AuthLogger).to receive(:error).twice
|
|
|
|
expect(Gitlab::AppLogger).to receive(:error).twice
|
|
|
|
|
|
|
|
2.times { service_instance.async_execute }
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'still queues workers for other hooks' do
|
|
|
|
other_hook = create(:project_hook)
|
|
|
|
|
|
|
|
expect_to_perform_worker(other_hook)
|
|
|
|
|
|
|
|
described_class.new(other_hook, data, :push_hooks).async_execute
|
|
|
|
end
|
|
|
|
end
|
|
|
|
|
|
|
|
context 'when the feature flag is disabled' do
|
|
|
|
before do
|
|
|
|
stub_feature_flags(web_hooks_rate_limit: false)
|
|
|
|
end
|
|
|
|
|
|
|
|
it 'queues a worker without tracking the call' do
|
|
|
|
expect(Gitlab::ApplicationRateLimiter).not_to receive(:throttled?)
|
|
|
|
expect_to_perform_worker(project_hook)
|
|
|
|
|
|
|
|
service_instance.async_execute
|
|
|
|
end
|
|
|
|
end
|
2017-09-10 17:25:29 +05:30
|
|
|
end
|
|
|
|
end
|
|
|
|
end
|