X-Git-Url: https://code.grnet.gr/git/ganeti-local/blobdiff_plain/daba67c785a583b30528b51549db165885b3ba59..8d99a8bf5852330464f87b40b96d6fe80e7cdbf5:/lib/jqueue.py?ds=sidebyside diff --git a/lib/jqueue.py b/lib/jqueue.py index 4126db7..d137443 100644 --- a/lib/jqueue.py +++ b/lib/jqueue.py @@ -29,7 +29,6 @@ used by all other classes in this module. """ -import os import logging import errno import re @@ -53,6 +52,7 @@ from ganeti import mcpu from ganeti import utils from ganeti import jstore from ganeti import rpc +from ganeti import runtime from ganeti import netutils from ganeti import compat @@ -94,7 +94,7 @@ class _QueuedOpCode(object): @ivar stop_timestamp: timestamp for the end of the execution """ - __slots__ = ["input", "status", "result", "log", + __slots__ = ["input", "status", "result", "log", "priority", "start_timestamp", "exec_timestamp", "end_timestamp", "__weakref__"] @@ -113,6 +113,9 @@ class _QueuedOpCode(object): self.exec_timestamp = None self.end_timestamp = None + # Get initial priority (it might change during the lifetime of this opcode) + self.priority = getattr(op, "priority", constants.OP_PRIO_DEFAULT) + @classmethod def Restore(cls, state): """Restore the _QueuedOpCode from the serialized form. @@ -131,6 +134,7 @@ class _QueuedOpCode(object): obj.start_timestamp = state.get("start_timestamp", None) obj.exec_timestamp = state.get("exec_timestamp", None) obj.end_timestamp = state.get("end_timestamp", None) + obj.priority = state.get("priority", constants.OP_PRIO_DEFAULT) return obj def Serialize(self): @@ -148,6 +152,7 @@ class _QueuedOpCode(object): "start_timestamp": self.start_timestamp, "exec_timestamp": self.exec_timestamp, "end_timestamp": self.end_timestamp, + "priority": self.priority, } @@ -170,7 +175,7 @@ class _QueuedJob(object): """ # pylint: disable-msg=W0212 - __slots__ = ["queue", "id", "ops", "log_serial", + __slots__ = ["queue", "id", "ops", "log_serial", "ops_iter", "cur_opctx", "received_timestamp", "start_timestamp", "end_timestamp", "__weakref__"] @@ -197,6 +202,16 @@ class _QueuedJob(object): self.start_timestamp = None self.end_timestamp = None + self._InitInMemory(self) + + @staticmethod + def _InitInMemory(obj): + """Initializes in-memory variables. + + """ + obj.ops_iter = None + obj.cur_opctx = None + def __repr__(self): status = ["%s.%s" % (self.__class__.__module__, self.__class__.__name__), "id=%s" % self.id, @@ -231,6 +246,8 @@ class _QueuedJob(object): obj.log_serial = max(obj.log_serial, log_entry[0]) obj.ops.append(op) + cls._InitInMemory(obj) + return obj def Serialize(self): @@ -301,6 +318,24 @@ class _QueuedJob(object): return status + def CalcPriority(self): + """Gets the current priority for this job. + + Only unfinished opcodes are considered. When all are done, the default + priority is used. + + @rtype: int + + """ + priorities = [op.priority for op in self.ops + if op.status not in constants.OPS_FINALIZED] + + if not priorities: + # All opcodes are done, assume default priority + return constants.OP_PRIO_DEFAULT + + return min(priorities) + def GetLogEntries(self, newer_than): """Selectively returns the log entries. @@ -340,6 +375,8 @@ class _QueuedJob(object): row.append(self.id) elif fname == "status": row.append(self.CalcStatus()) + elif fname == "priority": + row.append(self.CalcPriority()) elif fname == "ops": row.append([op.input.__getstate__() for op in self.ops]) elif fname == "opresult": @@ -354,6 +391,8 @@ class _QueuedJob(object): row.append([op.exec_timestamp for op in self.ops]) elif fname == "opend": row.append([op.end_timestamp for op in self.ops]) + elif fname == "oppriority": + row.append([op.priority for op in self.ops]) elif fname == "received_ts": row.append(self.received_timestamp) elif fname == "start_ts": @@ -377,17 +416,38 @@ class _QueuedJob(object): @param result: the opcode result """ - try: - not_marked = True - for op in self.ops: - if op.status in constants.OPS_FINALIZED: - assert not_marked, "Finalized opcodes found after non-finalized ones" - continue - op.status = status - op.result = result - not_marked = False - finally: - self.queue.UpdateJobUnlocked(self) + not_marked = True + for op in self.ops: + if op.status in constants.OPS_FINALIZED: + assert not_marked, "Finalized opcodes found after non-finalized ones" + continue + op.status = status + op.result = result + not_marked = False + + def Cancel(self): + """Marks job as canceled/-ing if possible. + + @rtype: tuple; (bool, string) + @return: Boolean describing whether job was successfully canceled or marked + as canceling and a text message + + """ + status = self.CalcStatus() + + if status == constants.JOB_STATUS_QUEUED: + self.MarkUnfinishedOps(constants.OP_STATUS_CANCELED, + "Job canceled by request") + return (True, "Job %s canceled" % self.id) + + elif status == constants.JOB_STATUS_WAITLOCK: + # The worker will notice the new status and cancel the job + self.MarkUnfinishedOps(constants.OP_STATUS_CANCELING, None) + return (True, "Job %s will be canceled" % self.id) + + else: + logging.debug("Job %s is no longer waiting in the queue", self.id) + return (False, "Job %s is no longer waiting in the queue" % self.id) class _OpExecCallbacks(mcpu.OpExecCbBase): @@ -470,10 +530,8 @@ class _OpExecCallbacks(mcpu.OpExecCbBase): timestamp = utils.SplitTime(time.time()) self._AppendFeedback(timestamp, log_type, log_msg) - def ReportLocks(self, msg): - """Write locking information to the job. - - Called whenever the LU processor is waiting for a lock or has acquired one. + def CheckCancel(self): + """Check whether job has been cancelled. """ assert self._op.status in (constants.OP_STATUS_WAITLOCK, @@ -482,6 +540,15 @@ class _OpExecCallbacks(mcpu.OpExecCbBase): # Cancel here if we were asked to self._CheckCancel() + def SubmitManyJobs(self, jobs): + """Submits jobs for processing. + + See L{JobQueue.SubmitManyJobs}. + + """ + # Locking is done in job queue + return self._queue.SubmitManyJobs(jobs) + class _JobChangesChecker(object): def __init__(self, fields, prev_job_info, prev_log_serial): @@ -676,6 +743,383 @@ class _WaitForJobChangesHelper(object): return constants.JOB_NOTCHANGED +def _EncodeOpError(err): + """Encodes an error which occurred while processing an opcode. + + """ + if isinstance(err, errors.GenericError): + to_encode = err + else: + to_encode = errors.OpExecError(str(err)) + + return errors.EncodeException(to_encode) + + +class _TimeoutStrategyWrapper: + def __init__(self, fn): + """Initializes this class. + + """ + self._fn = fn + self._next = None + + def _Advance(self): + """Gets the next timeout if necessary. + + """ + if self._next is None: + self._next = self._fn() + + def Peek(self): + """Returns the next timeout. + + """ + self._Advance() + return self._next + + def Next(self): + """Returns the current timeout and advances the internal state. + + """ + self._Advance() + result = self._next + self._next = None + return result + + +class _OpExecContext: + def __init__(self, op, index, log_prefix, timeout_strategy_factory): + """Initializes this class. + + """ + self.op = op + self.index = index + self.log_prefix = log_prefix + self.summary = op.input.Summary() + + self._timeout_strategy_factory = timeout_strategy_factory + self._ResetTimeoutStrategy() + + def _ResetTimeoutStrategy(self): + """Creates a new timeout strategy. + + """ + self._timeout_strategy = \ + _TimeoutStrategyWrapper(self._timeout_strategy_factory().NextAttempt) + + def CheckPriorityIncrease(self): + """Checks whether priority can and should be increased. + + Called when locks couldn't be acquired. + + """ + op = self.op + + # Exhausted all retries and next round should not use blocking acquire + # for locks? + if (self._timeout_strategy.Peek() is None and + op.priority > constants.OP_PRIO_HIGHEST): + logging.debug("Increasing priority") + op.priority -= 1 + self._ResetTimeoutStrategy() + return True + + return False + + def GetNextLockTimeout(self): + """Returns the next lock acquire timeout. + + """ + return self._timeout_strategy.Next() + + +class _JobProcessor(object): + def __init__(self, queue, opexec_fn, job, + _timeout_strategy_factory=mcpu.LockAttemptTimeoutStrategy): + """Initializes this class. + + """ + self.queue = queue + self.opexec_fn = opexec_fn + self.job = job + self._timeout_strategy_factory = _timeout_strategy_factory + + @staticmethod + def _FindNextOpcode(job, timeout_strategy_factory): + """Locates the next opcode to run. + + @type job: L{_QueuedJob} + @param job: Job object + @param timeout_strategy_factory: Callable to create new timeout strategy + + """ + # Create some sort of a cache to speed up locating next opcode for future + # lookups + # TODO: Consider splitting _QueuedJob.ops into two separate lists, one for + # pending and one for processed ops. + if job.ops_iter is None: + job.ops_iter = enumerate(job.ops) + + # Find next opcode to run + while True: + try: + (idx, op) = job.ops_iter.next() + except StopIteration: + raise errors.ProgrammerError("Called for a finished job") + + if op.status == constants.OP_STATUS_RUNNING: + # Found an opcode already marked as running + raise errors.ProgrammerError("Called for job marked as running") + + opctx = _OpExecContext(op, idx, "Op %s/%s" % (idx + 1, len(job.ops)), + timeout_strategy_factory) + + if op.status == constants.OP_STATUS_CANCELED: + # Cancelled jobs are handled by the caller + assert not compat.any(i.status != constants.OP_STATUS_CANCELED + for i in job.ops[idx:]) + + elif op.status in constants.OPS_FINALIZED: + # This is a job that was partially completed before master daemon + # shutdown, so it can be expected that some opcodes are already + # completed successfully (if any did error out, then the whole job + # should have been aborted and not resubmitted for processing). + logging.info("%s: opcode %s already processed, skipping", + opctx.log_prefix, opctx.summary) + continue + + return opctx + + @staticmethod + def _MarkWaitlock(job, op): + """Marks an opcode as waiting for locks. + + The job's start timestamp is also set if necessary. + + @type job: L{_QueuedJob} + @param job: Job object + @type op: L{_QueuedOpCode} + @param op: Opcode object + + """ + assert op in job.ops + assert op.status in (constants.OP_STATUS_QUEUED, + constants.OP_STATUS_WAITLOCK) + + update = False + + op.result = None + + if op.status == constants.OP_STATUS_QUEUED: + op.status = constants.OP_STATUS_WAITLOCK + update = True + + if op.start_timestamp is None: + op.start_timestamp = TimeStampNow() + update = True + + if job.start_timestamp is None: + job.start_timestamp = op.start_timestamp + update = True + + assert op.status == constants.OP_STATUS_WAITLOCK + + return update + + def _ExecOpCodeUnlocked(self, opctx): + """Processes one opcode and returns the result. + + """ + op = opctx.op + + assert op.status == constants.OP_STATUS_WAITLOCK + + timeout = opctx.GetNextLockTimeout() + + try: + # Make sure not to hold queue lock while calling ExecOpCode + result = self.opexec_fn(op.input, + _OpExecCallbacks(self.queue, self.job, op), + timeout=timeout, priority=op.priority) + except mcpu.LockAcquireTimeout: + assert timeout is not None, "Received timeout for blocking acquire" + logging.debug("Couldn't acquire locks in %0.6fs", timeout) + + assert op.status in (constants.OP_STATUS_WAITLOCK, + constants.OP_STATUS_CANCELING) + + # Was job cancelled while we were waiting for the lock? + if op.status == constants.OP_STATUS_CANCELING: + return (constants.OP_STATUS_CANCELING, None) + + # Stay in waitlock while trying to re-acquire lock + return (constants.OP_STATUS_WAITLOCK, None) + except CancelJob: + logging.exception("%s: Canceling job", opctx.log_prefix) + assert op.status == constants.OP_STATUS_CANCELING + return (constants.OP_STATUS_CANCELING, None) + except Exception, err: # pylint: disable-msg=W0703 + logging.exception("%s: Caught exception in %s", + opctx.log_prefix, opctx.summary) + return (constants.OP_STATUS_ERROR, _EncodeOpError(err)) + else: + logging.debug("%s: %s successful", + opctx.log_prefix, opctx.summary) + return (constants.OP_STATUS_SUCCESS, result) + + def __call__(self, _nextop_fn=None): + """Continues execution of a job. + + @param _nextop_fn: Callback function for tests + @rtype: bool + @return: True if job is finished, False if processor needs to be called + again + + """ + queue = self.queue + job = self.job + + logging.debug("Processing job %s", job.id) + + queue.acquire(shared=1) + try: + opcount = len(job.ops) + + # Is a previous opcode still pending? + if job.cur_opctx: + opctx = job.cur_opctx + job.cur_opctx = None + else: + if __debug__ and _nextop_fn: + _nextop_fn() + opctx = self._FindNextOpcode(job, self._timeout_strategy_factory) + + op = opctx.op + + # Consistency check + assert compat.all(i.status in (constants.OP_STATUS_QUEUED, + constants.OP_STATUS_CANCELING, + constants.OP_STATUS_CANCELED) + for i in job.ops[opctx.index + 1:]) + + assert op.status in (constants.OP_STATUS_QUEUED, + constants.OP_STATUS_WAITLOCK, + constants.OP_STATUS_CANCELING, + constants.OP_STATUS_CANCELED) + + assert (op.priority <= constants.OP_PRIO_LOWEST and + op.priority >= constants.OP_PRIO_HIGHEST) + + if op.status not in (constants.OP_STATUS_CANCELING, + constants.OP_STATUS_CANCELED): + assert op.status in (constants.OP_STATUS_QUEUED, + constants.OP_STATUS_WAITLOCK) + + # Prepare to start opcode + if self._MarkWaitlock(job, op): + # Write to disk + queue.UpdateJobUnlocked(job) + + assert op.status == constants.OP_STATUS_WAITLOCK + assert job.CalcStatus() == constants.JOB_STATUS_WAITLOCK + assert job.start_timestamp and op.start_timestamp + + logging.info("%s: opcode %s waiting for locks", + opctx.log_prefix, opctx.summary) + + queue.release() + try: + (op_status, op_result) = self._ExecOpCodeUnlocked(opctx) + finally: + queue.acquire(shared=1) + + op.status = op_status + op.result = op_result + + if op.status == constants.OP_STATUS_WAITLOCK: + # Couldn't get locks in time + assert not op.end_timestamp + else: + # Finalize opcode + op.end_timestamp = TimeStampNow() + + if op.status == constants.OP_STATUS_CANCELING: + assert not compat.any(i.status != constants.OP_STATUS_CANCELING + for i in job.ops[opctx.index:]) + else: + assert op.status in constants.OPS_FINALIZED + + if op.status == constants.OP_STATUS_WAITLOCK: + finalize = False + + if opctx.CheckPriorityIncrease(): + # Priority was changed, need to update on-disk file + queue.UpdateJobUnlocked(job) + + # Keep around for another round + job.cur_opctx = opctx + + assert (op.priority <= constants.OP_PRIO_LOWEST and + op.priority >= constants.OP_PRIO_HIGHEST) + + # In no case must the status be finalized here + assert job.CalcStatus() == constants.JOB_STATUS_WAITLOCK + + else: + # Ensure all opcodes so far have been successful + assert (opctx.index == 0 or + compat.all(i.status == constants.OP_STATUS_SUCCESS + for i in job.ops[:opctx.index])) + + # Reset context + job.cur_opctx = None + + if op.status == constants.OP_STATUS_SUCCESS: + finalize = False + + elif op.status == constants.OP_STATUS_ERROR: + # Ensure failed opcode has an exception as its result + assert errors.GetEncodedError(job.ops[opctx.index].result) + + to_encode = errors.OpExecError("Preceding opcode failed") + job.MarkUnfinishedOps(constants.OP_STATUS_ERROR, + _EncodeOpError(to_encode)) + finalize = True + + # Consistency check + assert compat.all(i.status == constants.OP_STATUS_ERROR and + errors.GetEncodedError(i.result) + for i in job.ops[opctx.index:]) + + elif op.status == constants.OP_STATUS_CANCELING: + job.MarkUnfinishedOps(constants.OP_STATUS_CANCELED, + "Job canceled by request") + finalize = True + + elif op.status == constants.OP_STATUS_CANCELED: + finalize = True + + else: + raise errors.ProgrammerError("Unknown status '%s'" % op.status) + + # Finalizing or last opcode? + if finalize or opctx.index == (opcount - 1): + # All opcodes have been run, finalize job + job.end_timestamp = TimeStampNow() + + # Write to disk. If the job status is final, this is the final write + # allowed. Once the file has been written, it can be archived anytime. + queue.UpdateJobUnlocked(job) + + if finalize or opctx.index == (opcount - 1): + logging.info("Finished job %s, status = %s", job.id, job.CalcStatus()) + return True + + return False + finally: + queue.release() + + class _JobQueueWorker(workerpool.BaseWorker): """The actual job workers. @@ -683,129 +1127,58 @@ class _JobQueueWorker(workerpool.BaseWorker): def RunTask(self, job): # pylint: disable-msg=W0221 """Job executor. - This functions processes a job. It is closely tied to the _QueuedJob and - _QueuedOpCode classes. + This functions processes a job. It is closely tied to the L{_QueuedJob} and + L{_QueuedOpCode} classes. @type job: L{_QueuedJob} @param job: the job to be processed """ - self.SetTaskName("Job%s" % job.id) - - logging.info("Processing job %s", job.id) - proc = mcpu.Processor(self.pool.queue.context, job.id) queue = job.queue + assert queue == self.pool.queue + + setname_fn = lambda op: self.SetTaskName(self._GetWorkerName(job, op)) + setname_fn(None) + + proc = mcpu.Processor(queue.context, job.id) + + # Create wrapper for setting thread name + wrap_execop_fn = compat.partial(self._WrapExecOpCode, setname_fn, + proc.ExecOpCode) + + if not _JobProcessor(queue, wrap_execop_fn, job)(): + # Schedule again + raise workerpool.DeferTask(priority=job.CalcPriority()) + + @staticmethod + def _WrapExecOpCode(setname_fn, execop_fn, op, *args, **kwargs): + """Updates the worker thread name to include a short summary of the opcode. + + @param setname_fn: Callable setting worker thread name + @param execop_fn: Callable for executing opcode (usually + L{mcpu.Processor.ExecOpCode}) + + """ + setname_fn(op) try: - try: - count = len(job.ops) - for idx, op in enumerate(job.ops): - op_summary = op.input.Summary() - if op.status == constants.OP_STATUS_SUCCESS: - # this is a job that was partially completed before master - # daemon shutdown, so it can be expected that some opcodes - # are already completed successfully (if any did error - # out, then the whole job should have been aborted and not - # resubmitted for processing) - logging.info("Op %s/%s: opcode %s already processed, skipping", - idx + 1, count, op_summary) - continue - try: - logging.info("Op %s/%s: Starting opcode %s", idx + 1, count, - op_summary) - - queue.acquire(shared=1) - try: - if op.status == constants.OP_STATUS_CANCELED: - logging.debug("Canceling opcode") - raise CancelJob() - assert op.status == constants.OP_STATUS_QUEUED - logging.debug("Opcode %s/%s waiting for locks", - idx + 1, count) - op.status = constants.OP_STATUS_WAITLOCK - op.result = None - op.start_timestamp = TimeStampNow() - if idx == 0: # first opcode - job.start_timestamp = op.start_timestamp - queue.UpdateJobUnlocked(job) - - input_opcode = op.input - finally: - queue.release() - - # Make sure not to hold queue lock while calling ExecOpCode - result = proc.ExecOpCode(input_opcode, - _OpExecCallbacks(queue, job, op)) - - queue.acquire(shared=1) - try: - logging.debug("Opcode %s/%s succeeded", idx + 1, count) - op.status = constants.OP_STATUS_SUCCESS - op.result = result - op.end_timestamp = TimeStampNow() - if idx == count - 1: - job.end_timestamp = TimeStampNow() - - # Consistency check - assert compat.all(i.status == constants.OP_STATUS_SUCCESS - for i in job.ops) - - queue.UpdateJobUnlocked(job) - finally: - queue.release() - - logging.info("Op %s/%s: Successfully finished opcode %s", - idx + 1, count, op_summary) - except CancelJob: - # Will be handled further up - raise - except Exception, err: - queue.acquire(shared=1) - try: - try: - logging.debug("Opcode %s/%s failed", idx + 1, count) - op.status = constants.OP_STATUS_ERROR - if isinstance(err, errors.GenericError): - to_encode = err - else: - to_encode = errors.OpExecError(str(err)) - op.result = errors.EncodeException(to_encode) - op.end_timestamp = TimeStampNow() - logging.info("Op %s/%s: Error in opcode %s: %s", - idx + 1, count, op_summary, err) - - to_encode = errors.OpExecError("Preceding opcode failed") - job.MarkUnfinishedOps(constants.OP_STATUS_ERROR, - errors.EncodeException(to_encode)) - - # Consistency check - assert compat.all(i.status == constants.OP_STATUS_SUCCESS - for i in job.ops[:idx]) - assert compat.all(i.status == constants.OP_STATUS_ERROR and - errors.GetEncodedError(i.result) - for i in job.ops[idx:]) - finally: - job.end_timestamp = TimeStampNow() - queue.UpdateJobUnlocked(job) - finally: - queue.release() - raise - - except CancelJob: - queue.acquire(shared=1) - try: - job.MarkUnfinishedOps(constants.OP_STATUS_CANCELED, - "Job canceled by request") - job.end_timestamp = TimeStampNow() - queue.UpdateJobUnlocked(job) - finally: - queue.release() - except errors.GenericError, err: - logging.exception("Ganeti exception") - except: - logging.exception("Unhandled exception") + return execop_fn(op, *args, **kwargs) finally: - status = job.CalcStatus() - logging.info("Finished job %s, status = %s", job.id, status) + setname_fn(None) + + @staticmethod + def _GetWorkerName(job, op): + """Sets the worker thread name. + + @type job: L{_QueuedJob} + @type op: L{opcodes.OpCode} + + """ + parts = ["Job%s" % job.id] + + if op: + parts.append(op.TinySummary()) + + return "/".join(parts) class _JobQueueWorkerPool(workerpool.WorkerPool): @@ -813,7 +1186,7 @@ class _JobQueueWorkerPool(workerpool.WorkerPool): """ def __init__(self, queue): - super(_JobQueueWorkerPool, self).__init__("JobQueue", + super(_JobQueueWorkerPool, self).__init__("Jq", JOBQUEUE_THREADS, _JobQueueWorker) self.queue = queue @@ -867,7 +1240,7 @@ class JobQueue(object): """ self.context = context self._memcache = weakref.WeakValueDictionary() - self._my_hostname = netutils.HostInfo().name + self._my_hostname = netutils.Hostname.GetSysName() # The Big JobQueue lock. If a code block or method acquires it in shared # mode safe it must guarantee concurrency with all the code acquiring it in @@ -900,52 +1273,71 @@ class JobQueue(object): self._queue_size = 0 self._UpdateQueueSizeUnlocked() - self._drained = self._IsQueueMarkedDrain() + self._drained = jstore.CheckDrainFlag() # Setup worker pool self._wpool = _JobQueueWorkerPool(self) try: - # We need to lock here because WorkerPool.AddTask() may start a job while - # we're still doing our work. - self.acquire() - try: - logging.info("Inspecting job queue") + self._InspectQueue() + except: + self._wpool.TerminateWorkers() + raise + + @locking.ssynchronized(_LOCK) + @_RequireOpenQueue + def _InspectQueue(self): + """Loads the whole job queue and resumes unfinished jobs. - all_job_ids = self._GetJobIDsUnlocked() - jobs_count = len(all_job_ids) + This function needs the lock here because WorkerPool.AddTask() may start a + job while we're still doing our work. + + """ + logging.info("Inspecting job queue") + + restartjobs = [] + + all_job_ids = self._GetJobIDsUnlocked() + jobs_count = len(all_job_ids) + lastinfo = time.time() + for idx, job_id in enumerate(all_job_ids): + # Give an update every 1000 jobs or 10 seconds + if (idx % 1000 == 0 or time.time() >= (lastinfo + 10.0) or + idx == (jobs_count - 1)): + logging.info("Job queue inspection: %d/%d (%0.1f %%)", + idx, jobs_count - 1, 100.0 * (idx + 1) / jobs_count) lastinfo = time.time() - for idx, job_id in enumerate(all_job_ids): - # Give an update every 1000 jobs or 10 seconds - if (idx % 1000 == 0 or time.time() >= (lastinfo + 10.0) or - idx == (jobs_count - 1)): - logging.info("Job queue inspection: %d/%d (%0.1f %%)", - idx, jobs_count - 1, 100.0 * (idx + 1) / jobs_count) - lastinfo = time.time() - job = self._LoadJobUnlocked(job_id) + job = self._LoadJobUnlocked(job_id) - # a failure in loading the job can cause 'None' to be returned - if job is None: - continue + # a failure in loading the job can cause 'None' to be returned + if job is None: + continue - status = job.CalcStatus() + status = job.CalcStatus() - if status in (constants.JOB_STATUS_QUEUED, ): - self._wpool.AddTask((job, )) + if status == constants.JOB_STATUS_QUEUED: + restartjobs.append(job) - elif status in (constants.JOB_STATUS_RUNNING, - constants.JOB_STATUS_WAITLOCK, - constants.JOB_STATUS_CANCELING): - logging.warning("Unfinished job %s found: %s", job.id, job) - job.MarkUnfinishedOps(constants.OP_STATUS_ERROR, - "Unclean master daemon shutdown") + elif status in (constants.JOB_STATUS_RUNNING, + constants.JOB_STATUS_WAITLOCK, + constants.JOB_STATUS_CANCELING): + logging.warning("Unfinished job %s found: %s", job.id, job) - logging.info("Job queue inspection finished") - finally: - self.release() - except: - self._wpool.TerminateWorkers() - raise + if status == constants.JOB_STATUS_WAITLOCK: + # Restart job + job.MarkUnfinishedOps(constants.OP_STATUS_QUEUED, None) + restartjobs.append(job) + else: + job.MarkUnfinishedOps(constants.OP_STATUS_ERROR, + "Unclean master daemon shutdown") + + self.UpdateJobUnlocked(job) + + if restartjobs: + logging.info("Restarting %s jobs", len(restartjobs)) + self._EnqueueJobs(restartjobs) + + logging.info("Job queue inspection finished") @locking.ssynchronized(_LOCK) @_RequireOpenQueue @@ -1062,7 +1454,9 @@ class JobQueue(object): @param replicate: whether to spread the changes to the remote nodes """ - utils.WriteFile(file_name, data=data) + getents = runtime.GetEnts() + utils.WriteFile(file_name, data=data, uid=getents.masterd_uid, + gid=getents.masterd_gid) if replicate: names, addrs = self._GetNodeIp() @@ -1278,19 +1672,6 @@ class JobQueue(object): logging.exception("Can't load/parse job %s", job_id) return None - @staticmethod - def _IsQueueMarkedDrain(): - """Check if the queue is marked from drain. - - This currently uses the queue drain file, which makes it a - per-node flag. In the future this can be moved to the config file. - - @rtype: boolean - @return: True of the job queue is marked for draining - - """ - return os.path.exists(constants.JOB_QUEUE_DRAIN_FILE) - def _UpdateQueueSizeUnlocked(self): """Update the queue size. @@ -1306,10 +1687,7 @@ class JobQueue(object): @param drain_flag: Whether to set or unset the drain flag """ - if drain_flag: - utils.WriteFile(constants.JOB_QUEUE_DRAIN_FILE, data="", close=True) - else: - utils.RemoveFile(constants.JOB_QUEUE_DRAIN_FILE) + jstore.SetDrainFlag(drain_flag) self._drained = drain_flag @@ -1330,6 +1708,7 @@ class JobQueue(object): @return: the job object to be queued @raise errors.JobQueueDrainError: if the job queue is marked for draining @raise errors.JobQueueFull: if the job queue has too many jobs in it + @raise errors.GenericError: If an opcode is not valid """ # Ok when sharing the big job queue lock, as the drain file is created when @@ -1342,6 +1721,13 @@ class JobQueue(object): job = _QueuedJob(self, job_id, ops) + # Check priority + for idx, op in enumerate(job.ops): + if op.priority not in constants.OP_PRIO_SUBMIT_VALID: + allowed = utils.CommaJoin(constants.OP_PRIO_SUBMIT_VALID) + raise errors.GenericError("Opcode %s has invalid priority %s, allowed" + " are %s" % (idx, op.priority, allowed)) + # Write to disk self.UpdateJobUnlocked(job) @@ -1361,7 +1747,7 @@ class JobQueue(object): """ job_id = self._NewSerialsUnlocked(1)[0] - self._wpool.AddTask((self._SubmitJobUnlocked(job_id, ops), )) + self._EnqueueJobs([self._SubmitJobUnlocked(job_id, ops)]) return job_id @locking.ssynchronized(_LOCK) @@ -1373,21 +1759,33 @@ class JobQueue(object): """ results = [] - tasks = [] + added_jobs = [] all_job_ids = self._NewSerialsUnlocked(len(jobs)) for job_id, ops in zip(all_job_ids, jobs): try: - tasks.append((self._SubmitJobUnlocked(job_id, ops), )) + added_jobs.append(self._SubmitJobUnlocked(job_id, ops)) status = True data = job_id except errors.GenericError, err: - data = str(err) + data = ("%s; opcodes %s" % + (err, utils.CommaJoin(op.Summary() for op in ops))) status = False results.append((status, data)) - self._wpool.AddManyTasks(tasks) + + self._EnqueueJobs(added_jobs) return results + def _EnqueueJobs(self, jobs): + """Helper function to add jobs to worker pool's queue. + + @type jobs: list + @param jobs: List of all jobs + + """ + self._wpool.AddManyTasks([(job, ) for job in jobs], + priority=[job.CalcPriority() for job in jobs]) + @_RequireOpenQueue def UpdateJobUnlocked(self, job, replicate=True): """Update a job's on disk storage. @@ -1456,22 +1854,12 @@ class JobQueue(object): logging.debug("Job %s not found", job_id) return (False, "Job %s not found" % job_id) - job_status = job.CalcStatus() - - if job_status not in (constants.JOB_STATUS_QUEUED, - constants.JOB_STATUS_WAITLOCK): - logging.debug("Job %s is no longer waiting in the queue", job.id) - return (False, "Job %s is no longer waiting in the queue" % job.id) + (success, msg) = job.Cancel() - if job_status == constants.JOB_STATUS_QUEUED: - job.MarkUnfinishedOps(constants.OP_STATUS_CANCELED, - "Job canceled by request") - return (True, "Job %s canceled" % job.id) + if success: + self.UpdateJobUnlocked(job) - elif job_status == constants.JOB_STATUS_WAITLOCK: - # The worker will notice the new status and cancel the job - job.MarkUnfinishedOps(constants.OP_STATUS_CANCELING, None) - return (True, "Job %s will be canceled" % job.id) + return (success, msg) @_RequireOpenQueue def _ArchiveJobsUnlocked(self, jobs):