2014-10-01 16:42:40 +00:00
|
|
|
import logging
|
2014-05-08 16:59:35 +00:00
|
|
|
import threading
|
2015-03-11 16:32:33 +00:00
|
|
|
import traceback
|
2015-03-23 15:36:51 +00:00
|
|
|
from collections import OrderedDict
|
2014-05-08 16:59:35 +00:00
|
|
|
|
2015-03-11 16:32:33 +00:00
|
|
|
from django.core.mail import mail_admins
|
2014-05-08 16:59:35 +00:00
|
|
|
|
2015-05-01 18:05:34 +00:00
|
|
|
from orchestra.utils.db import close_connection
|
2015-04-20 14:23:10 +00:00
|
|
|
from orchestra.utils.python import import_class, OrderedSet
|
2014-05-13 13:46:40 +00:00
|
|
|
|
2015-04-07 15:14:49 +00:00
|
|
|
from . import settings, Operation
|
2015-04-01 15:49:21 +00:00
|
|
|
from .backends import ServiceBackend
|
2014-05-08 16:59:35 +00:00
|
|
|
from .helpers import send_report
|
2015-04-07 15:14:49 +00:00
|
|
|
from .models import BackendLog
|
2015-05-05 19:42:55 +00:00
|
|
|
from .signals import pre_action, post_action, pre_commit, post_commit, pre_prepare, post_prepare
|
2014-05-08 16:59:35 +00:00
|
|
|
|
|
|
|
|
2014-10-01 16:42:40 +00:00
|
|
|
logger = logging.getLogger(__name__)
|
2015-03-04 21:06:16 +00:00
|
|
|
router = import_class(settings.ORCHESTRATION_ROUTER)
|
2014-10-01 16:42:40 +00:00
|
|
|
|
|
|
|
|
2014-05-08 16:59:35 +00:00
|
|
|
def as_task(execute):
|
|
|
|
def wrapper(*args, **kwargs):
|
2014-11-18 17:47:26 +00:00
|
|
|
""" send report """
|
|
|
|
# Tasks run on a separate transaction pool (thread), no need to temper with the transaction
|
2014-10-27 14:31:04 +00:00
|
|
|
try:
|
|
|
|
log = execute(*args, **kwargs)
|
2015-03-25 15:45:04 +00:00
|
|
|
if log.state != log.SUCCESS:
|
|
|
|
send_report(execute, args, log)
|
2015-03-11 16:32:33 +00:00
|
|
|
except Exception as e:
|
|
|
|
subject = 'EXCEPTION executing backend(s) %s %s' % (str(args), str(kwargs))
|
|
|
|
message = traceback.format_exc()
|
|
|
|
logger.error(subject)
|
|
|
|
logger.error(message)
|
|
|
|
mail_admins(subject, message)
|
|
|
|
# We don't propagate the exception further to avoid transaction rollback
|
2014-10-27 14:31:04 +00:00
|
|
|
else:
|
|
|
|
# Using the wrapper function as threader messenger for the execute output
|
2015-03-11 16:32:33 +00:00
|
|
|
# Absense of it will indicate a failure at this stage
|
2014-10-27 14:31:04 +00:00
|
|
|
wrapper.log = log
|
2015-03-25 15:45:04 +00:00
|
|
|
return log
|
|
|
|
return wrapper
|
|
|
|
|
|
|
|
|
2015-04-01 15:49:21 +00:00
|
|
|
def generate(operations):
|
2015-03-23 15:36:51 +00:00
|
|
|
scripts = OrderedDict()
|
2014-07-17 16:09:24 +00:00
|
|
|
cache = {}
|
2015-03-23 15:36:51 +00:00
|
|
|
block = False
|
2014-10-04 09:29:18 +00:00
|
|
|
# Generate scripts per server+backend
|
2014-05-08 16:59:35 +00:00
|
|
|
for operation in operations:
|
2014-10-02 15:58:27 +00:00
|
|
|
logger.debug("Queued %s" % str(operation))
|
2015-03-04 21:06:16 +00:00
|
|
|
if operation.servers is None:
|
|
|
|
operation.servers = router.get_servers(operation, cache=cache)
|
|
|
|
for server in operation.servers:
|
2014-05-08 16:59:35 +00:00
|
|
|
key = (server, operation.backend)
|
|
|
|
if key not in scripts:
|
2015-05-05 19:42:55 +00:00
|
|
|
backend, operations = (operation.backend(), [operation])
|
|
|
|
scripts[key] = (backend, operations)
|
|
|
|
backend.set_head()
|
|
|
|
pre_prepare.send(sender=backend.__class__, backend=backend)
|
|
|
|
backend.prepare()
|
|
|
|
post_prepare.send(sender=backend.__class__, backend=backend)
|
2014-05-08 16:59:35 +00:00
|
|
|
else:
|
|
|
|
scripts[key][1].append(operation)
|
2014-10-04 09:29:18 +00:00
|
|
|
# Get and call backend action method
|
2015-03-12 14:05:23 +00:00
|
|
|
backend = scripts[key][0]
|
|
|
|
method = getattr(backend, operation.action)
|
|
|
|
kwargs = {
|
|
|
|
'sender': backend.__class__,
|
|
|
|
'backend': backend,
|
|
|
|
'instance': operation.instance,
|
|
|
|
'action': operation.action,
|
|
|
|
}
|
2015-05-05 19:42:55 +00:00
|
|
|
backend.set_content()
|
2015-03-12 14:05:23 +00:00
|
|
|
pre_action.send(**kwargs)
|
2014-05-08 16:59:35 +00:00
|
|
|
method(operation.instance)
|
2015-03-12 14:05:23 +00:00
|
|
|
post_action.send(**kwargs)
|
2015-03-23 15:36:51 +00:00
|
|
|
if backend.block:
|
|
|
|
block = True
|
2015-04-02 16:14:55 +00:00
|
|
|
for value in scripts.values():
|
2015-04-01 15:49:21 +00:00
|
|
|
backend, operations = value
|
2015-05-05 19:42:55 +00:00
|
|
|
backend.set_tail()
|
|
|
|
pre_commit.send(sender=backend.__class__, backend=backend)
|
2015-04-01 15:49:21 +00:00
|
|
|
backend.commit()
|
2015-05-05 19:42:55 +00:00
|
|
|
post_commit.send(sender=backend.__class__, backend=backend)
|
2015-04-01 15:49:21 +00:00
|
|
|
return scripts, block
|
|
|
|
|
|
|
|
|
|
|
|
def execute(scripts, block=False, async=False):
|
|
|
|
""" executes the operations on the servers """
|
2015-04-07 15:14:49 +00:00
|
|
|
if settings.ORCHESTRATION_DISABLE_EXECUTION:
|
|
|
|
logger.info('Orchestration execution is dissabled by ORCHESTRATION_DISABLE_EXECUTION settings.')
|
|
|
|
return []
|
2014-05-08 16:59:35 +00:00
|
|
|
# Execute scripts on each server
|
|
|
|
threads = []
|
|
|
|
executions = []
|
2015-04-02 16:14:55 +00:00
|
|
|
for key, value in scripts.items():
|
2014-05-08 16:59:35 +00:00
|
|
|
server, __ = key
|
|
|
|
backend, operations = value
|
|
|
|
execute = as_task(backend.execute)
|
2015-03-23 15:36:51 +00:00
|
|
|
logger.debug('%s is going to be executed on %s' % (backend, server))
|
|
|
|
if block:
|
2015-04-02 16:14:55 +00:00
|
|
|
# Execute one backend at a time, no need for threads
|
2015-03-25 15:45:04 +00:00
|
|
|
execute(server, async=async)
|
|
|
|
else:
|
|
|
|
execute = close_connection(execute)
|
|
|
|
thread = threading.Thread(target=execute, args=(server,), kwargs={'async': async})
|
|
|
|
thread.start()
|
|
|
|
threads.append(thread)
|
2014-05-08 16:59:35 +00:00
|
|
|
executions.append((execute, operations))
|
|
|
|
[ thread.join() for thread in threads ]
|
|
|
|
logs = []
|
2014-10-04 09:29:18 +00:00
|
|
|
# collect results
|
2014-05-08 16:59:35 +00:00
|
|
|
for execution, operations in executions:
|
2015-03-11 16:32:33 +00:00
|
|
|
# There is no log if an exception has been rised at the very end of the execution
|
|
|
|
if hasattr(execution, 'log'):
|
|
|
|
for operation in operations:
|
|
|
|
logger.info("Executed %s" % str(operation))
|
2015-04-07 15:14:49 +00:00
|
|
|
if operation.instance.pk:
|
|
|
|
# Not all backends are called with objects saved on the database
|
2015-04-09 14:32:10 +00:00
|
|
|
operation.store(execution.log)
|
2015-03-11 16:32:33 +00:00
|
|
|
stdout = execution.log.stdout.strip()
|
|
|
|
stdout and logger.debug('STDOUT %s', stdout)
|
|
|
|
stderr = execution.log.stderr.strip()
|
|
|
|
stderr and logger.debug('STDERR %s', stderr)
|
|
|
|
logs.append(execution.log)
|
|
|
|
else:
|
|
|
|
mocked_log = BackendLog(state=BackendLog.EXCEPTION)
|
|
|
|
logs.append(mocked_log)
|
2014-05-08 16:59:35 +00:00
|
|
|
return logs
|
2015-04-01 15:49:21 +00:00
|
|
|
|
|
|
|
|
|
|
|
def collect(instance, action, **kwargs):
|
|
|
|
""" collect operations """
|
2015-04-20 14:23:10 +00:00
|
|
|
operations = kwargs.get('operations', OrderedSet())
|
2015-04-01 15:49:21 +00:00
|
|
|
route_cache = kwargs.get('route_cache', {})
|
2015-04-14 15:22:01 +00:00
|
|
|
for backend_cls in ServiceBackend.get_backends():
|
2015-04-12 18:18:10 +00:00
|
|
|
# Check if there exists a related instance to be executed for this backend and action
|
2015-04-01 15:49:21 +00:00
|
|
|
instances = []
|
2015-04-12 18:18:10 +00:00
|
|
|
if action in backend_cls.actions:
|
|
|
|
if backend_cls.is_main(instance):
|
|
|
|
instances = [(instance, action)]
|
|
|
|
else:
|
|
|
|
candidate = backend_cls.get_related(instance)
|
|
|
|
if candidate:
|
|
|
|
if candidate.__class__.__name__ == 'ManyRelatedManager':
|
|
|
|
if 'pk_set' in kwargs:
|
|
|
|
# m2m_changed signal
|
|
|
|
candidates = kwargs['model'].objects.filter(pk__in=kwargs['pk_set'])
|
|
|
|
else:
|
|
|
|
candidates = candidate.all()
|
2015-04-01 15:49:21 +00:00
|
|
|
else:
|
2015-04-12 18:18:10 +00:00
|
|
|
candidates = [candidate]
|
|
|
|
for candidate in candidates:
|
|
|
|
# Check if a delete for candidate is in operations
|
|
|
|
delete_mock = Operation(backend_cls, candidate, Operation.DELETE)
|
|
|
|
if delete_mock not in operations:
|
|
|
|
# related objects with backend.model trigger save()
|
|
|
|
instances.append((candidate, Operation.SAVE))
|
2015-04-01 15:49:21 +00:00
|
|
|
for selected, iaction in instances:
|
|
|
|
# Maintain consistent state of operations based on save/delete behaviour
|
|
|
|
# Prevent creating a deleted selected by deleting existing saves
|
|
|
|
if iaction == Operation.DELETE:
|
2015-04-07 15:14:49 +00:00
|
|
|
save_mock = Operation(backend_cls, selected, Operation.SAVE)
|
2015-04-01 15:49:21 +00:00
|
|
|
try:
|
|
|
|
operations.remove(save_mock)
|
|
|
|
except KeyError:
|
|
|
|
pass
|
|
|
|
else:
|
|
|
|
update_fields = kwargs.get('update_fields', None)
|
|
|
|
if update_fields is not None:
|
2015-04-05 18:02:36 +00:00
|
|
|
# TODO remove this, django does not execute post_save if update_fields=[]...
|
|
|
|
# Maybe open a ticket at Djangoproject ?
|
2015-04-01 15:49:21 +00:00
|
|
|
# "update_fileds=[]" is a convention for explicitly executing backend
|
|
|
|
# i.e. account.disable()
|
|
|
|
if update_fields != []:
|
|
|
|
execute = False
|
|
|
|
for field in update_fields:
|
|
|
|
if field not in backend_cls.ignore_fields:
|
|
|
|
execute = True
|
|
|
|
break
|
|
|
|
if not execute:
|
|
|
|
continue
|
2015-04-07 15:14:49 +00:00
|
|
|
operation = Operation(backend_cls, selected, iaction)
|
2015-04-01 15:49:21 +00:00
|
|
|
# Only schedule operations if the router gives servers to execute into
|
|
|
|
servers = router.get_servers(operation, cache=route_cache)
|
|
|
|
if servers:
|
|
|
|
operation.servers = servers
|
|
|
|
if iaction != Operation.DELETE:
|
|
|
|
# usually we expect to be using last object state,
|
|
|
|
# except when we are deleting it
|
|
|
|
operations.discard(operation)
|
|
|
|
elif iaction == Operation.DELETE:
|
|
|
|
operation.preload_context()
|
|
|
|
operations.add(operation)
|
|
|
|
return operations
|