Zhang Huangbin wrote:

Dear developers,

I always get this error message when logging to Roundcube webmail 0.6 stable release: Your session is invalid or expired. Detail message is:

DB Error in /srv/www/roundcubemail-0.6/program/include/rcube_mdb2.php (713): MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-29 21:10:59', '2011-10-29 21:10:59')] [Native code: 1062] [Native message: Duplicate entry 'rrfpesqbtad1g89r6ue1mv26641rf8qe5q2rd4ot' for key 'PRIMARY']

Before inserting a new session record, Roundcube attempts to read it. Obviously the database doesn't return an obviously existing record.

Do you have some database replication in place or different servers defined for reading and writing?

~Thomas _______________________________________________ List info: http://lists.roundcube.net/dev/ BT/aba52c80

Before inserting a new session record, Roundcube attempts to read it. Obviously the database doesn't return an obviously existing record.

Do you have some database replication in place or different servers defined for reading and writing?

We see a few of these in our logs too (even back before we updated from 0.5.1 to 0.6,) but only have a report from one user who actually can not login (which is what I'm more concerned about.)

We use mysqli. No replication, single local mysql server.

Here are all our "constraint violation" log entries from Friday (12) (You'll notice there are two for each event -- perhaps because we have session logging turned on.) You can see sometimes this happens when "task=logout" sometimes it's other GET's:

Oct 27 08:15:35 npomail1 roundcube: MDB2 Error: constraint violation (-3): _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 08:15:35', '2011-10-27 08:15:35')] [Native code: 1062] [Native message: Duplicate entry 'lion03f2nibap0oliel7l3bq92' for key 'PRIMARY'] Oct 27 08:15:35 npomail1 roundcube: DB Error: MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 08:15:35', '2011-10-27 08:15:35')] [Native code: 1062] [Native message: Duplicate entry 'lion03f2nibap0oliel7l3bq92' for key 'PRIMARY'] in /home/www/roundcubemail-0.6/program/include/rcube_mdb2.php on line 713 (GET /rc/?_task=mail&_action=keep-alive&_remote=1&_unlock=0&_=1319728543646) Oct 27 09:46:43 npomail1 roundcube: MDB2 Error: constraint violation (-3): _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 09:46:43', '2011-10-27 09:46:43')] [Native code: 1062] [Native message: Duplicate entry '1l2si5uqfuoobdqfjmkg3jm3j3' for key 'PRIMARY'] Oct 27 09:46:43 npomail1 roundcube: DB Error: MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 09:46:43', '2011-10-27 09:46:43')] [Native code: 1062] [Native message: Duplicate entry '1l2si5uqfuoobdqfjmkg3jm3j3' for key 'PRIMARY'] in /home/www/roundcubemail-0.6/program/include/rcube_mdb2.php on line 713 (GET /rc/?_task=mail&_action=get&_uid=23601&_mbox=INBOX&_part=7) Oct 27 10:19:57 npomail1 roundcube: MDB2 Error: constraint violation (-3): _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 10:19:57', '2011-10-27 10:19:57')] [Native code: 1062] [Native message: Duplicate entry '846onromelomao4vfflgr4v532' for key 'PRIMARY'] Oct 27 10:19:57 npomail1 roundcube: DB Error: MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 10:19:57', '2011-10-27 10:19:57')] [Native code: 1062] [Native message: Duplicate entry '846onromelomao4vfflgr4v532' for key 'PRIMARY'] in /home/www/roundcubemail-0.6/program/include/rcube_mdb2.php on line 713 (GET /rc/?_task=mail&_action=get&_uid=668&_mbox=INBOX&_part=2.3) Oct 27 11:36:05 npomail1 roundcube: MDB2 Error: constraint violation (-3): _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 11:36:05', '2011-10-27 11:36:05')] [Native code: 1062] [Native message: Duplicate entry '2eajae36tphem31t2vsl769pr3' for key 'PRIMARY'] Oct 27 11:36:05 npomail1 roundcube: DB Error: MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 11:36:05', '2011-10-27 11:36:05')] [Native code: 1062] [Native message: Duplicate entry '2eajae36tphem31t2vsl769pr3' for key 'PRIMARY'] in /home/www/roundcubemail-0.6/program/include/rcube_mdb2.php on line 713 (GET /rc/?_task=logout) Oct 27 12:00:48 npomail1 roundcube: MDB2 Error: constraint violation (-3): _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 12:00:48', '2011-10-27 12:00:48')] [Native code: 1062] [Native message: Duplicate entry '59uvl0oqq86lpjlfddvf5ierk0' for key 'PRIMARY'] Oct 27 12:00:48 npomail1 roundcube: DB Error: MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 12:00:48', '2011-10-27 12:00:48')] [Native code: 1062] [Native message: Duplicate entry '59uvl0oqq86lpjlfddvf5ierk0' for key 'PRIMARY'] in /home/www/roundcubemail-0.6/program/include/rcube_mdb2.php on line 713 (GET /rc/?_task=logout) Oct 27 18:58:57 npomail1 roundcube: MDB2 Error: constraint violation (-3): _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 18:58:57', '2011-10-27 18:58:57')] [Native code: 1062] [Native message: Duplicate entry 'a0cbefgpe2vfdc1hps2grqmtj2' for key 'PRIMARY'] Oct 27 18:58:57 npomail1 roundcube: DB Error: MDB2 Error: constraint violation Query: _execute: [Error message: Unable to execute statement] [Last executed query: INSERT INTO session (sess_id, vars, ip, created, changed) VALUES (?, ?, ?, '2011-10-27 18:58:57', '2011-10-27 18:58:57')] [Native code: 1062] [Native message: Duplicate entry 'a0cbefgpe2vfdc1hps2grqmtj2' for key 'PRIMARY'] in /home/www/roundcubemail-0.6/program/include/rcube_mdb2.php on line 713 (GET /rc/?_task=logout)

Whereas there were 126 entries like this that day:

Oct 28 00:06:09 npomail1 roundcube: Aborted session 2gq6bc3087t8854o2us8vj7au0; no valid session data found Oct 28 00:06:09 npomail1 roundcube: Aborted session 2gq6bc3087t8854o2us8vj7au0; no valid session data found Oct 28 00:06:50 npomail1 roundcube: Aborted session 2gq6bc3087t8854o2us8vj7au0; no valid session data found Oct 28 03:06:44 npomail1 roundcube: Aborted session 74m131d53lup0telsi159lp2n0; no valid session data found Oct 28 03:07:13 npomail1 roundcube: Aborted session 74m131d53lup0telsi159lp2n0; no valid session data found Oct 28 04:11:08 npomail1 roundcube: Aborted session 5l4hkqodjqoim22bs8hv8ua2e7; no valid session data found Oct 28 06:31:01 npomail1 roundcube: Aborted session 3sci6c8crn0t4073il1gh8bp42; no valid session data found Oct 28 07:32:06 npomail1 roundcube: Aborted session df567mjtd2kqf004s4v330l7d7; no valid session data found Oct 28 07:48:14 npomail1 roundcube: Aborted session bvqs7c676i4tppnqcjp68qlge2; no valid session data found Oct 28 07:50:36 npomail1 roundcube: Aborted session hsbo60acp17imr4nq1f5qq77n6; no valid session data found Oct 28 07:51:29 npomail1 roundcube: Aborted session 6488e7r8jc92li4s3b551ft7e1; no valid session data found Oct 28 08:23:35 npomail1 roundcube: Aborted session ef0fa50be805ca526b54ccfdc45f60cf; no valid session data found . . .

I don't imagine each time this gets logged someone has a problem, or we'd have heard more reports, but what do they mean? _______________________________________________ List info: http://lists.roundcube.net/dev/ BT/aba52c80

On Thu, Nov 3, 2011 at 4:33 AM, Zhang Huangbin zhbmaillistonly@gmail.comwrote:

On Monday, October 31, 2011 at 12:09 AM, Thomas Bruederli wrote:

...

Do you have some database replication in place or different servers

defined

...

for reading and writing?

Dear developers,

Any update on this issue? Thanks for your time. :)

No, you could help debug it though.

As far as I understand, you were one of a few to catch this issue, I'm looked at the code, I'm not sure what we can do to reproduce it. It sounds like a race-condition of some sort. But I'm not sure what the circumstances are.

Till

List info: http://lists.roundcube.net/dev/ BT/aba52c80

Am 03.11.2011 22:44, schrieb emi:

Hi everybody,

I found a plugin causing this issue: recipient_to_contact

Deactivating the plugin causes RC to work properly again. May this be the problem? (or some other plugin).

On line 189 of the plugin the session id is regenerated. I'm not sure why this should be necessary. Maybe this is the issue?

See you, emi

List info: http://lists.roundcube.net/dev/ BT/aba52c80