Hi All,
I have an odd problem. Here's my setup:
Debian Etch
SquirrelMail 1.4.9a-1
Horde 3.1.3-3/IMP 4.1.3-2
DBMail from the deb packages via apt.sources 2.2.3-1
So, this is the problem. This step by step process can be duplicated on
my test box:
1) Create new account in dbmail
2) Login with either IMP and/or Squirrelmail - I have both installed to
test this problem - All looks well right?
2) Send an email to this new user
3) The email can be seen from either webmail
4) Now read the message in either webmail
5) In IMP, the message disappears after you attempt to go to the inbox
after reading the message
6) If you go to the inbox in Squirrelmail, you get this message: ERROR:
Connection dropped by IMAP server. Query: EXPUNGE
7) Send a new email to the user and you'll be able to see the inbox and
both messages will show up
8) Delete the new message and the problem returns
Below is an excerpt from my mail log with debug turned to 10.
Any ideas?
Thanks,
Stephen
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[serverchild]
serverchild.c,select_and_accept(+252): received connection
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[serverchild]
serverchild.c,select_and_accept(+275): connection accepted
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Message:[serverchild]
serverchild.c,PerformChildTask(+342): incoming connection from
[127.0.0.1] by pid [17682]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[serverchild]
serverchild.c,PerformChildTask(+365): client info init complete, calling
client handler
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* OK
dbmail imap (protocol version 4r1) server 2.2 ready to run^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[imap]
imap4.c,IMAPClientHandler(+197): COMMAND: [A001 LOGIN "[EMAIL PROTECTED]"
"password"]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,build_args_array_ext(+2127): arg[0]:
'[EMAIL PROTECTED]'
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,build_args_array_ext(+2127): arg[1]: 'password'
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[imap]
imap4.c,IMAPClientHandler(+310): Executing command login...
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_handle_auth(+1377): trying to
validate user [EMAIL PROTECTED], pass [XXXX]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_usermap_resolve(+4720): checking userid [EMAIL PROTECTED] in
usermap
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_usermap_resolve(+4731): client on inet socket
[inet:127.0.0.1:143]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT login, sock_allow, sock_deny,
userid FROM dbmail_usermap WHERE login in ('[EMAIL PROTECTED]','ANY')
ORDER BY sock_allow, sock_deny]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_usermap_resolve(+4757): login [EMAIL PROTECTED] not found in
usermap
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT user_idnr FROM dbmail_users
WHERE lower(userid) = lower('[EMAIL PROTECTED]')]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT user_idnr, passwd,
encryption_type FROM dbmail_users WHERE user_idnr = 23]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[auth]
authsql.c,auth_validate(+413): validating using plaintext passwords
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [UPDATE dbmail_users SET last_login =
'2007-03-09 17:39:41' WHERE user_idnr = 23]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Message:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_handle_auth(+1382): user
(id:23, name [EMAIL PROTECTED]) tries login
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Message:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_handle_auth(+1403): user (id
23, name [EMAIL PROTECTED]) login accepted
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [A001
OK LOGIN completed^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[imap]
imap4.c,IMAPClientHandler(+338): Finished command login [0]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[imap]
imap4.c,IMAPClientHandler(+197): COMMAND: [A002 SELECT "INBOX"]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,build_args_array_ext(+2127): arg[0]: 'INBOX'
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[imap]
imap4.c,IMAPClientHandler(+310): Executing command select...
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_findmailbox(+2465): looking for mailbox with FQN [INBOX].
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT mailbox_idnr FROM
dbmail_mailboxes WHERE name LIKE 'INBOX' AND owner_idnr=23]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_acl_has_right(+4243): checking ACL [read_flag] for user [23] on
mailbox [38]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT owner_idnr FROM dbmail_mailboxes
WHERE mailbox_idnr = 38]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_acl_has_right(+4253): mailbox [38] is owned by user [23], is
that also [23]?
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_acl_has_right(+4257): mailbox [38] is owned by user [23], giving
all rights
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT no_select FROM dbmail_mailboxes
WHERE mailbox_idnr = 38]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT
permission,seen_flag,answered_flag,deleted_flag,flagged_flag,recent_flag
,draft_flag FROM dbmail_mailboxes WHERE mailbox_idnr = 38]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT 'a',COUNT(*) FROM
dbmail_messages WHERE mailbox_idnr=38 AND (status < 2) UNION SELECT
'b',COUNT(*) FROM dbmail_messages WHERE mailbox_idnr=38 AND (status < 2)
AND seen_flag=1 UNION SELECT 'c',COUNT(*) FROM dbmail_messages WHERE
mailbox_idnr=38 AND (status < 2) AND recent_flag=1]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT message_idnr FROM
dbmail_messages WHERE mailbox_idnr = 38 AND status < 2 ORDER BY
message_idnr ASC]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[db]
db.c,db_getmailbox_count(+2881): exists [1]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT message_idnr+1 FROM
dbmail_messages ORDER BY message_idnr DESC LIMIT 1]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT message_idnr FROM
dbmail_messages WHERE mailbox_idnr = 38 AND status IN (0,1) ORDER BY
message_idnr]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[mailbox]
dbmail-mailbox.c,mailbox_build_uid_map(+156): ids [1], msn [1]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* 1
EXISTS^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* 0
RECENT^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [*
FLAGS (\Seen \Answered \Deleted \Flagged \Draft \Recent)^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* OK
[PERMANENTFLAGS (\Seen \Answered \Deleted \Flagged \Draft)]^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* OK
[UIDNEXT 1275] Predicted next UID^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* OK
[UIDVALIDITY 38] UID value^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_query(+286): query [SELECT MIN(message_idnr) FROM
dbmail_messages WHERE mailbox_idnr = 38 AND status < 2 AND seen_flag =
0]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[sql]
dbmysql.c,db_get_result(+244): result is null
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [* BYE
internal dbase error^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Error:[imap]
imap4.c,IMAPClientHandler(+325): command return with error [select]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[imap]
imap4.c,IMAPClientHandler(+338): Finished command select [-1]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[imapsession]
dbmail-imapsession.c,dbmail_imap_session_printf(+1296): RESPONSE: [A002
OK completed^M ]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Message:[imap]
imap4.c,IMAPClientHandler(+386): Closing connection for client from IP
[127.0.0.1]
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Debug:[serverchild]
serverchild.c,PerformChildTask(+370): client handling complete, closing
streams
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[serverchild]
serverchild.c,PerformChildTask(+372): connection closed
Mar 9 17:39:41 neflheim dbmail/imap4d[17682]: Info:[serverchild]
serverchild.c,select_and_accept(+223): waiting for connection
_______________________________________________
DBmail mailing list
[email protected]
https://mailman.fastxs.nl/mailman/listinfo/dbmail
