Subversion Repositories wimsdev

Rev

Rev 13011 | Rev 15546 | Go to most recent revision | Blame | Compare with Previous | Last modification | View Log | RSS feed

  1. /*  Copyright (C) 1998-2003 XIAO, Gang of Universite de Nice - Sophia Antipolis
  2.  *
  3.  *  This program is free software; you can redistribute it and/or modify
  4.  *  it under the terms of the GNU General Public License as published by
  5.  *  the Free Software Foundation; either version 2 of the License, or
  6.  *  (at your option) any later version.
  7.  *
  8.  *  This program is distributed in the hope that it will be useful,
  9.  *  but WITHOUT ANY WARRANTY; without even the implied warranty of
  10.  *  MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
  11.  *  GNU General Public License for more details.
  12.  *
  13.  *  You should have received a copy of the GNU General Public License
  14.  *  along with this program; if not, write to the Free Software
  15.  *  Foundation, Inc., 675 Mass Ave, Cambridge, MA 02139, USA.
  16.  */
  17. /* Routines to write log files. */
  18. #include <sys/socket.h>
  19. #include <sys/un.h>
  20.  
  21. #include "wims.h"
  22.  
  23. char logbuf[4*(MAX_LINELEN+1)];
  24. char *logp=logbuf;
  25.  
  26. void write_logfile(char *fname, char *str)
  27. {
  28.   char *p;
  29.   if((p=strchr(str,'\n'))!=NULL) *p=0;
  30.   snprintf(logp,sizeof(logbuf)-(logp-logbuf),"%s %s\n",fname,str);
  31.   logp+=strlen(logp);
  32. }
  33.  
  34. void flushlog(void)
  35. {
  36.   int n, fd;
  37.   n=logp-logbuf; logp=logbuf;
  38.   if(n<=0) return;
  39.   fd=open(temp_log,O_WRONLY|O_APPEND|O_CREAT,S_IRUSR|S_IWUSR);
  40.   if(fd==-1) return;
  41.   (void)write(fd,logbuf,n); close(fd);
  42. }
  43.  
  44. /* Write module log file. */
  45. void module_log(void)
  46. {
  47.   /* log string is limited to 100 characters. */
  48.   char *logstr, *ip, *sess, lbuf[100], *p;
  49.  
  50.   if(robot_access || !modlog || strstr(session_prefix,"_check")!=NULL) return;
  51.   logstr=getvar("wims_module_log");
  52.   if(logstr==NULL || *logstr==0) return;
  53.   ip=remote_addr;
  54.   if(mode==mode_default) sess=getvar("wims_session");
  55.   else sess="popup";
  56.   if(ip==NULL || sess==NULL) return;
  57.   mystrncpy(lbuf,logstr,sizeof(lbuf));
  58.   p=strchr(sess,'_'); if(p==NULL) p=sess+strlen(sess);
  59.   if(p<sess+4) p=sess; else p=p-4;
  60.   snprintf(tmplbuf,sizeof(tmplbuf),"%s %.6s %s\11%s",
  61.    nowstr, p, ip, lbuf);
  62.   p=getvar(ro_name[ro_module]);
  63.   if(p==NULL || *p==0) return;
  64.   write_logfile(mkfname(NULL,"%s/%s",module_dir,p),tmplbuf);
  65. }
  66.  
  67. /* log http referers */
  68. void referer_log(void)
  69. {
  70.   char *c,*s,*ip,*r,*h,refstr[256];
  71.  
  72.   if(robot_access) return;
  73.   c=getvar(ro_name[ro_cmd]);
  74.   if(c==NULL) c="";
  75.   if(mode==mode_default) s=getvar("wims_session"); else s="popup";
  76.   if(s==NULL) s="----";
  77.   else if(!new_session && strcmp(c,"intro")!=0) return;
  78.   if(strlen(s)<4) s="----";
  79.   if(strchr(s,'_')!=NULL) return;
  80.   ip=remote_addr; if(*ip==0) return;
  81.   r=getenv("HTTP_REFERER");
  82.   if(r==NULL || *r==0) r="??";
  83.   /* skip some useless referers */
  84.   else {
  85.     if(strstr(r,cgi_name)!=NULL) return;
  86.     if(strstr(r,"file:")!=NULL || strchr(r,'.')==NULL) r="??";
  87.     if(strstr(r,"http")==NULL && strchr(r,'.')==NULL &&
  88.      strstr(r,"bookmark")!=NULL) r="??bookmark";
  89.   }
  90.   if(strncmp(r,"http://",strlen("http://"))==0) r+=strlen("http://");
  91.   /* Take references from the same site or not? No. */
  92.   h=getenv("HTTP_HOST");
  93.   if(h!=NULL && *h!=0 && strncmp(r,h,strlen(h))==0) return;
  94.     /* stop before '#' */
  95.   mystrncpy(refstr,r,sizeof(refstr));
  96.   r=strchr(refstr,'#'); if(r!=NULL) *r=0;
  97.   for(r=refstr;r<refstr+strlen(refstr);r++) {
  98.     if(*r=='%' && *(r+1)=='7' && *(r+2)=='E') {
  99.     *r='~'; ovlstrcpy(r+1,r+3);
  100.     }
  101.   }
  102.   snprintf(tmplbuf,sizeof(tmplbuf),"%s %s %s\11%s",
  103.      nowstr,s+strlen(s)-4,ip,refstr);
  104.   write_logfile("referer.log",tmplbuf);
  105. }
  106.  
  107. /* Log new creation of sessions. For server counting use. */
  108. void session_log(char *c)
  109. {
  110.   int i;
  111.   char *ip, *p, *agent, *s, *sess;
  112.  
  113.   ip=remote_addr; if(*ip==0) return;
  114.   if(mode==mode_default) {
  115.     sess=getvar("wims_session");
  116.     if(sess==NULL) return;
  117.     if(strchr(sess,'_')!=NULL) return;
  118.   }
  119.   else sess="popup";
  120.   p=getenv("REMOTE_HOST"); if(p==NULL) p="";
  121.   i=strlen(p); if(i>40) p+=i-40;
  122.   agent=getenv("HTTP_USER_AGENT"); if(agent==NULL) agent="";
  123.   s=strchr(sess,'_'); if(s==NULL) s=sess+strlen(sess);
  124.   if(s<sess+4) s=sess; else s=s-4;
  125.     /* limit agent name to 80 chars */
  126.   snprintf(tmplbuf,sizeof(tmplbuf),"%s %s %s\11%s\11%.80s",
  127.      nowstr, s, ip, p, agent);
  128.   write_logfile("session.log",tmplbuf);
  129. }
  130.  
  131. /* Log user information. */
  132. void user_log(char *c)
  133. {
  134.   char fname[MAX_FNAME+1], logbuf[MAX_LINELEN+1], cbuf[256], sbuf[32], shbuf[32];
  135.   char *user, *classe, *sh, *sess, *exo, *cc, *ip, *allow, *pend, *se;
  136.   char *ex;
  137.   double sc,Sc;
  138.   int i, scorelog, testmax;
  139.  
  140.   if(robot_access || strstr("session_prefix","_check")!=NULL) return;
  141.   user=getvar("wims_user"); classe=getvar("wims_class");
  142.   sc=0;
  143.   if(isexam) {
  144.     if(user==NULL || classe==NULL || *user==0 || *classe==0) return;
  145.     sh=getvar("worksheet"); if(sh==NULL) return;
  146.     mystrncpy(shbuf,sh,sizeof(shbuf));
  147.     exo=strchr(shbuf,'.'); if(exo==NULL) return;
  148.     *exo++=0; sh=shbuf;
  149.     if(mode==mode_default) sess=getvar("wims_session");
  150.     else sess="popup";
  151.     if(sess==NULL) return;
  152.     mystrncpy(sbuf,sess,sizeof(sbuf));
  153.     sess=strchr(sbuf,'_'); if(sess==NULL) return;
  154.     *sess=0; sess=sbuf; ex="E";
  155.     accessfile(logbuf,"r","%s/.E%s",class_dir,sh);
  156.     if(strchr(logbuf,'#')!=NULL || strcmp(user,"supervisor")==0) simuxam=1;
  157.     else {
  158.       accessfile(logbuf,"r","%s/%s/examsimu.%d", session_dir,sess,sh);
  159.       if(strstr(logbuf,"yes")!=NULL) user_error("expired_exam");
  160.     }
  161.     mkfname(examlogd,"%s/examlog/%s/%s",class_dir,user,sess);
  162.     mkfname(examlogf,"%s/%s.%s",examlogd,sh,exo);
  163.   }
  164.   else {
  165.     sh=getvar("wims_sheet");
  166.     if(sh==NULL || *sh==0) return;
  167.     exo=getvar("wims_exo"); if(exo==NULL) return;
  168.     sess=getvar("wims_session");
  169.     if(sess==NULL) return;
  170.     ex="";
  171.  
  172.   }
  173.   if(strcmp(c,"new")!=0 && strcmp(c,"renew")!=0
  174.      && strcmp(c,"rafale")!=0
  175.      && strcmp(c,"hint")!=0 && parm_restore==0) {
  176.     char *s;
  177.     s=getvar("module_score");
  178.     if(s==NULL || *s==0) return;
  179.     sc=atof(s); if(!isfinite(sc)) {sc=0; return;}
  180.     snprintf(cbuf,sizeof(cbuf),"score %s",s);
  181.     cc=cbuf;
  182.   }
  183.   else cc=c;
  184.   if(classe==NULL || *classe==0) i=1;
  185.   else i=getscorestatus(classe,atoi(sh));
  186.   testmax=gettrycheck(classe,user,atoi(sh),atoi(exo))==1;
  187.   pend=getvar("wims_scoring"); if(pend==NULL) pend="";
  188.   if((i==0 || !exodepOK || strcmp(pend,"pending")!=0 || testmax) && strcmp(cc,"rafale")!=0)
  189.     if(testmax) allow="maxtry noscore"; else allow="noscore";
  190.   else allow="";
  191.   ip=remote_addr; if(*ip==0) ip="-"; scorelog=0;
  192.   if(user==NULL || *user==0) {
  193.     classe="0"; allow="";
  194.     mkfname(fname,"../sessions/%s/.score",sess);
  195.   }
  196.   else {
  197.     char *pp;
  198.     if(classe==NULL || *classe==0) return;
  199.     pp=getvar("wims_scorereg"); /* contains suspend or exotrymax */
  200.     if((allow[0]==0 || i==1 || (pp!=NULL && strcmp(pp,"suspend")==0)) && *ex!='E')
  201.       scorelog=1;
  202.     else
  203.       mkfname(fname,"classes/%s/noscore/%s",classe,user);
  204.   }
  205.   if(isexam && user!=NULL && *user!=0) {
  206.     allow=exam_sheetexo;
  207.     snprintf(logbuf,sizeof(logbuf),":%s %2s %s  \t%s%s\n",
  208.     nowstr,exo,cc,ip,allow);
  209.     accessfile(logbuf,"a","%s/%s/examscore.%s", session_dir,sess,sh);
  210.     Sc=currexamscore(atoi(sh));
  211.     accessfile(logbuf,"r","%s/.E%s",class_dir,sh);
  212.     if(simuxam==0) {   /* not simulation */
  213.       if(sc>0) {
  214.         snprintf(logbuf,sizeof(logbuf),
  215.         "%s %.5f -1 %u %s %s\n",
  216.         sh,Sc,(unsigned int) nowtime,ip,sess);
  217.         accessfile(logbuf,"a","%s/score/%s.exam", class_dir,user);
  218.       }
  219.     }
  220.     else snprintf(exam_sheetexo+strlen(exam_sheetexo),
  221.         sizeof(exam_sheetexo)-strlen(exam_sheetexo), "\tS");
  222.   }
  223.   if(strcmp(c,"new")==0 || strcmp(c,"renew")==0)
  224.     se=getvar("wims_seed"); else se="";
  225.   snprintf(logbuf,sizeof(logbuf),"%s%s %s %2s %2s %s  \t%s\t%s\t%s",
  226.       ex,nowstr,sess,sh,exo,cc,ip,se,allow);
  227.   if(scorelog) {
  228.     snprintf(tmplbuf,sizeof(tmplbuf),"-c%s -u%s scorelog %s",
  229.        classe,user,logbuf);
  230.     _daemoncmd(tmplbuf);
  231.   }
  232.   else write_logfile(fname,logbuf);
  233. }
  234.  
  235. /* Log class information. */
  236. void class_log(char *cl, char *l, char *ip)
  237. {
  238.   char logbuf[1024];
  239.  
  240.   if(robot_access) return;
  241.   snprintf(logbuf,sizeof(logbuf),"%s %s   \t%s",
  242.       nowstr,ip,l);
  243.   write_logfile(mkfname(NULL,"classes/%s/.log",cl),logbuf);
  244. }
  245.  
  246. /* Log accesses to modules. For server counting use. */
  247. void access_log(char *c)
  248. {
  249.   int i;
  250.   char *ip, *p, *sess, *s, *agent, *u, *cl;
  251.   time_t logtime;
  252.   char ag[128], tm[64];
  253.  
  254.   ip=remote_addr;
  255.   if(*ip==0) ip="????????";
  256.   if(mode==mode_default) sess=getvar("wims_session");
  257.   else sess="popup";
  258.   if(sess==NULL) sess="----------";
  259.   p=getvar(ro_name[ro_module]);
  260.   if(p==NULL || *p==0) p="-";
  261. /* limit module name to 40 chars */
  262.   i=strlen(p); if(i>40) p+=i-40;
  263.   if(robot_access) {
  264.    agent=getenv("HTTP_USER_AGENT"); if(agent==NULL) agent="-";
  265.    snprintf(ag,sizeof(ag)," %s",agent);
  266.   }
  267.   else {
  268.    u=getvar("wims_user");
  269.    if(u!=NULL && *u!=0) snprintf(ag,sizeof(ag)," %s,%s",u,getvar("wims_class"));
  270.    else ag[0]=0;
  271.   }
  272.   s=strchr(sess,'_'); if(s==NULL) s=sess+strlen(sess);
  273.   if(s<sess+4) s=sess; else s=s-4;
  274.   tm[0]=0; logtime=time(0); if(logtime>nowtime+2) {
  275.    snprintf(tm,sizeof(tm)," (%lus)", logtime-nowtime);
  276.   }
  277.   snprintf(tmplbuf,sizeof(tmplbuf),"%s %.6s %s\11%s\11%s%s%s",
  278.       nowstr, s, ip, c, p, tm, ag);
  279.   write_logfile("access.log",tmplbuf);
  280.   user_log(c);
  281.   cl=getvar("wims_class");
  282.   if(cl!=NULL && *cl!=0) {
  283.     char *l;
  284.     l=getvar("wims_class_log");
  285.     if(l!=NULL && *l!=0) class_log(cl, l, ip);
  286.   }
  287. }
  288.  
  289. /* Log of mails. */
  290. void mail_log(char *c)
  291. {
  292.   int i;
  293.   char *ip, *p, *sess, *s, *cl;
  294.  
  295.   ip=remote_addr;
  296.   if(*ip==0) ip="????????";
  297.   if(mode==mode_default) sess=getvar("wims_session");
  298.   else sess="popup";
  299.   if(sess==NULL) sess="----------";
  300.   p=getvar(ro_name[ro_module]);
  301.   if(p==NULL || *p==0) p="-";
  302. /* limit module name to 40 chars */
  303.   i=strlen(p); if(i>40) p+=i-40;
  304.   s=strchr(sess,'_'); if(s==NULL) s=sess+strlen(sess);
  305.   if(s<sess+4) s=sess; else s=s-4;
  306.   cl=getvar("wims_class"); if(cl==NULL) cl="";
  307.   snprintf(tmplbuf,sizeof(tmplbuf),"%s %.6s %s\11%s\11%s\11%s",
  308.       nowstr, s, ip, c, cl, p);
  309.   write_logfile("mail.log",tmplbuf);
  310. }
  311.  
  312. /* log posted data */
  313. void post_log(void)
  314. {
  315.   char *h, *l, logstr[2*MAX_LINELEN+2];
  316.   char *authpwd, *p, *ll, *l1 ;
  317.  
  318.   h=remote_addr;
  319.   if(mpboundary[0]!=0) l="multipart/form-data"; else l=stdinbuf;
  320.  
  321.   ll=strdup(l);
  322.   authpwd="auth_password=";
  323.   if((p=strstr(l,authpwd))!=NULL ) {
  324.     l1=strdup(l);
  325.     mystrncpy(ll,l,p-l+strlen(authpwd)+1);
  326.     strcat(ll,"xxxx");
  327.     mystrncpy(l1,p+strlen(authpwd),strlen(l));
  328.     if((p=strstr(l1,"&"))!=NULL) strcat(ll,p);
  329.   }
  330.  
  331.   snprintf(logstr,sizeof(logstr),"%s %s\t%s",
  332.      nowstr, h, ll);
  333.   write_logfile("post.log",logstr);
  334. }
  335.  
  336. /* It is this routine which is called by main(). */
  337. void write_logs(void)
  338. {
  339.   char *p;
  340.   p=getvar(ro_name[ro_cmd]); if(p==NULL || *p==0) p="no_cmd";
  341.   access_log(p);
  342.   if(strstr(session_prefix,"_check")!=NULL) return;
  343.   module_log(); referer_log();
  344.   if(new_session) session_log(p);
  345. }
  346.  
  347. void user_error_log(char msg[])
  348. {
  349.   char *s, *m, *c, *h, *q, *r, *sess, logstr[512];
  350.   if(mode==mode_default) sess=getvar("wims_session");
  351.   else sess="popup";
  352.   if(sess==NULL) sess="----------";
  353.   m=getvar(ro_name[ro_module]);if(m==NULL) m="";
  354.   c=getvar(ro_name[ro_cmd]);if(c==NULL) c="";
  355.   h=remote_addr;
  356.   q=getenv("QUERY_STRING");if(q==NULL) q="";
  357.   r=getenv("HTTP_REFERER");
  358.   if(r==NULL || strstr(r,cgi_name)!=NULL) r="";
  359.   s=strchr(sess,'_'); if(s==NULL) s=sess+strlen(sess);
  360.   if(s<sess+4) s=sess; else s=s-4;
  361.   snprintf(logstr,sizeof(logstr),"%s %.5s %s %s, module=%s cmd=%s: %s %s",
  362.      nowstr, s, h, msg, m, c, r, q);
  363.   write_logfile("user_error.log",logstr);
  364.   if(user_error_nolog) return;
  365.   access_log("user_error");referer_log();
  366. }
  367.  
  368. void module_error_log(char msg[])
  369. {
  370.   char *s, *m, *c, logstr[256];
  371.   if(strstr(msg,"debug")!=NULL || strstr(msg,"timeup")!=NULL) return;
  372.   if(strstr(m_file.name,"sessions/")!=NULL) return;
  373.   s=getvar(ro_name[ro_module]);
  374.   if(s!=NULL) {
  375.    if(strncmp(s,"classes/",strlen("classes/"))==0 ||
  376.     strncmp(s,"devel/",strlen("devel/"))==0) return;
  377.   }
  378.   s=getvar("wims_session"); if(s==NULL) s="  ";
  379.   m=getvar(ro_name[ro_module]);if(m==NULL) m="";
  380.   c=getvar(ro_name[ro_cmd]);if(c==NULL) c="";
  381.   snprintf(logstr,sizeof(logstr),"%s %.10s %s in %s/%s, line %d",
  382.      nowstr, s+2, msg, m, m_file.name, m_file.l+1);
  383.   write_logfile("module_error.log",logstr);
  384.   access_log("module_error");
  385. }
  386.  
  387. /* Refused users due to threshold excess */
  388. void refuse_log(int th)
  389. {
  390.   char *load, *h;
  391.  
  392.   load=getvar("wims_server_load"); if(load==NULL) load="??";
  393.   h=remote_addr;
  394.   snprintf(tmplbuf,sizeof(tmplbuf),"%s %s\11%d:%s",
  395.       nowstr, h, th, load);
  396.   write_logfile("refuse.log",tmplbuf);
  397. }
  398.  
  399. #define logdpid "../tmp/log/wimslogd.pid"
  400. #define newlogd "../tmp/log/wimslogd.new"
  401.  
  402. void bringuplogd(void)
  403. {
  404.   char *arg[]={"../bin/wimslogd",NULL};
  405.   struct stat st;
  406.   pid_t pid;
  407.  
  408. /* need to update wimslogd? */
  409.   if(stat(newlogd,&st)==0) {
  410.    if((S_IXUSR&st.st_mode)!=0 && st.st_size>40000 && st.st_size<200000)
  411.      call_ssh("mv %s %s",newlogd,arg[0]);
  412.    else call_ssh("rm -f %s",newlogd);
  413.   }
  414.   pid=fork(); if(pid) return;   /* parent */
  415. /* double fork to escape sysmask orphan. */
  416.   pid=fork(); if(pid) {   /* secondary parent */
  417.    snprintf(tmplbuf,sizeof(tmplbuf),"%u",pid);
  418.    mkdirs("../tmp/log");
  419.    chmod("../tmp/log",S_IRUSR|S_IWUSR|S_IXUSR);
  420.    accessfile(tmplbuf,"w",logdpid);
  421.    exit(0);
  422.   }
  423.   setreuid(geteuid(),geteuid());setregid(getegid(),getegid());
  424.   snprintf(tmplbuf,sizeof(tmplbuf),"%u %u %u %u %u %u %d %d %d",
  425.       idle_time,idle_time2,idle_time3,
  426.       OLD_LOG_FILES,GEN_LOG_LIMIT,
  427.       MODULE_LOG_LIMIT,backup_hour,site_accounting,
  428.       examlog_limit);
  429.  
  430.   setenv("wimslogd",tmplbuf,1);
  431.   execve(arg[0],arg,environ);
  432.   fprintf(stderr,"Unable to execute wimslogd: %s\n",strerror(errno));
  433.   exit(1);
  434. }
  435.  
  436. void checklogd(void)
  437. {
  438.   int i,t;
  439.   char *p1, *p2, *p, buf[MAX_LINELEN+1];
  440.   sun.sun_family=PF_UNIX;
  441.   snprintf(sun.sun_path,sizeof(sun.sun_path),"%s",ksockfile);
  442.   p=getenv("REMOTE_ADDR"); if(p==NULL) p="";
  443.   snprintf(buf+sizeof(int),sizeof(buf)-sizeof(int),"ping %s",p);
  444.   t=kerneld(buf,sizeof(buf)); if(t<0) {
  445.     bringuplogd();
  446.     for(i=0; i<10 && t<0; i++) {
  447.       msleep(100);
  448.         snprintf(buf+sizeof(int),sizeof(buf)-sizeof(int),"ping %s",p);
  449.      t=kerneld(buf,sizeof(buf));
  450.     }
  451.   }
  452.   if(t<0) internal_error("Unable to bring up wimslogd.");
  453.   p1=find_word_start(buf+sizeof(int)); p2=find_word_end(p1);
  454.   if(*p2) *p2++=0;
  455.   if(strcmp(p1,"OK")!=0) internal_error("wimslogd error.");
  456.   if(*p2=='1') hostcquota=1; else hostcquota=0;
  457.   p1=find_word_start(find_word_end(p2));
  458.   p2=strchr(p1,'\n'); if(p2) *p2++=0; else p2=p1+strlen(p1);
  459.   mystrncpy(loadavg,p1,sizeof(loadavg));
  460.   p2=find_word_start(p2);
  461.   p1=find_word_end(p2); if(*p1) *p1++=0;
  462.  
  463.   p=getenv("SERVER_ADDR");
  464.   if(*p2!=0 && memcmp(p,"10.",3)==0) p=p2;
  465.   if(p!=NULL) {
  466.     i=strlen(cookieheader);
  467.     snprintf(cookieheader+i, sizeof(cookieheader)-i,"%s/",p);
  468.   }
  469.   p=getenv("HTTP_COOKIE"); cookiegot[0]=0;
  470.   if(p!=NULL && (p2=strstr(p,cookieheader))!=NULL) {
  471.     mystrncpy(cookiegot,find_word_start(p+strlen(cookieheader)),sizeof(cookiegot));
  472.     *find_word_end(cookiegot)=0;
  473.   }
  474. }
  475.