Opened 3 years ago

Closed 3 years ago

#1487053 closed Bugs (fixed)

"Server Error! (OK)" for INBOX

Reported by: mrfree Owned by:
Priority: 5 Milestone: 0.5-beta
Component: User Interface Version: 0.4.2
Severity: major Keywords:
Cc: levente.csabai@…

Description

Some users receive that message when they try to open INBOX folder, other like Draft or Sent work correctly.

I've tried with both chromium and firefox... same problem.
Using thunderbird all works well, so I think it should be a roundcube issue.

Any idea?

I'm using php-5.2.14 and apache-2.2.16

Change History (15)

comment:1 Changed 3 years ago by alec

  • Milestone changed from later to 0.5-beta

Any errors in log? What IMAP server?

comment:2 Changed 3 years ago by mrfree

I've found this in rc log (level 4)

[13-Oct-2010 12:46:31 +0200]: PHP Error: Could not convert string from DEFAULT_CHARSET to UTF-8. Make sure iconv/mbstring is installed or lib/utf8.class is available. in /var/www/posta.crabruzzo.it/roundcubemail-0.4.2/program/include/main.inc on line 308 (GET /?_task=mail&_remote=1&_action=list&_mbox=Junk&_page=1&_refresh=1&_=1286966791340&_unlock=1)

but the problem is still here and the logs are clean now...

I'm using dovecot-1.2.11

comment:3 Changed 3 years ago by alec

This error is not related to this issue. Consider giving me shell access to your box or at least a test account in your Roundcube installation. Write to alec at alec.pl.

comment:4 Changed 3 years ago by alec

Also make tests with disabled all plugins and default skin. What localization are you using? Check with other.

comment:5 Changed 3 years ago by mrfree

We have made some tests with alec... the problem seems to be related with sort option, but I don't exactly know how :)

I've toggle date sort and the messages appeared but the error messages re-appeared at page browsing. I've ignored it and continued to browse all pages, after it all seems to work well.

comment:6 Changed 3 years ago by alec

  • Component changed from Other to User Interface
  • Resolution set to worksforme
  • Status changed from new to closed

Unable to reproduce.

comment:7 Changed 3 years ago by levente.csabai

  • Cc levente.csabai@… added
  • Resolution worksforme deleted
  • Severity changed from critical to major
  • Status changed from closed to reopened

I could reproducing this bug (not too critical, only frustrating).
Machine: Local (192.168.1.3) Virtualbox machine
OS: Debian Linux Lenny with kernel 2.6.26-2-686
Webserver: Apache 2.2.9 (Installed package from Debian repo)
IMAP: Dovecot 1.0.15 (Installed package from Debian repo)
Database-server: MySQL Ver 14.12 Distrib 5.0.51a (Installed package from Debian repo)
Webmail: Roundcube 0.4.2 from the Sourceforge
Plugins: none

The problem appears only, when I open an unreaded mail (If I open readed mails, the bug disappears) from the Inbox, and I click to the "Back to the messages" icon or I double click the Incoming mails folder from the left pane. In this case the list of the mails in the Inbox not shown.

​Image from the bug

When I open a message:

192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /?_task=mail&_action=show&_uid=11&_mbox=INBOX HTTP/1.1" 200 3497
192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /skins/default/common.css?s=1285419833 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /skins/default/mail.css?s=1285419833 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /skins/default/splitter.js?s=1285419833 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /skins/default/functions.js?s=1286352167 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /program/js/jquery-1.4.min.js?s=1266747840 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:05 +0100] "GET /program/js/common.js?s=1286354712 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:06 +0100] "GET /program/js/app.js?s=1286354710 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:06 +0100] "GET /?_task=mail&_remote=1&_action=getunread&_=1289756947143&_unlock=0 HTTP/1.1" 200 105

Dovecot says:

Nov 14 18:56:51 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:56:51 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:56:51 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:56:51 debian dovecot: auth(default): master in: REQUEST#01116#0115289#0111
Nov 14 18:56:51 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:56:51 debian dovecot: auth(default): master out: USER#01116#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:56:51 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:56:51 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:56:51 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:56:51 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:56:52 debian dovecot: auth(default): new auth connection: pid=5297
Nov 14 18:56:52 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:56:53 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:56:53 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:56:53 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:56:53 debian dovecot: auth(default): master in: REQUEST#01117#0115293#0111
Nov 14 18:56:53 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:56:53 debian dovecot: auth(default): master out: USER#01117#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:56:53 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:56:53 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:56:53 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:56:53 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:56:53 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:56:54 debian dovecot: auth(default): new auth connection: pid=5299

Back to the Inbox

192.168.1.102 - - [14/Nov/2010:18:49:13 +0100] "GET /?_task=mail&_mbox=INBOX&_refresh=1 HTTP/1.1" 200 5297
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /skins/default/common.css?s=1285419833 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /skins/default/mail.css?s=1285419833 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /skins/default/splitter.js?s=1285419833 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /skins/default/functions.js?s=1286352167 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /program/js/jquery-1.4.min.js?s=1266747840 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /program/js/common.js?s=1286354712 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /program/js/app.js?s=1286354710 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /program/js/list.js?s=1286354717 HTTP/1.1" 304 -
192.168.1.102 - - [14/Nov/2010:18:49:15 +0100] "GET /?_task=mail&_remote=1&_action=getunread&_=1289756955487&_unlock=0 HTTP/1.1" 200 105
192.168.1.102 - - [14/Nov/2010:18:49:14 +0100] "GET /?_task=mail&_remote=1&_action=list&_mbox=INBOX&_refresh=1&_=1289756955463&_unlock=1 HTTP/1.1" 200 112

Dovecot logs:

Nov 14 18:57:05 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:57:05 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:57:05 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:57:05 debian dovecot: auth(default): master in: REQUEST#01118#0115295#0111
Nov 14 18:57:05 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:57:05 debian dovecot: auth(default): master out: USER#01118#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:57:05 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:57:05 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:57:05 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:57:05 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:57:05 debian dovecot: auth(default): new auth connection: pid=5303
Nov 14 18:57:05 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:57:07 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:57:07 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:57:07 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:57:07 debian dovecot: auth(default): master in: REQUEST#01119#0115297#0111
Nov 14 18:57:07 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:57:07 debian dovecot: auth(default): master out: USER#01119#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:57:07 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:57:07 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:57:07 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:57:07 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:57:07 debian dovecot: auth(default): master in: REQUEST#01120#0115299#0111
Nov 14 18:57:07 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:57:07 debian dovecot: auth(default): master out: USER#01120#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:57:07 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:57:07 debian dovecot: auth(default): new auth connection: pid=5306
Nov 14 18:57:07 debian dovecot: auth(default): new auth connection: pid=5307
Nov 14 18:57:07 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:58:06 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:58:06 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:58:06 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:58:06 debian dovecot: auth(default): master in: REQUEST#01121#0115303#0111
Nov 14 18:58:06 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:58:06 debian dovecot: auth(default): master out: USER#01121#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:58:06 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:58:06 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:58:06 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:58:06 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:58:06 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:58:07 debian dovecot: auth(default): new auth connection: pid=5313
Nov 14 18:59:06 debian dovecot: auth(default): client in: AUTH#0111#011PLAIN#011service=IMAP#011secured#011lip=192.168.1.3#011rip=192.168.1.3#011resp=AGluZm9AdGVzdC5sb2NhbABhc2Rhc2Q=
Nov 14 18:59:06 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): query: SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:59:06 debian dovecot: auth(default): client out: OK#0111#011user=info@test.local
Nov 14 18:59:06 debian dovecot: auth(default): master in: REQUEST#01122#0115306#0111
Nov 14 18:59:06 debian dovecot: auth-worker(default): sql(info@test.local,192.168.1.3): SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
Nov 14 18:59:06 debian dovecot: auth(default): master out: USER#01122#011info@test.local#011home=/srv/vmail/test.local/info#011mail=maildir:/srv/vmail/test.local/info#011uid=150#011gid=8#011quota=dirsize:storage=0
Nov 14 18:59:06 debian dovecot: IMAP(info@test.local): Effective uid=150, gid=8, home=/srv/vmail/test.local/info
Nov 14 18:59:06 debian dovecot: IMAP(info@test.local): maildir: data=/srv/vmail/test.local/info
Nov 14 18:59:06 debian dovecot: IMAP(info@test.local): maildir: root=/srv/vmail/test.local/info, index=/srv/vmail/test.local/info, control=, inbox=
Nov 14 18:59:06 debian dovecot: imap-login: Login: user=<info@test.local>, method=PLAIN, rip=192.168.1.3, lip=192.168.1.3, secured
Nov 14 18:59:06 debian dovecot: IMAP(info@test.local): Disconnected: Logged out
Nov 14 18:59:07 debian dovecot: auth(default): new auth connection: pid=5319

And a weird thing: if I switched the SQL-logging on

$rcmail_config['sql_debug'] = true;

the bug also disappears.

comment:8 Changed 3 years ago by alec

Any errors in Roundcube logs? What is debug_level setting? Change it to '1'. Try with svn-trunk version.

comment:9 Changed 3 years ago by levente.csabai

What is debug_level setting? Change it to '1'.

$rcmail_config['debug_level'] = 1;
$rcmail_config['smtp_log'] = true;
$rcmail_config['log_logins'] = true;
$rcmail_config['sql_debug'] = false;
$rcmail_config['imap_debug'] = true;
$rcmail_config['ldap_debug'] = false;
$rcmail_config['smtp_debug'] = true;

Any errors in Roundcube logs?

When the error occours, the /logs/imap says:
[14-Nov-2010 21:15:48 +0100]: S: * OK Dovecot ready.
[14-Nov-2010 21:15:48 +0100]: C: a001 LOGIN "info@test.local" "asdasd"
[14-Nov-2010 21:15:48 +0100]: S: a001 OK Logged in.
[14-Nov-2010 21:15:48 +0100]: C: cp01 CAPABILITY
[14-Nov-2010 21:15:48 +0100]: S: * CAPABILITY IMAP4rev1 SASL-IR SORT THREAD=REFERENCES MULTIAPPEND UNSELECT LITERAL+ IDLE CHILDREN NAMESPACE LOGIN-REFERRALS
[14-Nov-2010 21:15:48 +0100]: S: cp01 OK Capability completed.
[14-Nov-2010 21:15:48 +0100]: C: I LOGOUT
[14-Nov-2010 21:15:50 +0100]: S: * OK Dovecot ready.
[14-Nov-2010 21:15:50 +0100]: C: a001 LOGIN "info@test.local" "asdasd"
[14-Nov-2010 21:15:50 +0100]: S: a001 OK Logged in.
[14-Nov-2010 21:15:50 +0100]: C: cp01 CAPABILITY
[14-Nov-2010 21:15:50 +0100]: S: * CAPABILITY IMAP4rev1 SASL-IR SORT THREAD=REFERENCES MULTIAPPEND UNSELECT LITERAL+ IDLE CHILDREN NAMESPACE LOGIN-REFERRALS
[14-Nov-2010 21:15:50 +0100]: S: cp01 OK Capability completed.
[14-Nov-2010 21:15:50 +0100]: C: sel1 SELECT "INBOX"
[14-Nov-2010 21:15:50 +0100]: S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
[14-Nov-2010 21:15:50 +0100]: S: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted.
[14-Nov-2010 21:15:50 +0100]: S: * 12 EXISTS
[14-Nov-2010 21:15:50 +0100]: S: * 0 RECENT
[14-Nov-2010 21:15:50 +0100]: S: * OK [UNSEEN 1] First unseen.
[14-Nov-2010 21:15:50 +0100]: S: * OK [UIDVALIDITY 1289522369] UIDs valid
[14-Nov-2010 21:15:50 +0100]: S: * OK [UIDNEXT 13] Predicted next UID
[14-Nov-2010 21:15:50 +0100]: S: sel1 OK [READ-WRITE] Select completed.
[14-Nov-2010 21:15:50 +0100]: C: fuid FETCH 12 (UID)
[14-Nov-2010 21:15:50 +0100]: S: * 12 FETCH (UID 12)
[14-Nov-2010 21:15:50 +0100]: S: fuid OK Fetch completed.
[14-Nov-2010 21:15:50 +0100]: S: * OK Dovecot ready.
[14-Nov-2010 21:15:50 +0100]: C: a001 LOGIN "info@test.local" "asdasd"
[14-Nov-2010 21:15:50 +0100]: S: a001 OK Logged in.
[14-Nov-2010 21:15:50 +0100]: C: I LOGOUT
[14-Nov-2010 21:15:50 +0100]: C: fhi0 FETCH 1:* (UID)
[14-Nov-2010 21:15:50 +0100]: S: * 1 FETCH (UID 1)
[14-Nov-2010 21:15:50 +0100]: S: * 2 FETCH (UID 2)
[14-Nov-2010 21:15:50 +0100]: S: * 3 FETCH (UID 3)
[14-Nov-2010 21:15:50 +0100]: S: * 4 FETCH (UID 4)
[14-Nov-2010 21:15:50 +0100]: S: * 5 FETCH (UID 5)
[14-Nov-2010 21:15:50 +0100]: S: * 6 FETCH (UID 6)
[14-Nov-2010 21:15:50 +0100]: S: * 7 FETCH (UID 7)
[14-Nov-2010 21:15:50 +0100]: S: * 8 FETCH (UID 8)
[14-Nov-2010 21:15:50 +0100]: S: * 9 FETCH (UID 9)
[14-Nov-2010 21:15:50 +0100]: S: * 10 FETCH (UID 10)
[14-Nov-2010 21:15:50 +0100]: S: * 11 FETCH (UID 11)
[14-Nov-2010 21:15:50 +0100]: S: * 12 FETCH (UID 12)
[14-Nov-2010 21:15:50 +0100]: S: fhi0 OK Fetch completed.
[14-Nov-2010 21:15:50 +0100]: C: FH12 FETCH 10 (UID RFC822.SIZE FLAGS INTERNALDATE BODY.PEEK[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)])
[14-Nov-2010 21:15:50 +0100]: S: * 10 FETCH (UID 10 RFC822.SIZE 588 FLAGS (\Seen) INTERNALDATE "12-Nov-2010 18:42:15 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111214522.E27C7EBD7@mail.mycompany.com>
Date: Thu, 11 Nov 2010 22:45:22 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: FH12 OK Fetch completed.
[14-Nov-2010 21:15:50 +0100]: C: FH12 FETCH 1,2,3,4,5,6,7,8,9,10,11,12 (UID RFC822.SIZE FLAGS INTERNALDATE BODY.PEEK[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)])
[14-Nov-2010 21:15:50 +0100]: S: * 1 FETCH (UID 1 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 01:39:29 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101112003929.1058CEBE1@mail.mycompany.com>
Date: Fri, 12 Nov 2010 01:39:28 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 2 FETCH (UID 2 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 01:56:56 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111233351.5E863EBE0@mail.mycompany.com>
Date: Fri, 12 Nov 2010 00:33:51 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 3 FETCH (UID 3 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 02:11:56 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111224342.0733CEBDC@mail.mycompany.com>
Date: Thu, 11 Nov 2010 23:43:41 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 4 FETCH (UID 4 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 18:42:15 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111215546.AF860EBDA@mail.mycompany.com>
Date: Thu, 11 Nov 2010 22:55:46 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 5 FETCH (UID 5 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 18:42:16 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111231545.6C30AEBDB@mail.mycompany.com>
Date: Fri, 12 Nov 2010 00:15:45 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 6 FETCH (UID 6 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 18:42:15 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111215232.0FE9BEBD8@mail.mycompany.com>
Date: Thu, 11 Nov 2010 22:52:32 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 7 FETCH (UID 7 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 18:42:16 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111214051.65503EBD4@mail.mycompany.com>
Date: Thu, 11 Nov 2010 22:40:51 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 8 FETCH (UID 8 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 18:42:15 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111215407.8E728EBD9@mail.mycompany.com>
Date: Thu, 11 Nov 2010 22:54:07 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 9 FETCH (UID 9 RFC822.SIZE 588 FLAGS () INTERNALDATE "12-Nov-2010 18:42:15 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111230921.EFEDCEBDD@mail.mycompany.com>
Date: Fri, 12 Nov 2010 00:09:21 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 10 FETCH (UID 10 RFC822.SIZE 588 FLAGS (\Seen) INTERNALDATE "12-Nov-2010 18:42:15 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {295}
[14-Nov-2010 21:15:50 +0100]: S: To: info@test.local
From: admin@test.local
Subject: =?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: 8bit
Message-Id: <20101111214522.E27C7EBD7@mail.mycompany.com>
Date: Thu, 11 Nov 2010 22:45:22 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 11 FETCH (UID 11 RFC822.SIZE 500 FLAGS (\Seen) INTERNALDATE "13-Nov-2010 01:41:19 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {186}
[14-Nov-2010 21:15:50 +0100]: S: From: asdasd@asdasd.hu
To: info@test.local
Subject: Test message from Roundcube
Message-Id: <20101113004119.175C4EBD4@mail.test.local>
Date: Sat, 13 Nov 2010 01:41:19 +0100 (CET)


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: * 12 FETCH (UID 12 RFC822.SIZE 614 FLAGS (\Seen) INTERNALDATE "13-Nov-2010 02:11:45 +0100" BODY[HEADER.FIELDS (DATE FROM TO SUBJECT REPLY-TO IN-REPLY-TO CC BCC CONTENT-TRANSFER-ENCODING CONTENT-TYPE MESSAGE-ID REFERENCES DISPOSITION-NOTIFICATION-TO X-PRIORITY X-DRAFT-INFO)] {285}
[14-Nov-2010 21:15:50 +0100]: S: Content-Type: text/plain;
 charset=UTF-8;
 format=flowed
Content-Transfer-Encoding: 8bit
Date: Sat, 13 Nov 2010 02:11:45 +0100
From: <info@test.local>
To: <info@test.local>
Subject: teszt =?UTF-8?Q?=C3=B6nmagamnak?=
Message-ID: <63fe24660503915916dc6ef30ea673ae@test.local>


[14-Nov-2010 21:15:50 +0100]: S: )
[14-Nov-2010 21:15:50 +0100]: S: FH12 OK Fetch completed.
[14-Nov-2010 21:15:50 +0100]: C: I LOGOUT

the logs/errors not changing.

comment:10 Changed 3 years ago by levente.csabai

When the error occours, the /var/log/mysql/mysql.log:

101114 21:35:02	     44 Connect     roundcube@localhost on roundcubemail
		     44 Query       SET NAMES utf8
		     44 Query       SET NAMES utf8
		     44 Query       PREPARE mdb2_statement_mysqli_11f93a884301579ce927accf03cff198fc44836d3 FROM 'SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?'
		     44 Prepare     [1] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?
		     44 Query       SET @0 = '44857c1b152964a6929c906564a15112'
		     44 Query       EXECUTE mdb2_statement_mysqli_11f93a884301579ce927accf03cff198fc44836d3 USING @0
		     44 Execute     [1] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = '44857c1b152964a6929c906564a15112'
		     44 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_11f93a884301579ce927accf03cff198fc44836d3
		     44 Query       PREPARE mdb2_statement_mysqli_2911704708c0c5f6a75b3f8def67ea6d8a90d31e4 FROM 'SELECT * FROM users WHERE user_id = ?'
		     44 Prepare     [2] SELECT * FROM users WHERE user_id = ?
		     44 Query       SET @0 = '1'
		     44 Query       EXECUTE mdb2_statement_mysqli_2911704708c0c5f6a75b3f8def67ea6d8a90d31e4 USING @0
		     44 Execute     [2] SELECT * FROM users WHERE user_id = '1'
		     44 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_2911704708c0c5f6a75b3f8def67ea6d8a90d31e4
101114 21:35:03	     41 Query       SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
		     41 Query       SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
		     44 Query       PREPARE mdb2_statement_mysqli_3dadfb5951a7bc2115d9bede24f6b2b7bae9ffe8a FROM 'SELECT cache_id, data, cache_key FROM cache WHERE user_id=? AND cache_key LIKE \'IMAP.%\''
		     44 Prepare     [3] SELECT cache_id, data, cache_key FROM cache WHERE user_id=? AND cache_key LIKE 'IMAP.%'
		     44 Query       SET @0 = '1'
		     44 Query       EXECUTE mdb2_statement_mysqli_3dadfb5951a7bc2115d9bede24f6b2b7bae9ffe8a USING @0
		     44 Execute     [3] SELECT cache_id, data, cache_key FROM cache WHERE user_id='1' AND cache_key LIKE 'IMAP.%'
		     44 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_3dadfb5951a7bc2115d9bede24f6b2b7bae9ffe8a
		     44 Prepare     [4] UPDATE session SET vars = ?, changed = FROM_UNIXTIME(1289766903) WHERE sess_id = ?
		     44 Execute     [4] UPDATE session SET vars = 'language|s:5:\"hu_HU\";auth_time|i:1289765639;imap_root|s:0:\"\";imap_delimiter|s:1:\".\";user_id|s:1:\"1\";username|s:15:\"info@test.local\";imap_host|s:15:\"mail.test.local\";imap_port|i:143;imap_ssl|N;password|s:24:\"iqhxfSW1rmCOCbbC4pOm6g==\";login_time|i:1289765639;timezone|d:1;task|s:4:\"mail\";request_tokens|a:1:{s:4:\"mail\";s:32:\"c94277efa3756153ab0cf847cb682320\";}mbox|s:5:\"INBOX\";sort_col|s:0:\"\";sort_order|s:4:\"DESC\";list_attrib|a:16:{s:4:\"name\";s:8:\"messages\";s:2:\"id\";s:11:\"messagelist\";s:11:\"cellspacing\";s:1:\"0\";s:7:\"columns\";s:0:\"\";s:7:\"summary\";s:12:\"Message list\";s:11:\"messageicon\";s:21:\"/images/icons/dot.png\";s:10:\"unreadicon\";s:24:\"/images/icons/unread.png\";s:11:\"deletedicon\";s:25:\"/images/icons/deleted.png\";s:11:\"repliedicon\";s:25:\"/images/icons/replied.png\";s:13:\"forwardedicon\";s:27:\"/images/icons/forwarded.png\";s:20:\"forwardedrepliedicon\";s:35:\"/images/icons/forwarded_replied.png\";s:14:\"attachmenticon\";s:28:\"/images/icons/attachment.png\";s:11:\"flaggedicon\";s:25:\"/images/icons/flagged.png\";s:13:\"unflaggedicon\";s:23:\"/images/icons/blank.gif\";s:18:\"unreadchildrenicon\";s:0:\"\";s:15:\"optionsmenuicon\";s:30:\"/images/icons/columnpicker.gif\";}skin_path|s:13:\"skins/default\";unseen_count|a:2:{s:5:\"INBOX\";i:8;s:4:\"Sent\";i:0;}folders|a:1:{s:5:\"INBOX\";a:2:{s:3:\"cnt\";i:12;s:6:\"maxuid\";s:2:\"12\";}}safe_messages|a:3:{i:12;b:0;i:10;b:0;i:9;b:0;}', changed = FROM_UNIXTIME(1289766903) WHERE sess_id = '44857c1b152964a6929c906564a15112'
		     44 Quit       
101114 21:35:04	     45 Connect     roundcube@localhost on roundcubemail
		     45 Query       SET NAMES utf8
		     45 Query       SET NAMES utf8
		     46 Connect     roundcube@localhost on roundcubemail
		     46 Query       SET NAMES utf8
		     46 Query       SET NAMES utf8
		     45 Query       PREPARE mdb2_statement_mysqli_1e9525a290917a43db55a9ce3db318ffbed524f7f FROM 'SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?'
		     45 Prepare     [1] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?
		     45 Query       SET @0 = '44857c1b152964a6929c906564a15112'
		     45 Query       EXECUTE mdb2_statement_mysqli_1e9525a290917a43db55a9ce3db318ffbed524f7f USING @0
		     45 Execute     [1] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = '44857c1b152964a6929c906564a15112'
		     45 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_1e9525a290917a43db55a9ce3db318ffbed524f7f
		     46 Query       PREPARE mdb2_statement_mysqli_13cd42db8c835b1771ef700865b6162b201a1742c FROM 'SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?'
		     46 Prepare     [1] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?
		     45 Query       PREPARE mdb2_statement_mysqli_2f7314d348cc2e95cb99d6e07e01acc42d6891e17 FROM 'SELECT * FROM users WHERE user_id = ?'
		     45 Prepare     [2] SELECT * FROM users WHERE user_id = ?
		     46 Query       SET @0 = '44857c1b152964a6929c906564a15112'
		     46 Query       EXECUTE mdb2_statement_mysqli_13cd42db8c835b1771ef700865b6162b201a1742c USING @0
		     46 Execute     [1] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = '44857c1b152964a6929c906564a15112'
		     46 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_13cd42db8c835b1771ef700865b6162b201a1742c
		     46 Query       PREPARE mdb2_statement_mysqli_272a32549d0c0f86113e8b4e65956360e27cc9fa0 FROM 'SELECT * FROM users WHERE user_id = ?'
		     46 Prepare     [2] SELECT * FROM users WHERE user_id = ?
		     46 Query       SET @0 = '1'
		     45 Query       SET @0 = '1'
		     46 Query       EXECUTE mdb2_statement_mysqli_272a32549d0c0f86113e8b4e65956360e27cc9fa0 USING @0
		     46 Execute     [2] SELECT * FROM users WHERE user_id = '1'
		     45 Query       EXECUTE mdb2_statement_mysqli_2f7314d348cc2e95cb99d6e07e01acc42d6891e17 USING @0
		     45 Execute     [2] SELECT * FROM users WHERE user_id = '1'
		     46 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_272a32549d0c0f86113e8b4e65956360e27cc9fa0
		     45 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_2f7314d348cc2e95cb99d6e07e01acc42d6891e17
		     41 Query       SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
		     41 Query       SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
		     41 Query       SELECT username as user, password, '/srv/vmail/test.local/info' as userdb_home, 'maildir:/srv/vmail/test.local/info' as userdb_mail, 150 as userdb_uid, 8 as userdb_gid FROM mailbox WHERE username = 'info@test.local' AND active = '1'
		     41 Query       SELECT '/srv/vmail/test.local/info' as home, 'maildir:/srv/vmail/test.local/info' as mail, 150 AS uid, 8 AS gid, concat('dirsize:storage=', quota) AS quota FROM mailbox WHERE username = 'info@test.local' AND active = '1'
101114 21:35:05	     46 Query       PREPARE mdb2_statement_mysqli_3b05df3817f41b851300436b61f50cb0c40f55898 FROM 'SELECT cache_id, data, cache_key FROM cache WHERE user_id=? AND cache_key LIKE \'IMAP.%\''
		     46 Prepare     [3] SELECT cache_id, data, cache_key FROM cache WHERE user_id=? AND cache_key LIKE 'IMAP.%'
		     45 Query       PREPARE mdb2_statement_mysqli_3f6b5e6d3dd8cd0cc0e4621d8d43fcdd6991ab28b FROM 'SELECT cache_id, data, cache_key FROM cache WHERE user_id=? AND cache_key LIKE \'IMAP.%\''
		     45 Prepare     [3] SELECT cache_id, data, cache_key FROM cache WHERE user_id=? AND cache_key LIKE 'IMAP.%'
		     46 Query       SET @0 = '1'
		     46 Query       EXECUTE mdb2_statement_mysqli_3b05df3817f41b851300436b61f50cb0c40f55898 USING @0
		     46 Execute     [3] SELECT cache_id, data, cache_key FROM cache WHERE user_id='1' AND cache_key LIKE 'IMAP.%'
		     46 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_3b05df3817f41b851300436b61f50cb0c40f55898
		     45 Query       SET @0 = '1'
		     45 Query       EXECUTE mdb2_statement_mysqli_3f6b5e6d3dd8cd0cc0e4621d8d43fcdd6991ab28b USING @0
		     45 Execute     [3] SELECT cache_id, data, cache_key FROM cache WHERE user_id='1' AND cache_key LIKE 'IMAP.%'
		     45 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_3f6b5e6d3dd8cd0cc0e4621d8d43fcdd6991ab28b
		     46 Quit       
		     45 Query       PREPARE mdb2_statement_mysqli_4d5219ff93d09f558717377ee5682a975ec75ac06 FROM 'SELECT idx, uid FROM messages WHERE user_id=? AND cache_key=? ORDER BY `idx` ASC'
		     45 Prepare     [4] SELECT idx, uid FROM messages WHERE user_id=? AND cache_key=? ORDER BY `idx` ASC
		     45 Query       SET @0 = '1'
		     45 Query       SET @1 = 'INBOX.msg'
		     45 Query       EXECUTE mdb2_statement_mysqli_4d5219ff93d09f558717377ee5682a975ec75ac06 USING @0, @1
		     45 Execute     [4] SELECT idx, uid FROM messages WHERE user_id='1' AND cache_key='INBOX.msg' ORDER BY `idx` ASC
		     45 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_4d5219ff93d09f558717377ee5682a975ec75ac06
		     45 Query       PREPARE mdb2_statement_mysqli_5b8d966c0dc4fbe819773831ab514b0d25300bff2 FROM 'SELECT message_id FROM messages WHERE user_id=? AND cache_key=? AND uid=?'
		     45 Prepare     [5] SELECT message_id FROM messages WHERE user_id=? AND cache_key=? AND uid=?
		     45 Query       SET @0 = '1'
		     45 Query       SET @1 = 'INBOX.msg'
		     45 Query       SET @2 = '9'
		     45 Query       EXECUTE mdb2_statement_mysqli_5b8d966c0dc4fbe819773831ab514b0d25300bff2 USING @0, @1, @2
		     45 Execute     [5] SELECT message_id FROM messages WHERE user_id='1' AND cache_key='INBOX.msg' AND uid='9'
		     45 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_5b8d966c0dc4fbe819773831ab514b0d25300bff2
		     45 Prepare     [6] INSERT INTO messages (user_id, del, cache_key, created, idx, uid, subject, `from`, `to`, cc, date, size, headers, structure) VALUES (?, 0, ?, now(), ?, ?, ?, ?, ?, ?, FROM_UNIXTIME(1289516961), ?, ?, ?)
		     45 Execute     [6] INSERT INTO messages (user_id, del, cache_key, created, idx, uid, subject, `from`, `to`, cc, date, size, headers, structure) VALUES ('1', 0, 'INBOX.msg', now(), '9', '9', 'Postafiókja sikeresen elkészült!', 'admin@test.local', 'info@test.local', '', FROM_UNIXTIME(1289516961), '588', 'O:17:\"rcube_mail_header\":35:{s:2:\"id\";s:1:\"9\";s:3:\"uid\";s:1:\"9\";s:7:\"subject\";s:59:\"=?utf-8?Q?Postafi=C3=B3kja_sikeresen_elk=C3=A9sz=C3=BClt!?=\";s:4:\"from\";s:16:\"admin@test.local\";s:2:\"to\";s:15:\"info@test.local\";s:2:\"cc\";s:0:\"\";s:7:\"replyto\";s:0:\"\";s:11:\"in_reply_to\";s:0:\"\";s:4:\"date\";s:37:\"Fri, 12 Nov 2010 00:09:21 +0100 (CET)\";s:9:\"messageID\";s:45:\"<20101111230921.EFEDCEBDD@mail.mycompany.com>\";s:4:\"size\";s:3:\"588\";s:8:\"encoding\";s:4:\"8bit\";s:7:\"charset\";s:5:\"utf-8\";s:5:\"ctype\";s:10:\"text/plain\";s:5:\"flags\";a:1:{i:0;s:4:\"Seen\";}s:9:\"timestamp\";s:10:\"1289516961\";s:1:\"f\";s:0:\"\";s:14:\"body_structure\";s:0:\"\";s:12:\"internaldate\";s:26:\"12-Nov-2010 18:42:15 +0100\";s:10:\"references\";s:0:\"\";s:8:\"priority\";s:0:\"\";s:6:\"mdn_to\";s:0:\"\";s:8:\"mdn_sent\";s:0:\"\";s:8:\"is_draft\";s:0:\"\";s:4:\"seen\";s:1:\"1\";s:7:\"deleted\";s:0:\"\";s:6:\"recent\";s:0:\"\";s:8:\"answered\";s:0:\"\";s:9:\"forwarded\";s:0:\"\";s:4:\"junk\";s:0:\"\";s:7:\"flagged\";s:0:\"\";s:12:\"has_children\";s:0:\"\";s:5:\"depth\";s:1:\"0\";s:15:\"unread_children\";s:1:\"0\";s:6:\"others\";a:0:{}}', NULL)
		     45 Prepare     [7] INSERT INTO messages (user_id, del, cache_key, created, idx, uid, subject, `from`, `to`, cc, date, size, headers, structure) VALUES (?, 0, ?, now(), ?, ?, ?, ?, ?, ?, FROM_UNIXTIME(1289516961), ?, ?, ?)
		     45 Prepare     [8] UPDATE cache SET created=now(), data=? WHERE user_id=? AND cache_key=?
		     45 Execute     [8] UPDATE cache SET created=now(), data='a:3:{s:13:\"dovecot.sieve\";a:1:{s:3:\"ALL\";i:0;}s:5:\"INBOX\";a:2:{s:3:\"ALL\";i:12;s:6:\"UNSEEN\";i:8;}s:4:\"Sent\";a:1:{s:6:\"UNSEEN\";i:0;}}' WHERE user_id='1' AND cache_key='IMAP.messagecount'
		     45 Query       PREPARE mdb2_statement_mysqli_67d6f511cd947e1f49219dac23c746bdb95c51b89 FROM 'SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?'
		     45 Prepare     [9] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = ?
		     45 Query       SET @0 = '44857c1b152964a6929c906564a15112'
		     45 Query       EXECUTE mdb2_statement_mysqli_67d6f511cd947e1f49219dac23c746bdb95c51b89 USING @0
		     45 Execute     [9] SELECT vars, ip, UNIX_TIMESTAMP(changed) AS changed FROM session WHERE sess_id = '44857c1b152964a6929c906564a15112'
		     45 Query       DEALLOCATE PREPARE mdb2_statement_mysqli_67d6f511cd947e1f49219dac23c746bdb95c51b89
		     45 Quit

comment:11 Changed 3 years ago by levente.csabai

If I set the debug_level to 8, the Firebug's console says 3 things when the error appears:

HTTP GET: ./?_task=mail&_remote=1&_action=list&_mbox=INBOX&_refresh=1
GET http://mail.test.local/?_task=mail&_remote=1&_action=list&_mbox=INBOX&_refresh=1&_=1289769968813&_unlock=1 jquery...6747840 (130. row)
Reply:
<pre>object(mysqli_stmt)#24 (0) {
}
string(42) "Duplicate entry '1-INBOX.msg-10' for key 2"

HTTP GET: ./?_task=mail&_remote=1&_action=getunread
GET http://mail.test.local/?_task=mail&_remote=1&_action=getunread&_=1289769968885&_unlock=0 jquery...6747840 (130. row)
Reply:
{"action":"getunread","unlock":false,"exec":"this.set_unread_count(\"INBOX\",10,true);\n"}

this.set_unread_count("INBOX",10,true);


comment:12 Changed 3 years ago by rosali

It looks to me like a Roundcube caching issue:

string(42) "Duplicate entry '1-INBOX.msg-10' for key 2"

comment:13 Changed 3 years ago by alec

Yes, but this shouldn't be printed to the browser. So, there's something wrong with error logging from MDB2 package. Are you using MDB2 package source from Roundcube code tree?

comment:14 Changed 3 years ago by alec

Fixed in [3e54812a].

comment:15 Changed 3 years ago by alec

  • Resolution set to fixed
  • Status changed from reopened to closed
Note: See TracTickets for help on using tickets.