#!/usr/bin/env ruby require 'chronic_duration' require 'em-hiredis' require 'flapjack/filters/acknowledgement' require 'flapjack/filters/ok' require 'flapjack/filters/scheduled_maintenance' require 'flapjack/filters/unscheduled_maintenance' require 'flapjack/filters/detect_mass_client_failures' require 'flapjack/filters/delays' require 'flapjack/data/entity_check' require 'flapjack/data/event' require 'flapjack/redis_pool' require 'flapjack/utility' module Flapjack class Processor include Flapjack::Utility def initialize(opts = {}) @config = opts[:config] @redis_config = opts[:redis_config] || {} @logger = opts[:logger] @coordinator = opts[:coordinator] @redis = Flapjack::RedisPool.new(:config => @redis_config, :size => 2) @queue = @config['queue'] || 'events' @notifier_queue = @config['notifier_queue'] || 'notifications' @archive_events = @config['archive_events'] || false @events_archive_maxage = @config['events_archive_maxage'] ncsm_duration_conf = @config['new_check_scheduled_maintenance_duration'] || '100 years' @ncsm_duration = ChronicDuration.parse(ncsm_duration_conf) @exit_on_queue_empty = !! @config['exit_on_queue_empty'] options = { :logger => opts[:logger], :redis => @redis } @filters = [] @filters << Flapjack::Filters::Ok.new(options) @filters << Flapjack::Filters::ScheduledMaintenance.new(options) @filters << Flapjack::Filters::UnscheduledMaintenance.new(options) @filters << Flapjack::Filters::DetectMassClientFailures.new(options) @filters << Flapjack::Filters::Delays.new(options) @filters << Flapjack::Filters::Acknowledgement.new(options) boot_time = opts[:boot_time] fqdn = `/bin/hostname -f`.chomp pid = Process.pid @instance_id = "#{fqdn}:#{pid}" # FIXME: all of the below keys assume there is only ever one executive running; # we could generate a fuid and save it to disk, and prepend it from that # point on... # FIXME: add an administrative function to reset all event counters if @redis.hget('event_counters', 'all').nil? @redis.hset('event_counters', 'all', 0) @redis.hset('event_counters', 'ok', 0) @redis.hset('event_counters', 'failure', 0) @redis.hset('event_counters', 'action', 0) end #@redis.zadd('executive_instances', boot_time.to_i, @instance_id) @redis.hset("executive_instance:#{@instance_id}", 'boot_time', boot_time.to_i) @redis.hset("event_counters:#{@instance_id}", 'all', 0) @redis.hset("event_counters:#{@instance_id}", 'ok', 0) @redis.hset("event_counters:#{@instance_id}", 'failure', 0) @redis.hset("event_counters:#{@instance_id}", 'action', 0) touch_keys end # expire instance keys after one week # TODO: set up a separate EM timer to reset key expiry every minute # and reduce the expiry to, say, five minutes # TODO: remove these keys on process exit def touch_keys [ "executive_instance:#{@instance_id}", "event_counters:#{@instance_id}", "event_counters:#{@instance_id}", "event_counters:#{@instance_id}", "event_counters:#{@instance_id}" ].each {|key| @redis.expire(key, 1036800) } end def start @logger.info("Booting main loop.") until @should_quit @logger.debug("Waiting for event...") event = Flapjack::Data::Event.next(@queue, :redis => @redis, :archive_events => @archive_events, :events_archive_maxage => @events_archive_maxage, :logger => @logger, :block => ! @exit_on_queue_empty ) if @exit_on_queue_empty && event.nil? && Flapjack::Data::Event.pending_count(@queue, :redis => @redis) # SHUT IT ALL DOWN!!! @logger.warn "Shutting down as exit_on_queue_empty is true, and the queue is empty" @should_quit = true @coordinator.stop # FIXME: seems the above call doesn't block until the remove_pikelets fiber exits... EM::Synchrony.sleep(1) exit end process_event(event) unless event.nil? end @logger.info("Exiting main loop.") end # this must use a separate connection to the main Executive one, as it's running # from a different fiber while the main one is blocking. def stop unless @should_quit @should_quit = true redis_uri = @redis_config[:path] || "redis://#{@redis_config[:host] || '127.0.0.1'}:#{@redis_config[:port] || '6379'}/#{@redis_config[:db] || '0'}" shutdown_redis = EM::Hiredis.connect(redis_uri) shutdown_redis.rpush('events', Oj.dump('type' => 'noop')) end end private def process_event(event) pending = Flapjack::Data::Event.pending_count(@queue, :redis => @redis) @logger.debug("#{pending} events waiting on the queue") @logger.debug("Raw event received: #{event.inspect}") if ('noop' == event.type) return end event_str = "#{event.id}, #{event.type}, #{event.state}, #{event.summary}" event_str << ", #{Time.at(event.time).to_s}" if event.time @logger.debug("Processing Event: #{event_str}") entity_check = Flapjack::Data::EntityCheck.for_event_id(event.id, :redis => @redis) timestamp = Time.now.to_i event.tags = (event.tags || Flapjack::Data::TagSet.new) + entity_check.tags should_notify = update_keys(event, entity_check, timestamp) if !should_notify @logger.debug("Not generating notification for event #{event.id} because filtering was skipped") return elsif blocker = @filters.find {|filter| filter.block?(event) } @logger.debug("Not generating notification for event #{event.id} because this filter blocked: #{blocker.name}") return end @logger.info("Generating notification for event #{event_str}") generate_notification(event, entity_check, timestamp) end def update_keys(event, entity_check, timestamp) # TODO: run touch_keys from a separate EM timer for efficiency touch_keys result = true event.counter = @redis.hincrby('event_counters', 'all', 1) @redis.hincrby("event_counters:#{@instance_id}", 'all', 1) # FIXME skip if entity_check.nil? # FIXME: validate that the event is sane before we ever get here # FIXME: create an event if there is dodgy data case event.type # Service events represent changes in state on monitored systems when 'service' # Track when we last saw an event for a particular entity:check pair entity_check.last_update = timestamp if event.ok? @redis.hincrby('event_counters', 'ok', 1) @redis.hincrby("event_counters:#{@instance_id}", 'ok', 1) elsif event.failure? @redis.hincrby('event_counters', 'failure', 1) @redis.hincrby("event_counters:#{@instance_id}", 'failure', 1) @redis.hset('unacknowledged_failures', event.counter, event.id) end event.previous_state = entity_check.state if event.previous_state.nil? @logger.info("No previous state for event #{event.id}") if @ncsm_duration >= 0 @logger.info("Setting scheduled maintenance for #{time_period_in_words(@ncsm_duration)}") entity_check.create_scheduled_maintenance(timestamp, @ncsm_duration, :summary => 'Automatically created for new check') end else event.previous_state_duration = timestamp - entity_check.last_change.to_i end entity_check.update_state(event.state, :timestamp => timestamp, :summary => event.summary, :client => event.client, :count => event.counter, :details => event.details) # No state change, and event is ok, so no need to run through filters # OR # If the service event's state is ok and there was no previous state, don't alert. # This stops new checks from alerting as "recovery" after they have been added. if !event.previous_state && event.ok? @logger.debug("setting skip_filters to true because there was no previous state and event is ok") result = false end entity_check.update_current_scheduled_maintenance # Action events represent human or automated interaction with Flapjack when 'action' # When an action event is processed, store the event. @redis.hset(event.id + ':actions', timestamp, event.state) @redis.hincrby('event_counters', 'action', 1) @redis.hincrby("event_counters:#{@instance_id}", 'action', 1) if event.acknowledgement? && event.acknowledgement_id @redis.hdel('unacknowledged_failures', event.acknowledgement_id) end end result end def generate_notification(event, entity_check, timestamp) notification_type = Flapjack::Data::Notification.type_for_event(event) max_notified_severity = entity_check.max_notified_severity_of_current_failure @redis.set("#{event.id}:last_#{notification_type}_notification", timestamp) @redis.set("#{event.id}:last_#{event.state}_notification", timestamp) if event.failure? @redis.rpush("#{event.id}:#{notification_type}_notifications", timestamp) @redis.rpush("#{event.id}:#{event.state}_notifications", timestamp) if event.failure? @logger.debug("Notification of type #{notification_type} is being generated for #{event.id}.") severity = Flapjack::Data::Notification.severity_for_event(event, max_notified_severity) last_state = entity_check.historical_state_before(timestamp) Flapjack::Data::Notification.add(@notifier_queue, event, :type => notification_type, :severity => severity, :last_state => last_state, :redis => @redis) end end end