Manda mails notificando si compila bien.

[software/sercom-old.git] / src / sc_test

diff --git a/src/sc_test b/src/sc_test
index f9518f1b080677f913fb2bc260aac154acc04e9e..2e4be200312e48e41f49a5e65c85316bbcee8fa8 100755 (executable)
--- a/src/sc_test
+++ b/src/sc_test
@@ -4,23 +4,20 @@
 
 # Módulos estándar
 import os
 
 # Módulos estándar
 import os

-import sys

 import pwd
 import time
 import signal
 import pwd
 import time
 import signal

-import locale
+import glob

 import shutil
 import datetime
 import shutil
 import datetime

-import logging
-import logging.config

 import subprocess
 import subprocess

-import ConfigParser
-# Módulos externos
-import sqlobject
+import email.MIMEMultipart

 # Módulos locales
 # Módulos locales

-from sercom.dbo import *
+import sercom
+import sercom.sqlo
+from sercom.sqlo import *

 
 

-class secure:
+class secure_process:

     def __init__(self, chroot, uid, gid, cpu):
         self.chroot = chroot
         self.uid = uid
     def __init__(self, chroot, uid, gid, cpu):
         self.chroot = chroot
         self.uid = uid


@@ -32,29 +29,24 @@ class secure:
         chroot(self.chroot)
         setgid(self.gid)
         setuid(self.uid)
         chroot(self.chroot)
         setgid(self.gid)
         setuid(self.uid)

-        resource.setrlimit(resource.RLIMIT_AS, 20*1024*1024)
-        resource.setrlimit(resource.RLIMIT_CORE, 0)
-        resource.setrlimit(resource.RLIMIT_CPU, (self.cpu, self.cpu))
-        resource.setrlimit(resource.RLIMIT_DATA, 20*1024*1024)
-        resource.setrlimit(resource.RLIMIT_FSIZE, 20*1024*1024) #XXX Obtener de archivos esperados?
-        resource.setrlimit(resource.RLIMIT_LOCKS, 100)
-        resource.setrlimit(resource.RLIMIT_MEMLOCK, 100)
-        resource.setrlimit(resource.RLIMIT_NOFILE, 100)
-        resource.setrlimit(resource.RLIMIT_NPROC, 0)
-
-def logger():
-    log = None
-    for log_conf in ('log.ini', os.path.expanduser('~/.sercom/log.ini'), '/etc/sercom/log.ini'):
-        if (os.access(log_conf, os.R_OK)):
-            logging.config.fileConfig(log_conf)
-            log = logging.getLogger('test')
-    return log
+        x2 = lambda val: (val, val) # Devuelve una tupla con val 2 veces
+        resource.setrlimit(resource.RLIMIT_AS, x2(20*1024*1024))
+        resource.setrlimit(resource.RLIMIT_CORE, x2(0))
+        resource.setrlimit(resource.RLIMIT_CPU, x2(self.cpu))
+        resource.setrlimit(resource.RLIMIT_DATA, x2(20*1024*1024))
+        resource.setrlimit(resource.RLIMIT_FSIZE, x2(20*1024*1024)) #XXX Obtener de archivos esperados?
+        #resource.setrlimit(resource.RLIMIT_LOCKS, x2(100)) XXX NO EXISTE EN python
+        resource.setrlimit(resource.RLIMIT_MEMLOCK, x2(100))
+        resource.setrlimit(resource.RLIMIT_NOFILE, x2(100))
+        resource.setrlimit(resource.RLIMIT_NPROC, x2(0))
+        # Tratamos de forzar un sync para que entre al sleep del padre
+        time.sleep(0)

 
 def sigchld(signum, frame):
     """Signal handler para SIGCHILD."""
 
 def sigchld(signum, frame):
     """Signal handler para SIGCHILD."""

-    #global hijo_muerto
-    #hijo_muerto = True
-    pass
+    global hijo_muerto, log
+    log.debug('Murió el hijo')
+    hijo_muerto = True

 
 def sigterm(signum, frame):
     """Signal handler para SIGTERM y SIGINT."""
 
 def sigterm(signum, frame):
     """Signal handler para SIGTERM y SIGINT."""


@@ -62,46 +54,280 @@ def sigterm(signum, frame):
     continuar = False
     log.debug('Señal %d recibida', signum)
 
     continuar = False
     log.debug('Señal %d recibida', signum)
 

+def chroot_dir(intento):
+    return os.path.join(intento.path, 'chroot')

 
 

-# Seteo locale
-locale.setlocale(locale.LC_ALL, '')
+def compilar(intento, mail):
+    global log
+    # Busco makefile
+    makefile = os.path.join(intento.entrega.ejercicio.path, 'Makefile')
+    # Compilo
+    log.debug('Ejecutando: make -f %s', makefile)
+    intento.inicioCompila = datetime.datetime.now()
+    make = subprocess.Popen(('make', '-f', makefile), stderr=subprocess.PIPE,
+        cwd=intento.path)
+    make.wait()
+    intento.finCompila = datetime.datetime.now()
+    log.debug('Fin del comando: make -f %s', makefile)
+    # Verifico compilación
+    stderr = make.stderr.read()
+    intento.compila = not make.returncode
+    msg = 'Compilación: '
+    if intento.compila and not stderr:
+        msg += 'BIEN'
+    elif intento.compila:
+        msg += 'CON ADVERTENCIAS'
+    else:
+        msg += 'ERROR (código de retorno: %d)' % make.returncode
+    mail.body += msg
+    if stderr:
+        msg += '''Salida:
+------------------------------------------------------------------------
+%s
+------------------------------------------------------------------------

 
 

-# Obtengo configuración
-conf = ConfigParser.SafeConfigParser()
-if not conf.read(('/etc/sercom/sercom.ini', os.path.expanduser('~/.sercom/sercom.ini'), 'sercom.ini')):
-    sys.stderr.write('No se pudo obtener configuración!\n')
-    sys.exit(1)
+''' % stderr
+        mail.body += "\n(se adjunta salida, archivo 'make.stderr')\n"
+        mail.attachText(stderr, 'make.stderr')
+    mail.body += '\n\n'
+    intento.observaciones = msg + '\n\n'
+    log.debug(msg)

 
 

-# Obtengo id de usuario con el cual ejecutar las pruebas
-(uid, gid) = pwd.getpwnam(conf.get('general', 'user'))[2:4]
+def preparar(intento):
+    # Creo chroot - TODO copiarlo de algún lado donde ande el valgrind?
+    os.mkdir(chroot_dir(intento))
+    shutil.copy(os.path.join(intento.path, 'tp'),
+        os.path.join(chroot_dir(intento), 'tp'))

 
 

-# Cambio UID efectivo
-os.seteuid(uid)
+def limpiar(intento):
+    # Borro chroot entero
+    shutil.rmtree(chroot_dir(intento))

 
 

-# Seteo umask para que el grupo pueda leer
-os.umask(00027)
+def probar(intento, caso_de_prueba, mail):
+    # Cosas útiles
+    global log, conn, conf, uid, gid
+    # Para manejo de SIGCHLD
+    global hijo_muerto
+    hijo_muerto = False
+    # Obtengo datos útiles del caso de prueba
+    tiempo_cpu = caso_de_prueba.tiempoCpu
+    if tiempo_cpu is None:
+        tiempo_cpu = conf.get('general', 'tiempo_cpu')
+    tiempo_cpu = int(tiempo_cpu)
+    # Creo prueba nueva
+    prueba = Prueba(intento=intento, casoDePrueba=caso_de_prueba,
+        inicio=datetime.datetime.now(), connection=conn)
+    log.debug('Iniciando prueba: %s', prueba)
+    # Abro archivos para fds básicos
+    #XXX sacar nombres de archivos de prueba????
+    options = {
+        'cwd': chroot_dir(intento),
+        'close_fds': True,
+        'preexec_fn': secure_process(chroot_dir(intento), uid, gid, tiempo_cpu),
+    }
+    if os.path.exists(os.path.join(caso_de_prueba.path, 'stdin')):
+        options['stdin'] = file(os.path.join(caso_de_prueba.path, 'stdin'), 'r')
+    if os.path.exists(os.path.join(caso_de_prueba.path, 'stdout')):
+        options['stdout'] = subprocess.PIPE
+    if os.path.exists(os.path.join(caso_de_prueba.path, 'stderr')):
+        options['stderr'] = subprocess.PIPE
+    # Ejecuto programa
+    params = ['/tp']
+    if caso_de_prueba.parametros:
+        params += params2seq(caso_de_prueba.parametros)
+    log.debug('Ejecutando %s', ' '.join(params))
+    os.seteuid(0) # Dios! (para chroot)
+    try:
+        proc = subprocess.Popen(params, **options)
+    except Exception, e: # FIXME poner en el manejo de exceptiones estandar
+        try:
+            print e.child_traceback
+            raise
+        except:
+            raise
+    os.seteuid(uid) # Mortal de nuevo
+    if not hijo_muerto: # Recibido por el sigchld, para saber si murió
+        time.sleep(tiempo_cpu)
+    # Si el proceso sigue andando lo tenemos que matar
+    if not hijo_muerto:
+        log.debug('La prueba tardó más del tiempo permitido (%d segundos)',
+            tiempo_cpu)
+        os.seteuid(0) # Dios! (corre como Dios, hay que matarlo como Dios)
+        os.kill(proc.pid, signal.SIGKILL)
+        os.seteuid(uid) # Mortal de nuevo
+        log.debug('Prueba cancelada (kill)')
+        proc.wait()
+        prueba.observaciones = 'Excedió el límite de tiempo de ejecución ' \
+            '(%d seg)' % tiempo_cpu
+        prueba.fin = datetime.datetime.now()
+        prueba.pasada = False
+        return prueba
+    proc.wait() # Para que no queden zombies
+    prueba.fin = str(datetime.datetime.now())
+    # Salió con una señal?
+    if proc.returncode < 0:
+        sig = -proc.returncode
+        log.debug('El programa salió con la señal %d', sig)
+        prueba.pasada = False
+        #TODO otras señales conocidas
+        if sig == signal.SIGXCPU:
+            prueba.observaciones = 'Excedió el límite de tiempo de CPU ' \
+                '(%d seg)' % tiempo_cpu
+        else:
+            prueba.observaciones = 'Salió con la señal %d' % sig
+        return prueba
+    # Si tenemos que verificar el código de retorno
+    if caso_de_prueba.codigoRetorno is not None:
+        #FIXME trucho lo de 256
+        # Si el código de error esperado es 256 => el código de error debe ser != 0
+        # Si no el código de error esperado debe ser igual al obtenido
+        if caso_de_prueba.codigoRetorno != proc.returncode \
+                or caso_de_prueba.codigoRetorno == 256 \
+                    and proc.returncode != 0:
+            log.debug('Código de retorno incorrecto (debía ser %d y se obtuvo %d)',
+                caso_de_prueba.codigoRetorno, proc.returncode)
+            prueba.pasada = False
+            prueba.observaciones = 'Código de retorno incorrecto (debía ' \
+                'ser %d y se obtuvo %d)' % (caso_de_prueba.codigoRetorno,
+                proc.returncode)
+    #TODO verificar salidas, hacer diff
+    log.debug('Fin de ejecución de caso de prueba (hijo: %d, ret: %d)',
+        proc.pid, proc.returncode)
+    prueba.pasada = True
+    log.debug('Prueba OK: %s', prueba)
+    return prueba

 
 

-# Conecto señales
-signal.signal(signal.SIGCHLD, sigchld)
+def params2seq(params):
+    r"""Parsea un string de forma similar al bash, separando por espacios y
+    teniendo en cuenta comillas simples y dobles para agrupar. Para poner
+    comillas se puede usar el \ como caracter de escape (\' y \") y también
+    interpreta \n y \t. Devuelve una lista con los parámetros encontrados."""
+    # Constantes
+    SEP, TOKEN, DQUOTE, SQUOTE = ' ', None, '"', "'"
+    seq = []
+    buff = ''
+    escape = False
+    state = SEP
+    for c in params:
+        # Es un caracter escapado
+        if escape:
+            if c == 'n':
+                buff += '\n'
+            elif c == 't':
+                buff += '\t'
+            else:
+                buff += c
+            escape = False
+            continue
+        # Es una secuencia de escape
+        if c == '\\':
+            escape = True
+            continue
+        # Si está buscando espacios
+        if state == SEP:
+            if c == SEP:
+                continue
+            else:
+                state = TOKEN # Encontró
+        if state == TOKEN:
+            if c == DQUOTE:
+                state = DQUOTE
+                continue
+            if c == SQUOTE:
+                state = SQUOTE
+                continue
+            if c == SEP:
+                state = SEP
+                seq.append(buff)
+                buff = ''
+                continue
+            buff += c
+            continue
+        if state == DQUOTE:
+            if c == DQUOTE:
+                state = TOKEN
+                continue
+            buff += c
+            continue
+        if state == SQUOTE:
+            if c == SQUOTE:
+                state = TOKEN
+                continue
+            buff += c
+            continue
+        raise Exception, 'No tiene sentido'
+    if state == DQUOTE or state == SQUOTE:
+        raise Exception, 'Parse error, falta cerrar comilla (%s)' % state
+    if buff:
+        seq.append(buff)
+    return seq
+
+class MailIntento(email.MIMEMultipart.MIMEMultipart, object):
+    def __init__(self, intento):
+        global conf
+        from email.MIMEMultipart import MIMEMultipart
+        from email.MIMEMessage import MIMEMessage
+        from email.MIMEText import MIMEText
+        MIMEMultipart.__init__(self)
+        self.subject = '[%s] Resultado del intento %d del ejercicio %d.%d' % \
+            (conf.get('mail', 'prefijo'), intento.numero,
+                intento.entrega.nroEjercicio, intento.entrega.entrega)
+        self['From'] = conf.get('mail', 'from')
+        self['To'] = intento.mailRespuesta
+        self['Reply-To'] = conf.get('mail', 'admin')
+        self['Return-Path'] = conf.get('mail', 'admin')
+        self['X-Mailer'] = 'sercom 0.3'
+        self['X-Priority'] = '5'
+        self.epilogue = 'Para ver correctamente este e-mail su cliente debe ' \
+            'soportar MIME.\n\n'
+        self.prologue = '' # Garantiza que termine en \n el mensaje
+        self.attach(MIMEMessage(MIMEText('', 'plain', 'iso-8859-1')))
+        self.resultado = None
+    def __set_body(self, body):
+        self.get_payload(0).get_payload(0).set_payload(body)
+    def __get_body(self):
+        return self.get_payload(0).get_payload(0).get_payload()
+    body = property(__get_body, __set_body, doc='Cuerpo del mensaje.')
+    def attachText(self, text, nombre=None):
+        from email.MIMEText import MIMEText
+        attach = MIMEText(text, 'plain', 'iso-8859-1')
+        if nombre:
+            attach.add_header('Content-Disposition', 'attachment', filename=nombre)
+        self.attach(attach)
+    def send(self, resultado=None):
+        import smtplib
+        global conf
+        smtp = smtplib.SMTP(conf.get('mail', 'smtp'))
+        if resultado:
+            self.subject += ': ' + resultado
+        self['Subject'] = self.subject
+        smtp.sendmail(self['From'], self['To'], self.as_string())
+        smtp.close()
+
+# Manejadores de señales

 signal.signal(signal.SIGTERM, sigterm)
 signal.signal(signal.SIGINT, sigterm)
 signal.signal(signal.SIGTERM, sigterm)
 signal.signal(signal.SIGINT, sigterm)

+signal.signal(signal.SIGCHLD, sigchld)
+hijo_muerto = False # Cambia con SIGCHLD
+continuar = True # Cambia con SIGTERM o SIGINT

 
 

-# Conexión a la DB
-conn = sqlobject.connectionForURI(conf.get('dbo', 'database'))
-
-# Cargo config del logger
-log = logger()
-if not log:
-    print >>sys.stderr, 'No se pudo cargar archivo de configuración de log.'
-    sys.exit(1)
+# Inicializo
+conf, conn, log = sercom.init('test')

 log.info('Iniciado')
 
 log.info('Iniciado')
 

-# Algunas variables de configuración útiles
-data_dir = conf.get('general', 'data_dir')
+# Obtengo id de usuario con el cual ejecutar las pruebas
+uid, gid = pwd.getpwnam(conf.get('general', 'user'))[2:4]
+
+# Cambio UID efectivo
+os.seteuid(uid)
+
+# Atajo

 intervalo = float(conf.get('general', 'intervalo'))
 
 intervalo = float(conf.get('general', 'intervalo'))
 

+# Utilizo el directorio de datos como base para todos los SQLObjects
+sercom.sqlo.dir_base = conf.get('general', 'data_dir')
+

 # Hasta que nos maten
 # Hasta que nos maten

-continuar = True # Cambia con una señal

 while continuar:
     # Busco intento a probar
     intento = Intento.getProximoAProbar(conn)
 while continuar:
     # Busco intento a probar
     intento = Intento.getProximoAProbar(conn)


@@ -110,118 +336,30 @@ while continuar:
         time.sleep(intervalo)
         continue
     log.info('Nuevo intento a probar (%s)', intento)
         time.sleep(intervalo)
         continue
     log.info('Nuevo intento a probar (%s)', intento)

-    # Obtengo paths
-    intento_dir = os.path.join(data_dir, intento.path('intentos'))
-    entrega_dir = os.path.join(data_dir, intento.path('entregas'))
-    # Busco makefile
-    makefile = os.path.join(entrega_dir, 'Makefile')
-    if not os.path.exists(makefile):
-        makefile = os.path.join(data_dir, 'Makefile')
-    shutil.copy(makefile, intento_dir)
-    # Compilo
-    log.debug('A punto de ejecutar el comando: make -f %s', makefile)
-    intento.inicioCompila = datetime.datetime.now()
-    make = subprocess.Popen(('make', '-f', makefile), stdout=subprocess.PIPE,
-        stderr=subprocess.PIPE, cwd=intento_dir)
-    make.wait()
-    intento.finCompila = datetime.datetime.now()
-    log.debug('Fin del comando: make -f %s', makefile)
-    # Verifico compilación
-    if make.returncode:
-        log.debug('Error al compilar, código de retorno: %d, salida estándar: '
-            '%s, salida de error: %s)', make.returncode, make.stdout.read(),
-            make.stderr.read())
-        intento.compila = False
-        #TODO enviar_respuesta(R_ERR, $mail, "ERROR AL COMPILAR!\n\n$err\n\nCódigo de retorno: $ret\n", $intento);
-        continue;
-    else:
-        log.debug('Compilado OK')
-        intento.compila = True
-        #TODO mail acumulativo
-    # Creo chroot - TODO copiarlo de algún lado donde ande el valgrind?
-    chroot_dir = os.path.join(intento_dir, 'chroot')
-    ejecutable = os.path.join(chroot_dir, 'tp')
-    os.mkdir(chroot_dir)
-    shutil.move(os.path.join(intento_dir, 'tp'), ejecutable)
-    # Cambio permisos - XXX al pedo? Hice seteuid()
-    os.chmod(chroot_dir, 02770)
-    os.chown(chroot_dir, uid, gid)
-    os.chmod(ejecutable, 0550)
-    os.chown(ejecutable, uid, gid)
+    mail = MailIntento(intento)
+    # Compila
+    compilar(intento, mail)
+    if not intento.compila:
+        mail.send('NO COMPILA')
+        continue

     # Ejecución de casos de prueba
     intento.inicioPruebas = datetime.datetime.now()
     # Ejecución de casos de prueba
     intento.inicioPruebas = datetime.datetime.now()

+    pruebas = []

     for caso_de_prueba in intento.entrega.ejercicio.casosDePrueba:
     for caso_de_prueba in intento.entrega.ejercicio.casosDePrueba:

-        # Obtengo datos útiles del caso de prueba
-        tiempo_cpu = caso_de_prueba.tiempoCpu
-        if tiempo_cpu is None:
-            tiempo_cpu = conf.get('general', 'tiempo_cpu')
-        # Creo prueba nueva
-        prueba = Prueba(intento.id, caso_de_prueba.id, datetime.datetime.now())
-        log.debug('Prueba: %s', prueba)
-        # Abro archivos para fds básicos
-        #XXX sacar nombres de archivos de prueba????
-        stdin = file(os.path.join(intento_dir, 'stdin'), 'r')
-        stdout = file(os.path.join(intento_dir, 'stdout'), 'w')
-        stderr = file(os.path.join(intento_dir, 'stderr'), 'w')
-        # Ejecuto programa
-        log.debug('Ejecutando /tp %s', prueba.params)
-        os.seteuid(0) # Dios! (para chroot)
-        proc = subprocess.Popen('/tp', stdin=stdin, stdout=stdout, stderr=stderr,
-                               preexec_fn=secure(chroot_dir, uid, gid, tiempo_cpu))
-        os.seteuid(uid) # Mortal de nuevo
-        time.sleep(tiempo_cpu)
-        # Si el proceso sigue andando lo tenemos que matar
-        if proc.poll() is None:
-            log.debug('La prueba tardó más del tiempo permitido (%d segundos)',
-                tiempo_cpu)
-            os.seteuid(0) # Dios! (corre como Dios, hay que matarlo como Dios)
-            os.kill(proc.pid, signal.SIGKILL)
-            os.seteuid(uid) # Mortal de nuevo
-            log.debug('Prueba cancelada (kill)')
-            proc.wait()
-            prueba.observaciones = 'Excedió el límite de tiempo de ejecución ' \
-                '(%d seg)' % tiempo_cpu
-            prueba.fin = datetime.datetime.now()
-            prueba.pasada = False
-            continue
-        proc.wait() # Para que no queden zombies
-        prueba.fin = datetime.datetime.now()
-        # Salió con una señal?
-        if proc.returncode < 0:
-            sig = -proc.returncode
-            log.debug('El programa salió con la señal %d', sig)
-            prueba.pasada = False
-            #TODO otras señales conocidas
-            if sig == signal.SIGXCPU:
-                prueba.observaciones = 'Excedió el límite de tiempo de CPU ' \
-                    '(%d seg)' % tiempo_cpu
-            else:
-                prueba.observaciones = 'Salió con la señal %d' % sig
-            continue
-        # Si tenemos que verificar el código de retorno
-        if caso_de_prueba.codigoRetorno is not None:
-            #FIXME trucho lo de 256
-            # Si el código de error esperado es 256 => el código de error debe ser != 0
-            # Si no el código de error esperado debe ser igual al obtenido
-            if caso_de_prueba.codigoRetorno != proc.returncode \
-                    or caso_de_prueba.codigoRetorno == 256 \
-                        and proc.returncode != 0:
-                log.debug('Código de retorno incorrecto (debía ser %d y se obtuvo %d)',
-                    caso_de_prueba.codigoRetorno, proc.returncode)
-                prueba.pasada = False
-                prueba.observaciones = 'Código de retorno incorrecto (debía ' \
-                    'ser %d y se obtuvo %d)' % (caso_de_prueba.codigoRetorno,
-                    proc.returncode)
-        #TODO verificar salidas, hacer diff
-        log.debug('Fin de ejecución de caso de prueba (hijo: %d, ret: %d)',
-            proc.pid, proc.returncode)
-        log.debug('Prueba OK')
-        prueba.pasada = True
+        # Preparo chroot
+        preparar(intento)
+        # Pruebo y agrego prueba a la lista
+        prueba = probar(intento, caso_de_prueba, mail)
+        mail.body += 'Prueba %s: %s (%s)\n' % (prueba.casoDePrueba.nombre, prueba.pasada, prueba.observaciones)
+        pruebas.append(prueba)
+        # Limpio chroot
+        limpiar(intento)
+    #TODO Si es publica, veo si se hizo ok o no y voy creando mail

     intento.finPruebas = datetime.datetime.now()
     intento.finPruebas = datetime.datetime.now()

-    #TODO make clean
-    #TODO Armar mail de respuesta al alumno
-    for prueba in Prueba.selectBy(conn, Prueba.q.intento == intento):
-        #TODO Si es publica, veo si se hizo ok o no y voy creando mail
-        pass
-    time.sleep(conf.get('general', 'intervalo'))
+    mail.send('NO SEP')
+    # Limpio directorio
+    log.debug('Borrando ejecutable y código objeto (*.o)')
+    os.remove(os.path.join(intento.path, 'tp'))
+    [os.remove(obj) for obj in glob.glob(os.path.join(intento.path, '*.o'))]
+    # time.sleep(intervalo) #XXX Puede servir para enlentecer el server