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