1 Topic by jdodge

  • jdodge
  • Member
  • Offline
  • Registered: 2016-01-31
  • Posts: 8

Topic: internal server error

==== Required information ====
- iRedMail version (check /etc/iredmail-release): 0.9.4
- Linux/BSD distribution name and version:   Centos 7.1
- Store mail accounts in which backend (LDAP/MySQL/PGSQL):  MySQL (mariadb)
- Web server (Apache or Nginx): Nginx
- Manage mail accounts with iRedAdmin-Pro?  yes - 2.3.1
- Related log if you're reporting an issue:
====
/var/log/nginx/error.log:

2016/01/30 15:49:56 [error] 3477#0: *13358 "/var/www/html/admin/index.php" is not found (2: No such file or directory), client: 71.43.xxx.xxxx, server: _, request: "GET /admin/ HTTP/1.1", host: "mail.axxxx.com"

This happens when I create a new user, or try to edit a current user in the /iredadmin/ users page.  It seems to be creating the user -- but I cannot edit users.  It seems like it expects /admin/ to be workable, but only /iredamin/ is.  I'm familiar with Apache -- but used nginx for this install when I saw that 1.0 was planned to support nginx only.
What's the best way to fix this that won't cause me problems with addl upgrades?

  - jdodge

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 31,088

Re: internal server error

Any error in uwsgi log file? /var/log/uwsgi*

3 Reply by jdodge

  • jdodge
  • Member
  • Offline
  • Registered: 2016-01-31
  • Posts: 8

Re: internal server error

ZhangHuangbin wrote:

Any error in uwsgi log file? /var/log/uwsgi*

No, /var/log/uwsgi is empty

4 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 31,088

Re: internal server error

Could you please restart uwsgi service and try again?

5 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 31,088

Re: internal server error

If iRedAdmin-Pro raises "internal server error", uwsgi always logs the detailed reason. So please try to extract related log and paste here, so that others can help troubleshoot.

6 Reply by jdodge

  • jdodge
  • Member
  • Offline
  • Registered: 2016-01-31
  • Posts: 8

Re: internal server error

Restarted the uwsgi service -- still get the "internal server error" text in my webpage when I try to edit a user. 
Rebooted the system entirely -- still no luck.  I am NOT seeing the /var/www/html/admin/index.php" is not found error I in the /var/log/nginx/error.log that I saw before -- not sure where in the process it stopped logging that -- but it doesn't anymore.

       - Jeff

7 Reply by jdodge

  • jdodge
  • Member
  • Offline
  • Registered: 2016-01-31
  • Posts: 8

Re: internal server error

[root@mail /]# cd /var/log
[root@mail log]# ll
total 10168
drwxr-xr-x. 2 root    root          4096 Jan 20 19:03 anaconda
drwxr-x---. 2 root    root            94 Jan 31 05:21 audit
-rw-r--r--  1 root    root          8468 Jan 31 10:03 boot.log
-rw-------. 1 root    utmp         21120 Jan 30 20:56 btmp
-rw-------  1 root    root       1436510 Jan 31 10:29 cron
-rw-------. 1 root    root        617359 Jan 24 03:32 cron-20160124
-rw-r--r--  1 root    root         36601 Jan 31 10:03 dmesg
-rw-r--r--  1 root    root         36672 Jan 20 21:24 dmesg.old
-rw-------  1 vmail   vmail            0 Jan 24 03:32 dovecot-lmtp.log
-rw-------  1 vmail   vmail           14 Jan 20 19:26 dovecot-lmtp.log-20160124.bz2
-rw-------  1 vmail   vmail      1160523 Jan 31 10:28 dovecot.log
-rw-------  1 vmail   vmail        22317 Jan 24 03:25 dovecot.log-20160124.bz2
-rw-------  1 vmail   vmail         3060 Jan 31 10:14 dovecot-sieve.log
-rw-------  1 vmail   vmail          471 Jan 24 02:01 dovecot-sieve.log-20160124.bz2
-rw-rw-r--. 1 root    clamupdate       0 Jan 20 19:22 freshclam.log
-rw-------. 1 root    root          1221 Jan 20 19:06 grubby
drwx------. 2 root    root             6 Nov 19 16:43 httpd
drwx------. 2 iredapd iredapd         57 Jan 26 08:38 iredapd
-rw-r--r--. 1 root    root        585168 Jan 31 10:21 lastlog
-rw-------  1 root    root        166137 Jan 31 10:24 maillog
-rw-------. 1 root    root        101040 Jan 24 03:32 maillog-20160124
drwxr-x---. 2 mysql   mysql           24 Jan 20 19:22 mariadb
-rw-------  1 root    root       4251533 Jan 31 10:29 messages
-rw-------. 1 root    root       2234233 Jan 24 03:32 messages-20160124
drwx------. 2 nginx   nginx         4096 Jan 31 03:25 nginx
drwxrwx---. 2 apache  root            47 Jan 24 03:32 php-fpm
drwx------. 2 root    root             6 Jun 10  2014 ppp
-rw-r--r--  1 root    root           636 Jan 31 05:35 sa-update.log
-rw-------  1 root    root         10517 Jan 31 10:21 secure
-rw-------. 1 root    root         45721 Jan 21 22:50 secure-20160124
drwx------. 2 sogo    sogo          4096 Jan 31 03:25 sogo
-rw-------  1 root    root             0 Jan 24 03:32 spooler
-rw-------. 1 root    root             0 Jan 20 18:57 spooler-20160124
-rw-------. 1 root    root             0 Jan 20 18:54 tallylog
drwxr-xr-x. 2 root    root            22 Dec  9 08:25 tuned
drwxr-xr-x. 2 root    root             6 Jan 20 19:25 uwsgi
-rw-r--r--. 1 root    root           200 Jan 31 10:03 wpa_supplicant.log
-rw-rw-r--. 1 root    utmp         16896 Jan 31 10:21 wtmp
-rw-------. 1 root    root         18823 Jan 30 15:22 yum.log
[root@mail log]# cd uwsgi
[root@mail uwsgi]# ls -al
total 4
drwxr-xr-x.  2 root root    6 Jan 20 19:25 .
drwxr-xr-x. 13 root root 4096 Jan 31 10:03 ..
[root@mail uwsgi]#


Should I be looking somewhere else for the log -- I can find no trace!? 

Nevermind -- I just had to tail -f /var/log/messages while I tested it -- somethings's wrong with the logging setup to /var/low/uwsgi/ -- I'll look into that in a bit:

The relevant section:


Jan 31 10:32:21 mail uwsgi: Traceback (most recent call last):
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 239, in process
Jan 31 10:32:21 mail uwsgi: return self.handle()
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 230, in handle
Jan 31 10:32:21 mail uwsgi: return self._delegate(fn, self.fvars, args)
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 420, in _delegate
Jan 31 10:32:21 mail uwsgi: return handle_class(cls)
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 396, in handle_class
Jan 31 10:32:21 mail uwsgi: return tocall(*args)
Jan 31 10:32:21 mail uwsgi: File "/var/www/iRedAdmin-Pro-SQL-2.3.1/libs/sqllib/decorators.py", line 26, in proxyfunc
Jan 31 10:32:21 mail uwsgi: return func(*args, **kw)
Jan 31 10:32:21 mail uwsgi: File "/var/www/iRedAdmin-Pro-SQL-2.3.1/controllers/sql/user.py", line 237, in GET
Jan 31 10:32:21 mail uwsgi: mail=self.mail)
Jan 31 10:32:21 mail uwsgi: File "/var/www/iRedAdmin-Pro-SQL-2.3.1/libs/sqllib/user.py", line 253, in get_user_alias_addresses
Jan 31 10:32:21 mail uwsgi: where='alias_to = $mail AND is_alias = 1')
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/db.py", line 682, in select
Jan 31 10:32:21 mail uwsgi: return self.query(qout, processed=True)
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/db.py", line 644, in query
Jan 31 10:32:21 mail uwsgi: self._db_execute(db_cursor, sql_query)
Jan 31 10:32:21 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/db.py", line 587, in _db_execute
Jan 31 10:32:21 mail uwsgi: out = cur.execute(query, params)
Jan 31 10:32:21 mail uwsgi: File "/usr/lib64/python2.7/site-packages/MySQLdb/cursors.py", line 174, in execute
Jan 31 10:32:21 mail uwsgi: self.errorhandler(self, exc, value)
Jan 31 10:32:21 mail uwsgi: File "/usr/lib64/python2.7/site-packages/MySQLdb/connections.py", line 36, in defaulterrorhandler
Jan 31 10:32:21 mail uwsgi: raise errorclass, errorvalue
Jan 31 10:32:21 mail uwsgi: OperationalError: (1054, "Unknown column 'alias_to' in 'where clause'")
Jan 31 10:32:21 mail uwsgi: mail.auifl.com [pid: 663|app: 0|req: 31/31] 172.2.219.229 () {52 vars in 1056 bytes} [Sun Jan 31 10:32:21 2016] GET /iredadmin/profile/user/general/jdodge@xxxxxcom => generated 21 bytes in 26 msecs (HTTP/1.1 500) 2 headers in 165 bytes (2 switches on core 0)


for that I was trying to edit my own account (logged in as myself -- a domain admin)  When I tried to edit postmaster I got:

Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 239, in process
Jan 31 10:35:23 mail uwsgi: return self.handle()
Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 230, in handle
Jan 31 10:35:23 mail uwsgi: return self._delegate(fn, self.fvars, args)
Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 420, in _delegate
Jan 31 10:35:23 mail uwsgi: return handle_class(cls)
Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/application.py", line 396, in handle_class
Jan 31 10:35:23 mail uwsgi: return tocall(*args)
Jan 31 10:35:23 mail uwsgi: File "/var/www/iRedAdmin-Pro-SQL-2.3.1/libs/sqllib/decorators.py", line 26, in proxyfunc
Jan 31 10:35:23 mail uwsgi: return func(*args, **kw)
Jan 31 10:35:23 mail uwsgi: File "/var/www/iRedAdmin-Pro-SQL-2.3.1/controllers/sql/user.py", line 237, in GET
Jan 31 10:35:23 mail uwsgi: mail=self.mail)
Jan 31 10:35:23 mail uwsgi: File "/var/www/iRedAdmin-Pro-SQL-2.3.1/libs/sqllib/user.py", line 253, in get_user_alias_addresses
Jan 31 10:35:23 mail uwsgi: where='alias_to = $mail AND is_alias = 1')
Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/db.py", line 682, in select
Jan 31 10:35:23 mail uwsgi: return self.query(qout, processed=True)
Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/db.py", line 644, in query
Jan 31 10:35:23 mail uwsgi: self._db_execute(db_cursor, sql_query)
Jan 31 10:35:23 mail uwsgi: File "/usr/lib/python2.7/site-packages/web/db.py", line 587, in _db_execute
Jan 31 10:35:23 mail uwsgi: out = cur.execute(query, params)
Jan 31 10:35:23 mail uwsgi: File "/usr/lib64/python2.7/site-packages/MySQLdb/cursors.py", line 174, in execute
Jan 31 10:35:23 mail uwsgi: self.errorhandler(self, exc, value)
Jan 31 10:35:23 mail uwsgi: File "/usr/lib64/python2.7/site-packages/MySQLdb/connections.py", line 36, in defaulterrorhandler
Jan 31 10:35:23 mail uwsgi: raise errorclass, errorvalue
Jan 31 10:35:23 mail uwsgi: OperationalError: (1054, "Unknown column 'alias_to' in 'where clause'")
Jan 31 10:35:23 mail uwsgi: mail.auifl.com [pid: 663|app: 0|req: 32/32] 172.2.219.229 () {52 vars in 1064 bytes} [Sun Jan 31 10:35:23 2016] GET /iredadmin/profile/user/general/postmaster@xxxxx.com => generated 21 bytes in 27 msecs (HTTP/1.1 500) 2 headers in 165 bytes (2 switches on core 0)

I'm still a bit fuzzy on systemd's logging -- I'll read up on that and see if I can make sense of why it's not logging in /var/log/uwsgi

  - Jeff

8 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 31,088

Re: internal server error

jdodge wrote:

Jan 31 10:32:21 mail uwsgi: OperationalError: (1054, "Unknown column 'alias_to' in 'where clause'")

Column "alias.alias_to" was introduced in iRedMail-0.9.3, mentioned in our upgrade tutorial here:
http://www.iredmail.org/docs/upgrade.ir … asalias_to