Re: Mail relaying on postfix using the Jamm environemnt

"buzz lightyear" <[email protected]>
Newsgroups gmane.mail.jamm.user
Message-ID <[email protected]> 
>
>are you able to put some maillogs and some (filtered) authlogs online ?

Hope this is of some use - I setting this up on an old solaris box, slow but 
it works - the only log I'm looking at at the moment is /var/log/syslog

I hope the log below isn't too long.

Here, I have turned off tls and attempted (with mozilla) to send an email to 
myself using AUTH - for some reason, even though sasl fails, it still 
manages to get through - I suspect because of the networks table. However, 
you are still able to see sasl falling over  with the  sasldb2 problem


..... uhm - I've just noticed that there is an authentication. Whoops - I 
had been focusing so much on the failure of the sasldb2 that I hadn't 
noticed this. Hell, you just can't get good staff anymore.

If this is the case, and the ldap authentication works, then it's just a 
matter of fixing a broken tls connection.

Still, I'll post the log just incase I'm mistaken and it still is not 
working.

Best regards (and again, many thanks for your input)
Buzz




---------------------------------


May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: mynetworks ~? debug_peer_list
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: mynetworks ~? fast_flush_domains
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: mynetworks ~? mynetworks
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: relay_domains ~? debug_peer_list
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: relay_domains ~? fast_flush_domains
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: relay_domains ~? mynetworks
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: relay_domains ~? permit_mx_mail_networks
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: relay_domains ~? qmqpd_authorized_clients
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: relay_domains ~? relay_domains
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: permit_mx_mail_networks ~? debug_peer_list
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: permit_mx_mail_networks ~? fast_flush_domains
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: permit_mx_mail_networks ~? mynetworks
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: permit_mx_mail_networks ~? permit_mx_mail_networks
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_open: Using LDAP source accountsmap
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: server_host = master.example.com
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: server_port = 389
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: version = 2
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_open: accountsmap server_host URL is ldap://master.example.com:389
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: scope = sub
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: search_base = o=hosting,dc=mail,dc=example,dc=com
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: domain =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: timeout = 10
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: query_filter = 
(&(objectClass=JammMailAccount)(mail=%s)(accountActive=TRUE)(delete=FALSE))
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: result_filter = %s
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: result_attribute = mail
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: special_result_attribute =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accountsmap: bind = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: bind_dn =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: bind_pw =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accountsmap: cache = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: cache_expiry = -1
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: cache_size = -1
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: recursion_limit = 1000
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: expansion_limit = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: size_limit = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: dereference = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accountsmap: chase_referrals = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accountsmap: start_tls = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accountsmap: tls_require_cert = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: tls_ca_cert_file =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: tls_ca_cert_dir =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: tls_cert =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: tls_key =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: tls_random_file =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accountsmap: tls_cipher_suite =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accountsmap: debuglevel = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_open: ldap:accountsmap
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_open: Using LDAP source aliases
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: server_host = master.example.com
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: server_port = 389
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: version = 2
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_open: aliases server_host URL is ldap://master.example.com:389
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: scope = sub
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: search_base = o=hosting,dc=mail,dc=example,dc=com
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: domain =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: timeout = 10
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: query_filter = 
(&(objectClass=JammMailAlias)(mail=%s)(accountActive=TRUE))
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: result_filter = %s
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: result_attribute = maildrop
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: special_result_attribute =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: aliases: bind = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: bind_dn =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: bind_pw =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: aliases: cache = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: cache_expiry = -1
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: cache_size = -1
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: recursion_limit = 1000
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: expansion_limit = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: size_limit = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: dereference = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: aliases: chase_referrals = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: aliases: start_tls = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: aliases: tls_require_cert = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: tls_ca_cert_file =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: tls_ca_cert_dir =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: tls_cert =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: tls_key =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: tls_random_file =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: aliases: tls_cipher_suite =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: aliases: debuglevel = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_open: ldap:aliases
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_open: Using LDAP source accounts
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: server_host = master.example.com
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: server_port = 389
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: version = 2
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_open: accounts server_host URL is ldap://master.example.com:389
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: scope = sub
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: search_base = o=hosting,dc=mail,dc=example,dc=com
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: domain =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: timeout = 10
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: query_filter = 
(&(objectClass=JammMailAccount)(mail=%s)(accountActive=TRUE)(delete=FALSE))
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: result_filter = %s
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: result_attribute = mailbox
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: special_result_attribute =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accounts: bind = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: bind_dn =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: bind_pw =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accounts: cache = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: cache_expiry = -1
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: cache_size = -1
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: recursion_limit = 1000
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: expansion_limit = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: size_limit = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: dereference = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accounts: chase_referrals = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accounts: start_tls = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_bool: accounts: tls_require_cert = off
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: tls_ca_cert_file =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: tls_ca_cert_dir =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: tls_cert =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: tls_key =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: tls_random_file =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_str: accounts: tls_cipher_suite =
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
cfg_get_int: accounts: debuglevel = 0
May 24 17:19:00 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_open: ldap:accounts
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? debug_peer_list
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? fast_flush_domains
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? mynetworks
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? permit_mx_mail_networks
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? qmqpd_authorized_clients
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? relay_domains
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: smtpd_access_maps ~? smtpd_access_maps
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_sasl_initialize: SASL config file is smtpd.conf
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: fast_flush_domains ~? debug_peer_list
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: fast_flush_domains ~? fast_flush_domains
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
attr_clnt_create: transport=local endpoint=private/anvil
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_create: 7e2a8 18000
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_stop: 7e2a8
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_start: 7e2a8
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
connection established
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
master_notify: status 0
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
name_mask: resource
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
name_mask: software
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
name_mask: noanonymous
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
connect from unknown[192.168.0.4]
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: unknown: no match
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: 192.168.0.4: no match
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: unknown: no match
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: 192.168.0.4: no match
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostname: unknown ~? 192.168.0.0/28
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostaddr: 192.168.0.4 ~? 192.168.0.0/28
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 220 mail.example.com ESMTP Postfix
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: EHLO [127.0.0.1]
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-mail.example.com
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-PIPELINING
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-SIZE 10240000
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-VRFY
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-ETRN
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-AUTH LOGIN PLAIN CRAM-MD5
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250-AUTH=LOGIN PLAIN CRAM-MD5
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: unknown: no match
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: 192.168.0.4: no match
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 8BITMIME
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: AUTH CRAM-MD5
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_sasl_authenticate: sasl_method CRAM-MD5
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_sasl_authenticate: uncoded challenge: 
<[email protected]>
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 334 
PDEwNzY2NDMzODguMTE2NzM3ODFAYmFja3VwLmV4YW1wbGUuY29tPg==
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: 
bmJldHRlcmlkZ2VAc3ludGFjdGljcy5jb20gNTRmNzg4MzJmYzAxNGUwOTdiMzI0ZTc3Nzk1YzQ0MGM=
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_sasl_authenticate: decoded response: [email protected] 
54f78832fc014e097b324e77795c440c
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 947731 
mail.warning] warning: SASL authentication problem: unable to open Berkeley 
db /etc/sasldb2: No such file or directory
May 24 17:19:01 mail.example.com last message repeated 1 time
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 947731 
mail.warning] warning: SASL authentication failure: no secret in database
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 947731 
mail.warning] warning: unknown[192.168.0.4]: SASL CRAM-MD5 authentication 
failed
May 24 17:19:01 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 535 Error: authentication failed
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: AUTH PLAIN 
AG5iZXR0ZXJpZGdlQHN5bnRhY3RpY3MuY29tAGF1YmVyZ2luZQ==
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_sasl_authenticate: sasl_method PLAIN, init_response 
AG5iZXR0ZXJpZGdlQHN5bnRhY3RpY3MuY29tAGF1YmVyZ2luZQ==
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_sasl_authenticate: decoded initial response
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 947731 
mail.warning] warning: SASL authentication problem: unable to open Berkeley 
db /etc/sasldb2: No such file or directory
May 24 17:19:02 mail.example.com last message repeated 1 time
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 235 Authentication successful
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: MAIL FROM:<[email protected]> SIZE=448
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: input: <[email protected]>
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_check_addr: [email protected]
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
connect to subsystem private/rewrite
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr request = rewrite
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr rule = canonicalize
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr address = [email protected]
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: address
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: address
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: [email protected]
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: (list terminator)
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: (end)
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
rewrite_clnt: canonicalize: [email protected] -> 
[email protected]
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr request = resolve
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr address = [email protected]
May 24 17:19:02 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: transport
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: transport
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: virtual
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: nexthop
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: nexthop
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: example.com
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: recipient
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: recipient
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: flags
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: flags
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: 1024
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: (list terminator)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: (end)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
resolve_clnt: `[email protected]' -> transp=`virtual' 
host=`example.com' rcpt=`[email protected]' flags= class=virtual
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: install entry key [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: result: [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
fsspace: .: block size 1024, blocks free 649631
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_check_size: blocks 1024 avail 649631 min_free 0 msg_size_limit 
10240000
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 Ok
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: RCPT TO:<[email protected]>
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: input: <[email protected]>
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_check_addr: [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: result: [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr request = rewrite
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr rule = canonicalize
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr address = postmaster
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: address
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: address
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
private/rewrite socket: wanted attribute: (list terminator)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: (end)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
rewrite_clnt: canonicalize: postmaster -> [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
 >>> CHECKING RECIPIENT MAPS <<<
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: [email protected]: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: @example.com: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: [email protected]: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: @example.com: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: In dict_ldap_lookup
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: No existing connection for LDAP source accountsmap, 
reopening
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_connect: Connecting to server ldap://master.example.com:389
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_connect: Actual Protocol version used is 2.
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_connect: Cached connection handle for LDAP source accountsmap
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Searching with filter 
(&(objectClass=JammMailAccount)([email protected])(accountActive=TRUE)(delete=FALSE))
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Search found 1 match(es)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: search returned 1 value(s) for requested result 
attribute mail
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Leaving dict_ldap_get_values
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Search returned [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: virtual_alias_maps: ldap:accountsmap(0,100): 
[email protected] = [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
 >>> START Recipient address RESTRICTIONS <<<
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
generic_checks: name=permit_mynetworks
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
permit_mynetworks: unknown 192.168.0.4
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostname: unknown ~? 192.168.0.0/28
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostaddr: 192.168.0.4 ~? 192.168.0.0/28
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
generic_checks: name=permit_mynetworks status=1
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
 >>> CHECKING RECIPIENT MAPS <<<
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: [email protected]: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: @example.com: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: [email protected]: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: @example.com: not found
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: In dict_ldap_lookup
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Using existing connection for LDAP source accountsmap
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Searching with filter 
(&(objectClass=JammMailAccount)([email protected])(accountActive=TRUE)(delete=FALSE))
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Search found 1 match(es)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: search returned 1 value(s) for requested result 
attribute mail
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Leaving dict_ldap_get_values
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Search returned [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: virtual_alias_maps: ldap:accountsmap(0,100): 
[email protected] = [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> [email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
connect to subsystem public/cleanup
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: queue_id
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: queue_id
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: 5D043126C4
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: (list terminator)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: (end)
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr flags = 50
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
5D043126C4: client=unknown[192.168.0.4], sasl_method=PLAIN, 
[email protected]
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 Ok
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: RSET
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 Ok
May 24 17:19:03 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:03 mail.example.com imapd-ssl: [ID 702911 mail.info] 
Connection, ip=[::ffff:192.168.0.4]
May 24 17:19:03 mail.example.com imapd-ssl: [ID 702911 mail.info] LOGIN, 
[email protected], ip=[::ffff:192.168.0.4], protocol=IMAP
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: MAIL FROM:<[email protected]> SIZE=448
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: input: <[email protected]>
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_check_addr: [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: result: [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
fsspace: .: block size 1024, blocks free 649630
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_check_size: blocks 1024 avail 649630 min_free 0 msg_size_limit 
10240000
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 Ok
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: RCPT TO:<[email protected]>
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: input: <[email protected]>
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
smtpd_check_addr: [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
extract_addr: result: [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
 >>> CHECKING RECIPIENT MAPS <<<
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: [email protected]: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: @example.com: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: [email protected]: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: @example.com: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: In dict_ldap_lookup
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Using existing connection for LDAP source accountsmap
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Searching with filter 
(&(objectClass=JammMailAccount)([email protected])(accountActive=TRUE)(delete=FALSE))
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Search found 1 match(es)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: search returned 1 value(s) for requested result 
attribute mail
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Leaving dict_ldap_get_values
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Search returned [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: virtual_alias_maps: ldap:accountsmap(0,100): 
[email protected] = [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
 >>> START Recipient address RESTRICTIONS <<<
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
generic_checks: name=permit_mynetworks
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
permit_mynetworks: unknown 192.168.0.4
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostname: unknown ~? 192.168.0.0/28
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostaddr: 192.168.0.4 ~? 192.168.0.0/28
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
generic_checks: name=permit_mynetworks status=1
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
 >>> CHECKING RECIPIENT MAPS <<<
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
ctable_locate: leave existing entry key [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: [email protected]: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: recipient_canonical_maps: @example.com: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: [email protected]: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_string: example.com ~? example.com
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_list_match: example.com: no match
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: canonical_maps: @example.com: not found
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> (not found)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: In dict_ldap_lookup
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Using existing connection for LDAP source accountsmap
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Searching with filter 
(&(objectClass=JammMailAccount)([email protected])(accountActive=TRUE)(delete=FALSE))
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Search found 1 match(es)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: search returned 1 value(s) for requested result 
attribute mail
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_get_values[1]: Leaving dict_ldap_get_values
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
dict_ldap_lookup: Search returned [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
maps_find: virtual_alias_maps: ldap:accountsmap(0,100): 
[email protected] = [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
mail_addr_find: [email protected] -> [email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
connect to subsystem public/cleanup
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: queue_id
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: queue_id
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: AE91B126C4
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: (list terminator)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: (end)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
send attr flags = 50
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
AE91B126C4: client=unknown[192.168.0.4], sasl_method=PLAIN, 
[email protected]
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 Ok
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: DATA
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 354 End data with <CR><LF>.<CR><LF>
May 24 17:19:05 mail.example.com postfix/cleanup[612]: [ID 197553 mail.info] 
AE91B126C4: message-id=<[email protected]>
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: status
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: status
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: 0
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: reason
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: reason
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute value: (end)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
public/cleanup socket: wanted attribute: (list terminator)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
input attribute name: (end)
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 250 Ok: queued as AE91B126C4
May 24 17:19:05 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_pat: 7e2a8
May 24 17:19:05 mail.example.com postfix/qmgr[607]: [ID 197553 mail.info] 
AE91B126C4: from=<[email protected]>, size=635, nrcpt=1 (queue 
active)
May 24 17:19:06 mail.example.com postfix/virtual[616]: [ID 197553 mail.info] 
AE91B126C4: to=<[email protected]>, relay=virtual, delay=1, 
status=sent (delivered to maildir)
May 24 17:19:06 mail.example.com postfix/qmgr[607]: [ID 197553 mail.info] 
AE91B126C4: removed
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] < 
unknown[192.168.0.4]: QUIT
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] > 
unknown[192.168.0.4]: 221 Bye
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostname: unknown ~? 192.168.0.0/28
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
match_hostaddr: 192.168.0.4 ~? 192.168.0.0/28
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
disconnect from unknown[192.168.0.4]
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
master_notify: status 1
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
connection closed
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_stop: 7e2a8
May 24 17:19:07 mail.example.com postfix/smtpd[610]: [ID 197553 mail.info] 
watchdog_start: 7e2a8

_________________________________________________________________
Stay in touch with absent friends - get MSN Messenger 
http://www.msn.co.uk/messenger



-------------------------------------------------------
This SF.Net email is sponsored by: Oracle 10g
Get certified on the hottest thing ever to hit the market... Oracle 10g. 
Take an Oracle 10g class now, and we'll give you the exam FREE.
http://ads.osdn.com/?ad_id=3149&alloc_id=8166&op=click
lmpx.com only provides a reader for public news (NNTP) servers. It is not affiliated with the servers or forums shown here and is not responsible for the content of articles, which is written by their respective authors.