2014-03-16 20:59:34 -04:00
|
|
|
# encoding: utf-8
|
2019-04-29 20:27:42 -04:00
|
|
|
# frozen_string_literal: true
|
|
|
|
|
2022-07-27 22:27:38 -04:00
|
|
|
RSpec.describe Scheduler::Defer do
|
2014-03-16 20:59:34 -04:00
|
|
|
class DeferInstance
|
|
|
|
include Scheduler::Deferrable
|
|
|
|
end
|
|
|
|
|
|
|
|
def wait_for(timeout, &blk)
|
|
|
|
till = Time.now + (timeout.to_f / 1000)
|
|
|
|
sleep 0.001 while Time.now < till && !blk.call
|
|
|
|
end
|
|
|
|
|
|
|
|
before do
|
2023-01-15 17:08:44 -05:00
|
|
|
Discourse.catch_job_exceptions!
|
2014-03-16 20:59:34 -04:00
|
|
|
@defer = DeferInstance.new
|
|
|
|
@defer.async = true
|
|
|
|
end
|
|
|
|
|
2023-01-15 17:08:44 -05:00
|
|
|
after do
|
|
|
|
@defer.stop!
|
|
|
|
Discourse.reset_catch_job_exceptions!
|
|
|
|
end
|
2014-03-16 20:59:34 -04:00
|
|
|
|
2023-01-11 20:29:50 -05:00
|
|
|
it "supports basic instrumentation" do
|
|
|
|
@defer.later("first") {}
|
|
|
|
@defer.later("first") {}
|
|
|
|
@defer.later("second") {}
|
|
|
|
@defer.later("bad") { raise "boom" }
|
|
|
|
|
|
|
|
wait_for(200) { @defer.length == 0 }
|
|
|
|
|
|
|
|
stats = Hash[@defer.stats]
|
|
|
|
|
|
|
|
expect(stats["first"][:queued]).to eq(2)
|
|
|
|
expect(stats["first"][:finished]).to eq(2)
|
|
|
|
expect(stats["first"][:errors]).to eq(0)
|
|
|
|
expect(stats["first"][:duration]).to be > 0
|
|
|
|
|
|
|
|
expect(stats["second"][:queued]).to eq(1)
|
|
|
|
expect(stats["second"][:finished]).to eq(1)
|
|
|
|
expect(stats["second"][:errors]).to eq(0)
|
|
|
|
expect(stats["second"][:duration]).to be > 0
|
|
|
|
|
|
|
|
expect(stats["bad"][:queued]).to eq(1)
|
|
|
|
expect(stats["bad"][:finished]).to eq(1)
|
|
|
|
expect(stats["bad"][:duration]).to be > 0
|
|
|
|
expect(stats["bad"][:errors]).to eq(1)
|
|
|
|
end
|
|
|
|
|
2018-10-12 02:03:47 -04:00
|
|
|
it "supports timeout reporting" do
|
|
|
|
@defer.timeout = 0.05
|
|
|
|
|
2022-05-04 21:50:43 -04:00
|
|
|
logger =
|
|
|
|
track_log_messages do |l|
|
2018-10-12 02:03:47 -04:00
|
|
|
10.times { @defer.later("fast job") {} }
|
2022-05-04 21:50:43 -04:00
|
|
|
|
2018-10-12 02:03:47 -04:00
|
|
|
@defer.later "weird slow job" do
|
|
|
|
sleep
|
|
|
|
end
|
|
|
|
|
2022-05-04 21:50:43 -04:00
|
|
|
wait_for(200) { l.errors.length == 1 }
|
2018-10-12 02:03:47 -04:00
|
|
|
end
|
|
|
|
|
2022-05-04 21:50:43 -04:00
|
|
|
expect(logger.warnings.length).to eq(0)
|
|
|
|
expect(logger.fatals.length).to eq(0)
|
|
|
|
expect(logger.errors.length).to eq(1)
|
|
|
|
expect(logger.errors).to include(/'weird slow job' is still running/)
|
2018-10-12 02:03:47 -04:00
|
|
|
end
|
|
|
|
|
2015-02-16 17:56:21 -05:00
|
|
|
it "can pause and resume" do
|
|
|
|
x = 1
|
|
|
|
@defer.pause
|
|
|
|
|
|
|
|
@defer.later { x = 2 }
|
|
|
|
|
2017-11-22 23:48:47 -05:00
|
|
|
expect(@defer.length).to eq(1)
|
|
|
|
|
2015-02-16 17:56:21 -05:00
|
|
|
@defer.do_all_work
|
|
|
|
|
|
|
|
expect(x).to eq(2)
|
|
|
|
|
|
|
|
@defer.resume
|
|
|
|
|
|
|
|
@defer.later { x = 3 }
|
|
|
|
|
2019-05-27 03:08:35 -04:00
|
|
|
wait_for(1000) { x == 3 }
|
2015-02-16 17:56:21 -05:00
|
|
|
|
|
|
|
expect(x).to eq(3)
|
|
|
|
end
|
|
|
|
|
2014-03-17 00:22:11 -04:00
|
|
|
it "recovers from a crash / fork" do
|
|
|
|
s = nil
|
|
|
|
@defer.stop!
|
|
|
|
wait_for(1000) { @defer.stopped? }
|
|
|
|
# hack allow thread to die
|
|
|
|
sleep 0.005
|
|
|
|
|
|
|
|
@defer.later { s = "good" }
|
|
|
|
|
2019-10-23 01:18:41 -04:00
|
|
|
wait_for(1000) { s == "good" }
|
2014-03-17 00:22:11 -04:00
|
|
|
|
2015-01-09 11:34:37 -05:00
|
|
|
expect(s).to eq("good")
|
2014-03-17 00:22:11 -04:00
|
|
|
end
|
|
|
|
|
2014-03-16 20:59:34 -04:00
|
|
|
it "can queue jobs properly" do
|
|
|
|
s = nil
|
|
|
|
|
|
|
|
@defer.later { s = "good" }
|
|
|
|
|
2019-10-23 01:18:41 -04:00
|
|
|
wait_for(1000) { s == "good" }
|
2014-03-16 20:59:34 -04:00
|
|
|
|
2015-01-09 11:34:37 -05:00
|
|
|
expect(s).to eq("good")
|
2014-03-16 20:59:34 -04:00
|
|
|
end
|
|
|
|
end
|