def manage_manual_action(): logger.debug("manage_manual_action") try: #Create lock file to prevent more than one celery task for manual action (export or archive) lockfile = '/var/run/celery/manual_action.lock' applock = ApplicationLock(lockfile) if not(applock.lock()): logger.debug("failed to acquire lock file: %d" % os.getpid()) logger.info("manage_manual_action task still executing") return logger.info("Worker PID %d lockfile created %s" % (os.getpid(),lockfile)) except Exception as e: logger.exception(e) try: # # Check for manually selected Archive and Export actions - action_state == 'SA' or 'SE' # These jobs should execute even when auto action is disabled. # Note: manual actions will not be executed in the order they are selected, but by age. # user_comment = "Manual Action" manualSelects = DMFileStat.objects.filter(action_state__in=['SA','SE']).order_by('created') if manualSelects.exists(): actiondmfilestat = manualSelects[0] dmfileset = actiondmfilestat.dmfileset project_msg = {} msg_dict = {} msg_dict[dmfileset.type] = "Success" project_msg[actiondmfilestat.result_id] = msg_dict if actiondmfilestat.action_state == 'SA': logger.info("Manual Archive Action: %s from %s" % (dmfileset.type,actiondmfilestat.result.resultsName)) archive_action('dm_agent', user_comment, actiondmfilestat) datatasks.project_msg_banner('', project_msg, dmactions.ARCHIVE) elif actiondmfilestat.action_state == 'SE': logger.info("Manual Export Action: %s from %s" % (dmfileset.type,actiondmfilestat.result.resultsName)) export_action('dm_agent', user_comment, actiondmfilestat) datatasks.project_msg_banner('', project_msg, dmactions.EXPORT) else: logger.warn("Dev Error: we don't handle this '%s' here" % actiondmfilestat.action_state) return #NOTE: all these exceptions are also handled in manage_data() below. beaucoup de duplication de code except (DMExceptions.FilePermission, DMExceptions.InsufficientDiskSpace, DMExceptions.MediaNotSet, DMExceptions.MediaNotAvailable, DMExceptions.FilesInUse) as e: message = Message.objects.filter(tags__contains=e.tag) if not message: Message.error(e.message,tags=e.tag) #TODO: TS-6525: This logentry will repeat for an Archive action every 30 seconds until the cause of the exception is fixed. #at least, while the message banner is raised, suppress additional Log Entries. dmactions.add_eventlog(actiondmfilestat, "%s - %s" % (dmfileset.type, e.message), username='******') # Revert this dmfilestat object action-state to Local actiondmfilestat.setactionstate('L') except DMExceptions.SrcDirDoesNotExist as e: if actiondmfilestat.dmfileset.type == dmactions_types.SIG: msg = "Src Dir not found: %s. Setting action_state to Deleted" % e.message EventLog.objects.add_entry(actiondmfilestat.result,msg,username='******') actiondmfilestat.setactionstate('DD') logger.info(msg) except Exception as e: msg = "action error on %s " % actiondmfilestat.result.resultsName msg += " Error: %s" % str(e) logger.exception("%s - %s" % (dmfileset.type, msg)) dmactions.add_eventlog(actiondmfilestat,"%s - %s" % (dmfileset.type, msg),username='******') # Revert this dmfilestat object action-state to Local actiondmfilestat.setactionstate('L') finally: applock.unlock() logger.debug("(%05d,%05d) Release lock file" % (os.getppid(),os.getpid())) return
def manage_data(deviceid, dmfileset, pathlist, auto_acknowledge_enabled, auto_action_enabled): logger.debug("manage_data: %s %s %s" % (dmfileset['auto_action'], hex(deviceid),dmfileset['type'])) def getfirstnotpreserved(dmfilestats, action): '''QuerySet of DMFileStat objects. Returns first instance of an object with preserve_data set to False and passes the action_validation test''' logger.debug("Function: %s()" % sys._getframe().f_code.co_name) logger.debug("Looking at %d dmfilestat objects" % dmfilestats.count()) for archiveme in dmfilestats: if not archiveme.getpreserved(): try: dmactions.action_validation(archiveme,action) return archiveme except: logger.debug("%s Failed action_validation. Try next fileset" % archiveme.result.resultsName) else: logger.debug("Skipped a preserved fileset: %s" % archiveme.result.resultsName) logger.info("%d filestat objects are preserved." % dmfilestats.count()) raise DMExceptions.NoDMFileStat("NONE FOUND") try: #logger.debug("manage_data lock for %s (%d)" % (dmfileset['type'], os.getpid())) #Create lock file to prevent more than one celery task for each process_type and partition uniqid = "%s_%s" % (hex(deviceid),slugify(dmfileset['type'])) lockfile = '/var/run/celery/%s.lock' % (uniqid) applock = ApplicationLock(lockfile) if not(applock.lock()): logger.info("failed to acquire lock file: %s" % uniqid) return logger.info("Worker PID %d lockfile created %s" % (os.getpid(),uniqid)) except Exception as e: logger.exception(e) try: #--------------------------------------------------------------------------- # Database object filtering #--------------------------------------------------------------------------- actiondmfilestat = None user_comment = "Auto Action" # Order by incrementing pk. This puts them in chronological order. # Select DMFileStat objects of category DMFileSet.type (1/4th of all objects) dmfilestats = DMFileStat.objects.filter(dmfileset__type=dmfileset['type']).order_by('created') tot_obj = dmfilestats.count() # Select objects not yet processed dmfilestats = dmfilestats.filter(action_state__in=['L','S','N','A']) tot_act = dmfilestats.count() logger.info("(%s)Total %s: %d Active: %d" %(hex(deviceid),dmfileset['type'],tot_obj,tot_act)) # Select objects that are old enough threshdate = datetime.now(pytz.UTC) - timedelta(days=dmfileset['auto_trigger_age']) dmfilestats = dmfilestats.filter(created__lt=threshdate) # Select objects stored on the deviceid query = Q() for path in pathlist: if dmfileset['type'] == dmactions_types.SIG: dmfilestats = dmfilestats.filter(result__experiment__expDir__startswith=path) else: dmfilestats = dmfilestats.filter(result__reportstorage__dirPath__startswith=path) # query |= Q(result__experiment__expDir__startswith=path) | Q(result__reportstorage__dirPath__startswith=path) #dmfilestats = dmfilestats.filter(query) #--------------------------------------------------------------------------- # Archive #--------------------------------------------------------------------------- if dmfileset['auto_action'] == 'ARC': ''' Rules: 1) archive a fileset as soon as age threshold reached, regardless of disk threshold 2) select oldest fileset 3) select unprocessed fileset 4) Archiving does not require 'S' -> 'N' -> 'A' progression before action 5) Do not include filesets marked 'E' in auto-action - can get stuck on that fileset forever ''' logger.info("(%s)Exceed %d days. Eligible to archive: %d" %(hex(deviceid),dmfileset['auto_trigger_age'],dmfilestats.count())) actiondmfilestat = None #Bail out if disabled if auto_action_enabled != True: logger.info("Data management auto-action is disabled.") return # Select first object stored on the deviceid try: actiondmfilestat = getfirstnotpreserved(dmfilestats, dmactions.ARCHIVE) logger.info("(%s)Picked: %s" % (hex(deviceid),actiondmfilestat.result.resultsName)) except DMExceptions.NoDMFileStat: logger.debug("No filesets to archive on this device: %s" % hex(deviceid)) except: logger.error(traceback.format_exc()) else: archive_action('dm_agent', user_comment, actiondmfilestat) #--------------------------------------------------------------------------- # Delete #--------------------------------------------------------------------------- elif dmfileset['auto_action'] == 'DEL': ''' Rules: If auto-acknowledge is True: promote to Acknowledged and delete when age and disk threshold reached. If auto-acknowledge is False: If fileset type is SIG: 'S' -> 'N' -> 'A' progression Else: promote to 'A' delete an 'A' fileset ''' logger.info("(%s)Exceed %d days. Eligible to delete: %d" %(hex(deviceid),dmfileset['auto_trigger_age'],dmfilestats.count())) if auto_acknowledge_enabled: if dmfileset['type'] == dmactions_types.SIG: logger.debug("Sig Proc Input Files auto acknowledge enabled") ''' Do not require 'S' -> 'N' -> 'A' progression before action; mark 'A' and process ''' A_list = dmfilestats.filter(action_state='A') if A_list.count() > 0: # there are filesets acknowledged and ready to be deleted. Covers situation where user has #already acknowledged but recently enabled auto-acknowledge as well. #deleteme = A_list[0] try: actiondmfilestat = getfirstnotpreserved(A_list, dmactions.DELETE) logger.info("(%s)Picked: %s" % (hex(deviceid),actiondmfilestat.result.resultsName)) except DMExceptions.NoDMFileStat: logger.info("(%s) No filesets to delete on this device" % hex(deviceid)) except: logger.error(traceback.format_exc()) if actiondmfilestat == None: # Select oldest fileset regardless if its 'L','S','N','A'. This covers situation where user # recently enabled auto-acknowledge try: actiondmfilestat = getfirstnotpreserved(dmfilestats, dmactions.DELETE) logger.info("(%s)Picked: %s" % (hex(deviceid),actiondmfilestat.result.resultsName)) except DMExceptions.NoDMFileStat: logger.info("(%s) No filesets to delete on this device" % hex(deviceid)) except: logger.error(traceback.format_exc()) else: if dmfileset['type'] == dmactions_types.SIG: logger.debug("Sig Proc Input Files auto acknowledge disabled") ''' Need to select # of filesets and mark 'S' Need to find 'S' filesets and notify (set to 'N') Set queue length to 5 sum(S,N,A) == queue length ''' #Get email recipient try: recipient = User.objects.get(username='******').email recipient = recipient.replace(',',' ').replace(';',' ').split() except: recipient = None # Need to select Experiments to process with appropriate dmfilestats action states. exps = Experiment.objects.exclude(storage_options='KI').exclude(expDir='').order_by('date') L_list = exps.filter(results_set__dmfilestat__in = dmfilestats.filter(action_state='L')).distinct() S_list = exps.filter(results_set__dmfilestat__in = dmfilestats.filter(action_state='S')).distinct() N_list = exps.filter(results_set__dmfilestat__in = dmfilestats.filter(action_state='N')).distinct() A_list = exps.filter(results_set__dmfilestat__in = dmfilestats.filter(action_state='A')).distinct() logger.info("Experiments (Keep=False) L:%d S:%d N:%d A:%d" % (L_list.count(),S_list.count(),N_list.count(),A_list.count())) queue_length = 5 to_select = queue_length - (A_list.count() + N_list.count() + S_list.count()) if to_select > 0: # Mark to_select number of oldest Experiments, from 'L', to 'S' promoted = dmfilestats.filter(result__experiment__id__in=list(L_list[:to_select].values_list('id',flat=True))) if auto_action_enabled: promoted.update(action_state = 'S') for dmfilestat in promoted: EventLog.objects.add_entry(dmfilestat.result, "Signal Processing Input Selected for Deletion", username='******') # Get updated list of Selected items selected = dmfilestats.filter(action_state='S') # Send Selected items to be notified if selected.count() > 0 and auto_action_enabled: logger.debug("notify recipient %s" % (recipient)) if notify([dmfilestat.result.resultsName for dmfilestat in selected],recipient): selected.update(action_state = 'N') for dmfilestat in selected: EventLog.objects.add_entry(dmfilestat.result, "Notification for Deletion Sent", username='******') try: actiondmfilestat = getfirstnotpreserved(dmfilestats.filter(action_state='A'), dmactions.DELETE) logger.info("(%s)Picked: %s" % (hex(deviceid),actiondmfilestat.result.resultsName)) except DMExceptions.NoDMFileStat: logger.info("(%s) No filesets to delete on this device" % hex(deviceid)) except: logger.error(traceback.format_exc()) else: try: actiondmfilestat = getfirstnotpreserved(dmfilestats, dmactions.DELETE) logger.info("(%s)Picked: %s" % (hex(deviceid),actiondmfilestat.result.resultsName)) except DMExceptions.NoDMFileStat: logger.info("(%s) No filesets to delete on this device" % hex(deviceid)) except: logger.error(traceback.format_exc()) #Bail out if disabled if auto_action_enabled != True: logger.info("Data management auto-action is disabled.") return if actiondmfilestat is not None: delete_action('dm_agent', user_comment, actiondmfilestat) else: logger.error("Unknown or unhandled action: %s" % dmfileset['auto_action']) except (DMExceptions.FilePermission, DMExceptions.InsufficientDiskSpace, DMExceptions.MediaNotSet, DMExceptions.MediaNotAvailable, DMExceptions.FilesInUse) as e: message = Message.objects.filter(tags__contains=e.tag) if not message: Message.error(e.message,tags=e.tag) #TODO: TS-6525: This logentry will repeat for an Archive action every 30 seconds until the cause of the exception is fixed. #at least, while the message banner is raised, suppress additional Log Entries. EventLog.objects.add_entry(actiondmfilestat.result,"%s - %s" % (dmfileset['type'], e.message),username='******') except DMExceptions.SrcDirDoesNotExist as e: if actiondmfilestat.dmfileset.type == dmactions_types.SIG: msg = "Src Dir not found: %s. Setting action_state to Deleted" % e.message EventLog.objects.add_entry(actiondmfilestat.result,msg,username='******') actiondmfilestat.setactionstate('DD') logger.info(msg) except Exception as inst: msg = '' if actiondmfilestat: msg = "Auto-action error on %s " % actiondmfilestat.result.resultsName msg += " Error: %s" % str(inst) logger.exception("%s - %s" % (dmfileset['type'], msg)) if actiondmfilestat: EventLog.objects.add_entry(actiondmfilestat.result,"%s - %s" % (dmfileset['type'], msg),username='******') finally: applock.unlock() #logger.debug("(%05d,%05d) Release lock file" % (os.getppid(),os.getpid())) return