# =================================================================
#
# Authors: Tom Kralidis <tomkralidis@gmail.com>
# Just van den Broecke <justb4@gmail.com>
#
# Copyright (c) 2014 Tom Kralidis
#
# Permission is hereby granted, free of charge, to any person
# obtaining a copy of this software and associated documentation
# files (the "Software"), to deal in the Software without
# restriction, including without limitation the rights to use,
# copy, modify, merge, publish, distribute, sublicense, and/or sell
# copies of the Software, and to permit persons to whom the
# Software is furnished to do so, subject to the following
# conditions:
#
# The above copyright notice and this permission notice shall be
# included in all copies or substantial portions of the Software.
#
# THE SOFTWARE IS PROVIDED "AS IS", WITHOUT WARRANTY OF ANY KIND,
# EXPRESS OR IMPLIED, INCLUDING BUT NOT LIMITED TO THE WARRANTIES
# OF MERCHANTABILITY, FITNESS FOR A PARTICULAR PURPOSE AND
# NONINFRINGEMENT. IN NO EVENT SHALL THE AUTHORS OR COPYRIGHT
# HOLDERS BE LIABLE FOR ANY CLAIM, DAMAGES OR OTHER LIABILITY,
# WHETHER IN AN ACTION OF CONTRACT, TORT OR OTHERWISE, ARISING
# FROM, OUT OF OR IN CONNECTION WITH THE SOFTWARE OR THE USE OR
# OTHER DEALINGS IN THE SOFTWARE.
#
# =================================================================
import logging
import os
import random
import string
from datetime import datetime, timedelta
from models import Resource, ResourceLock, flush_runs
from healthcheck import run_resource
from apscheduler.schedulers.background import BackgroundScheduler
from apscheduler.jobstores.base import JobLookupError
from apscheduler.events import \
EVENT_SCHEDULER_STARTED, EVENT_SCHEDULER_SHUTDOWN, \
EVENT_JOB_MISSED, EVENT_JOB_ERROR
from init import App
LOGGER = logging.getLogger(__name__)
DB = App.get_db()
# Create scheduler
scheduler = BackgroundScheduler()
# commit or rollback shorthand
[docs]def db_commit():
err = None
try:
DB.session.commit()
except Exception:
DB.session.rollback()
# finally:
# DB.session.close()
return err
[docs]def run_job(resource_id, frequency):
"""
Runs single job (all Probes) for single Resource.
As multiple instances of the job scheduler may run in different
processes and threads, the database is used to synchronize and assure
only one job will run. This is achieved by having one lock per Resource.
Only the process/thread that acquires its related ResourceLock record
runs the job. As to avoid permanent "lockouts", each ResourceLock has
a lifetime, namely the timespan until the next Run as configured for/per
Resource. This gives all job runners a chance to obtain a lock once
"time's up" for the ResourceLock.
An extra check for lock obtainment is made via an unique UUID per job
runner. Once the lock is obtained the UUID-field of the lock record
is set and committed to the DB. If we then try to obtain the lock again
(by reading from DB) but the UUID is different this means another job
runner instance did the same but was just before us. The lock timespan
will guard that a particular UUID will keep the lock forever, e.g. if
the application is suddenly shutdown.
:param resource_id:
:param frequency:
:return:
"""
# Generate unique id
# https://stackoverflow.com/questions/2257441/random-string-generation-with-upper-case-letters-and-digits-in-python
uuid = '%d-%s' % (os.getpid(), ''.join(random.choice(
string.ascii_uppercase + string.digits) for _ in range(8)))
resource = Resource.query.filter_by(identifier=resource_id).first()
# Resource may have been deleted, cancel job
if not resource:
stop_job(resource_id)
return
# Resource exists: try to obtain our Resource Lock record.
lock = ResourceLock.query.filter_by(identifier=resource_id).first()
if not lock:
# No lock at all on Resource: (hope) we're first
# obtain fresh lock, back-off if failed
LOGGER.info('%d No Lock at all: obtain new' % resource_id)
lock = ResourceLock(resource, uuid, frequency)
DB.session.add(lock)
lock_err = db_commit()
if lock_err:
# Another process may have been there first!
LOGGER.info('%d Error obtaining Lock %s' %
(resource_id, str(lock_err)))
return
else:
# Lock record found for Resource: check if available for our UUID.
LOGGER.info('%d Lock present: try obtaining..' % resource_id)
if not lock.obtain(uuid, frequency):
LOGGER.info('%d Cannot obtain lock' % resource_id)
return
else:
LOGGER.info('%d Lock obtained, delete and renew' % resource_id)
DB.session.delete(lock)
lock_err = db_commit()
if lock_err:
# Another process may have been there first!
LOGGER.info('%d Lock Delete failed' % resource_id)
return
LOGGER.info('%d Lock deleted, add new' % resource_id)
# (hope) we're first
# obtain fresh lock, back-off if failed
lock = ResourceLock(resource, uuid, frequency)
DB.session.add(lock)
lock_err = db_commit()
if lock_err:
# Another process may have been there first!
LOGGER.info('%d Lock Add failed' % resource_id)
return
# Check if we really own the lock
LOGGER.info('%d Lock Add OK' % resource_id)
lock = ResourceLock.query.filter_by(
identifier=resource_id).first()
if lock.owner != uuid:
LOGGER.info('%d Lock Add OK, not owner: back-off'
% resource_id)
return
# Run Resource healthchecks only if we have lock.
if lock:
try:
run_resource(resource_id)
LOGGER.info('%d run_resource OK' % resource_id)
finally:
pass
[docs]def start_schedule():
LOGGER.info('Starting scheduler')
# Adapt configuration
scheduler.configure(job_defaults={
'coalesce': True,
'max_instances': 1,
'misfire_grace_time': 300
})
scheduler.add_listener(lifecycle_listener,
EVENT_SCHEDULER_STARTED | EVENT_SCHEDULER_SHUTDOWN)
scheduler.add_listener(error_listener,
EVENT_JOB_MISSED | EVENT_JOB_ERROR)
# Start APScheduler
scheduler.start()
import atexit
atexit.register(lambda: stop_schedule())
# Add GHC jobs, one for each Resource, plus
# maintenance jobs.
# Cold start every cron of every Resource
for resource in Resource.query.all():
add_job(resource)
# Start maintenance jobs
scheduler.add_job(flush_runs, 'interval', minutes=150)
scheduler.add_job(check_schedule, 'interval', minutes=5)
[docs]def check_schedule():
LOGGER.info('Checking Job schedules')
# Check the schedule for changed jobs
for resource in Resource.query.all():
job = get_job(resource)
if job is None:
add_job(resource)
continue
current_freq = job.args[1]
# Run frequency changed?
if current_freq != resource.run_frequency:
# Reschedule Job
update_job(resource)
[docs]def lifecycle_listener(event):
event_code = event.code
event_code_str = ''
if event_code == EVENT_SCHEDULER_STARTED:
event_code_str = 'EVENT_SCHEDULER_STARTED'
elif event_code | EVENT_SCHEDULER_SHUTDOWN:
event_code_str = 'EVENT_SCHEDULER_SHUTDOWN'
LOGGER.info('lifecycle_listener: %s - %s' % (event_code_str, str(event)))
[docs]def error_listener(event):
event_code = event.code
event_code_str = ''
if event_code | EVENT_JOB_MISSED:
event_code_str = 'EVENT_JOB_MISSED'
elif event_code | EVENT_JOB_ERROR:
event_code_str = 'EVENT_JOB_ERROR'
LOGGER.error('error_listener: %s - %s' % (event_code_str, str(event)))
[docs]def get_job(resource):
return scheduler.get_job(str(resource.identifier))
[docs]def update_job(resource):
stop_job(resource.identifier)
# Add job to Scheduler
add_job(resource)
[docs]def add_job(resource):
LOGGER.info('Starting job for resource=%d' % resource.identifier)
freq = resource.run_frequency
next_run_time = datetime.now() + timedelta(
seconds=random.randint(0, freq * 60))
scheduler.add_job(
run_job, 'interval', args=[resource.identifier, freq],
minutes=freq, next_run_time=next_run_time, max_instances=1,
misfire_grace_time=round((freq * 60) / 2), coalesce=True,
id=str(resource.identifier))
[docs]def stop_job(resource_id):
LOGGER.info('Stopping job for resource=%d' % resource_id)
# Try to remove job from scheduler
try:
scheduler.remove_job(str(resource_id))
except JobLookupError:
pass
[docs]def stop_schedule():
LOGGER.info('Stopping Scheduler')
scheduler.shutdown()
scheduler.remove_listener(lifecycle_listener)
scheduler.remove_listener(error_listener)
if __name__ == '__main__':
import time
# Start scheduler
start_schedule()
while True:
LOGGER.info("This prints once in 5 minutes")
time.sleep(300) # Delay for 5 minute (300 seconds).