Paul J Stevens wrote:
Hi there,
try increasing the trace_level for delivery to 5 and see what the logs
show then.
Hi!
Here's the log on trace_level = 5. Examining it, I see that dbmail only
attempts to insert the message for the first email on the list, and
never looks for the second email in the users table. Could this be a
dbmail bug?
email1 has user id 17, email2 has userid 25.
Thanks in advance
Cristóvão
Mar 3 09:52:49 s2 postfix/smtpd[30388]: connect from
unknown[200.250.222.67]
Mar 3 09:52:49 s2 postfix/smtpd[30388]: 59AB31870114:
client=unknown[200.250.222.67], sasl_method=CRAM-MD5,
[EMAIL PROTECTED]
Mar 3 09:52:49 s2 postfix/cleanup[30500]: 59AB31870114:
message-id=<[EMAIL PROTECTED]>
Mar 3 09:52:49 s2 postfix/qmgr[73641]: 59AB31870114:
from=<[EMAIL PROTECTED]>, size=583, nrcpt=2 (queue active)
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [host]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): NULL item
value in item-list
Mar 3 09:52:49 s2 last message repeated 3 times
<--- snip log of finding database user and password --->
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): item not found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [sqlport]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): NULL item
value in item-list
Mar 3 09:52:49 s2 last message repeated 3 times
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): item not found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [sqlsocket]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): NULL item
value in item-list
Mar 3 09:52:49 s2 last message repeated 3 times
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): item not found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT 1=1 FROM dbmail_physmessage LIMIT 1 OFFSET 0]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: header.c,consume_header_line: end
of header found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: header.c,get_rfc_size:
remaining_len = 1
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mime_readheader(): entering mime loop
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mime_readheader(): found double
newline; header size: 14 lines
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): mimelist
currently has [11] nodes
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): mail address
parser starting
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): total fields in
header 11
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): scanning for
Return-Path
Mar 3 09:52:49 s2 last message repeated 10 times
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): found 0
emailaddresses
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): mail address
parser finished
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): mimelist
currently has [11] nodes
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): mail address
parser starting
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): total fields in
header 11
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): scanning for From
Mar 3 09:52:49 s2 last message repeated 7 times
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): found
[EMAIL PROTECTED], next in list is <null>
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): scanning for From
Mar 3 09:52:49 s2 last message repeated 2 times
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): found 1
emailaddresses
Mar 3 09:52:49 s2 dbmail/smtp[30509]: mail_adr_list(): mail address
parser finished
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT user_idnr FROM dbmail_users WHERE userid='[EMAIL PROTECTED]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: previous
result set is possibly not freed.
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dsn.c, dsnuser_resolve: added
user [EMAIL PROTECTED] id [17] to delivery list
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [BEGIN]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT user_idnr FROM dbmail_users WHERE userid='[EMAIL PROTECTED]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: misc.c,create_unique_id: created:
4763335abf91dbf7719cd5e24c9912fd
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE name='INBOX' AND ow]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: db.c, db_find_create_mailbox:
mailbox [INBOX] found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [INSERT INTO dbmail_physmessage (messagesize, internal_date) VALUES ]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT currval('dbmail_physmessage_id_seq')]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [INSERT INTO dbmail_messages(mailbox_idnr, physmessage_id, unique_id]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT currval('dbmail_message_idnr_seq')]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT physmessage_id FROM dbmail_messages WHERE message_idnr = '24]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [INSERT INTO dbmail_messageblks (is_header, messageblk, blocksize, p]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT currval('dbmail_messageblk_idnr_seq')]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: store_message_temp(): allocating
[524288] bytes of memory for readblock
Mar 3 09:52:49 s2 dbmail/smtp[30509]: pipe.c,store_message_in_blocks:
inserting message:
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT physmessage_id FROM dbmail_messages WHERE message_idnr = '24]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [INSERT INTO dbmail_messageblks (is_header, messageblk, blocksize, p]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT currval('dbmail_messageblk_idnr_seq')]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [UPDATE dbmail_messages SET unique_id = '4763335abf91dbf7719cd5e24c9]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT physmessage_id FROM dbmail_messages WHERE message_idnr = '24]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [UPDATE dbmail_physmessage SET messagesize = '566', rfcsize = '581' ]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT dbmail_mailboxes.owner_idnr FROM dbmail_mailboxes, dbmail_me]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: db.c,db_add_quotum_used: adding
566 to mailsize
Mar 3 09:52:49 s2 dbmail/smtp[30509]:
db.c.user_idnr_is_delivery_user_idnr: looking up user_idnr for
[EMAIL PROTECTED]@__
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT user_idnr FROM dbmail_users WHERE userid='[EMAIL PROTECTED]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: pipe.c, insert_messages:
temporary msgidnr is [242995]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: pipe.c, insert_messages: calling
sort_and_deliver for useridnr [17]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [SQLREGEX]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): item not found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [LIBSIEVE]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): item not found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT mailbox_idnr FROM dbmail_mailboxes WHERE name='INBOX' AND ow]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: db.c, db_find_create_mailbox:
mailbox [INBOX] found
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT pm.messagesize FROM dbmail_physmessage pm, dbmail_messages m]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT 1 FROM dbmail_users WHERE user_idnr = '17' AND (maxmail_size]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: misc.c,create_unique_id: created:
45ca0f8baf229f241a4d9e0b08fbea80
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [INSERT INTO dbmail_messages (mailbox_idnr,physmessage_id, seen_flag]
Mar 3 09:52:49 s2 postfix/smtpd[30388]: disconnect from
unknown[200.250.222.67]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT currval('dbmail_message_idnr_seq')]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: db.c,db_add_quotum_used: adding
566 to mailsize
Mar 3 09:52:49 s2 dbmail/smtp[30509]:
db.c.user_idnr_is_delivery_user_idnr: no need to look up user_idnr for
[EMAIL PROTECTED]@__
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [UPDATE dbmail_users SET curmail_size = curmail_size + '566' WHERE u]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: sort.c, sort_and_deliver: message
id=242996, size=566 is inserted
Mar 3 09:52:49 s2 dbmail/smtp[30509]: pipe.c, insert_messages:
successful sort_and_deliver for useridnr [17]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [AUTO_NOTIFY]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): found value [no]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): searching value
for config item [AUTO_REPLY]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: GetConfigValue(): found value [no]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: insert_messages(): we need to
deliver [0] messages to external addresses
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT physmessage_id FROM dbmail_messages WHERE message_idnr = '24]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [DELETE FROM dbmail_messages WHERE message_idnr = '242995']
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [SELECT message_idnr FROM dbmail_messages WHERE physmessage_id = '14]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: insert_messages(): temporary
message deleted from database
Mar 3 09:52:49 s2 dbmail/smtp[30509]: insert_messages(): End of function
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dbpgsql.c,db_query: executing
query [COMMIT]
Mar 3 09:52:49 s2 dbmail/smtp[30509]: main(): freeing dsnuser list
Mar 3 09:52:49 s2 dbmail/smtp[30509]: dsn.c, dsnuser_free: dsnuser freed
Mar 3 09:52:49 s2 dbmail/smtp[30509]: main(): freeing all other lists
Mar 3 09:52:49 s2 dbmail/smtp[30509]: main(): freeing memory blocks
Mar 3 09:52:49 s2 dbmail/smtp[30509]: main(): they're all free. we're done.
Mar 3 09:52:49 s2 dbmail/smtp[30509]: main(): exit code is [0].
Mar 3 09:52:49 s2 postfix/pipe[30392]: 59AB31870114:
to=<[EMAIL PROTECTED]>, relay=dbmail-smtp, delay=0, status=sent
(smsweb.com.br)
Mar 3 09:52:49 s2 postfix/pipe[30392]: 59AB31870114:
to=<[EMAIL PROTECTED]>, relay=dbmail-smtp, delay=0, status=sent
(smsweb.com.br)
Mar 3 09:52:49 s2 postfix/qmgr[73641]: 59AB31870114: removed
Cristóvão Dalla Costa wrote:
Hello
In our postfix + dbmail installation some email is vanishing. When a
message specifies two or more recipients in the same domain only the
first gets the mail. The maillog shows that dbmail has accepted the
message yet the message is nowhere to be found. When we send to two
recipients on differente domains (but on the same server) the problem
doesn't exist. Can anyone shed some light on what might be happening?
Here's an excerpt from the log:
Mar 2 11:58:12 s2 postfix/qmgr[31118]: E49A7187001A:
from=<[EMAIL PROTECTED]>, size=589, nrcpt=2 (queue active)
Mar 2 11:58:12 s2 postfix/smtpd[31196]: disconnect from
unknown[200.250.222.67]
Mar 2 11:58:12 s2 dbmail/smtp[31219]: sort.c, sort_and_deliver:
message id=194992, size=574 is inserted
Mar 2 11:58:12 s2 postfix/pipe[31122]: E49A7187001A:
to=<[EMAIL PROTECTED]>, relay=dbmail-smtp, delay=1, status=sent
(smsweb.com.br)
Mar 2 11:58:12 s2 postfix/pipe[31122]: E49A7187001A:
to=<[EMAIL PROTECTED]>, relay=dbmail-smtp, delay=1, status=sent
(smsweb.com.br)
Mar 2 11:58:12 s2 postfix/qmgr[31118]: E49A7187001A: removed
Also, we're seeing that when an user is over quota dbmail replies
with an "inexistant user" code. Is this normal?
This is dbmail 2.0.3, postfix 2.1.5, postgresql 8.0.1 on freebsd 5.3
Thanks in advance
Cristóvão
_______________________________________________
Dbmail mailing list
[email protected]
https://mailman.fastxs.nl/mailman/listinfo/dbmail
