Thread: relay denied
View Single Post
  #20  
Old 26th July 2009, 18:56
madmerlin madmerlin is offline
Junior Member
 
Join Date: Jun 2009
Posts: 21
Thanks: 2
Thanked 0 Times in 0 Posts
Default

This is the info I get back when trying to send externally via user auth and TLS, and with the log level cranked right up...

Code:
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  mail
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  ipv4
Jul 26 09:35:01 dbl postfix/smtpd[3954]: name_mask: ipv4
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  <myserver>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  <mydomain>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  Postfix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  postfix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  postfix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  postdrop
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  <myserver>, localhost, localhost.localdomain
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /etc/mailname
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /usr/lib/postfix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /var/lib/postfix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /usr/sbin
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /var/spool/postfix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  pid
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  all
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  double-bounce
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  nobody
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  hash:/etc/aliases
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  20080902
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  2.5.5
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  hash
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  deferred, defer
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  +
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  mysql:/etc/postfix/mysql-virtual_relaydomains.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $relay_domains -> mysql:/etc/postfix/mysql-virtual_relaydomains.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  TZ MAIL_CONFIG LANG
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  MAIL_CONFIG MAIL_DEBUG MAIL_LOGTAG TZ XAUTHORITY DISPLAY LANG=C
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  subnet
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  +=
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  -=+
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  debug_peer_list,fast_flush_domains,mynetworks,permit_mx_backup_networks,qmqpd_authorized_clients,smtpd_access_maps
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  bounce
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  cleanup
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  defer
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  pickup
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  qmgr
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  rewrite
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  showq
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  error
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  flush
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  verify
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  trace
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  100s
Jul 26 09:35:01 dbl last message repeated 3 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  3600s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  3600s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  5s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  5s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1000s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1000s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  10s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  10s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1s
Jul 26 09:35:01 dbl last message repeated 3 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  500s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  500s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  18000s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  18000s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  127.0.0.0/8 [::1]/128
Jul 26 09:35:01 dbl postfix/smtpd[3954]: inet_addr_local: configured 2 IPv4 addresses
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  4
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $myhostname ESMTP $mail_name (Ubuntu) -> <myserver> ESMTP Postfix (Ubuntu)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  resource, software
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  check_client_access mysql:/etc/postfix/mysql-virtual_client.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  check_sender_access mysql:/etc/postfix/mysql-virtual_sender.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  permit_mynetworks, permit_sasl_authenticated, check_recipient_access mysql:/etc/postfix/mysql-virtual_recipient.cf, reject_unauth_destination
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl last message repeated 4 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  postmaster
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl last message repeated 2 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  proxy:mysql:/etc/postfix/mysql-virtual_forwardings.cf, mysql:/etc/postfix/mysql-virtual_email2email.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  hash:/etc/aliases
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand proxy:unix:passwd.byname $alias_maps -> proxy:unix:passwd.byname hash:/etc/aliases
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  noanonymous
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  smtpd
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl last message repeated 2 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  amavis:[127.0.0.1]:10024
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl last message repeated 2 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  CONNECT GET POST
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  <>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $double_bounce_sender -> double-bounce
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $authorized_verp_clients -> 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $myhostname -> <myserver>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  no_address_mappings
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand ${smtpd_client_connection_limit_exceptions:$mynetworks} -> 127.0.0.0/8 [::1]/128
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  permit_inet_interfaces
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl last message repeated 2 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $smtpd_sasl_security_options -> noanonymous
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /etc/postfix/smtpd.cert
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  /etc/postfix/smtpd.key
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $smtpd_tls_dcert_file -> 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  medium
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  SSLv3, TLSv1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  md5
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  may
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  cyrus
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  j {daemon_name} v
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  {tls_version} {cipher} {cipher_bits} {cert_subject} {cert_issuer}
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  i {auth_type} {auth_authen} {auth_author} {mail_addr}
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  i {rcpt_addr}
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  i
Jul 26 09:35:01 dbl last message repeated 2 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  2
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  tempfail
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $myhostname -> <myserver>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: expand $mail_name $mail_version -> Postfix 2.5.5
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  yes
Jul 26 09:35:01 dbl last message repeated 3 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  100s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  100s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  3s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  3s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  100s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  100s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1000s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  1000s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  3600s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  3600s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  30s
Jul 26 09:35:01 dbl last message repeated 3 times
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  300s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: process generation: 42 (42)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: mynetworks ~? debug_peer_list
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: mynetworks ~? fast_flush_domains
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: mynetworks ~? mynetworks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: relay_domains ~? debug_peer_list
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: relay_domains ~? fast_flush_domains
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: relay_domains ~? mynetworks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: relay_domains ~? permit_mx_backup_networks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: relay_domains ~? qmqpd_authorized_clients
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: relay_domains ~? smtpd_access_maps
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_list_match: relay_domains: no match
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: user = ispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: password = xxxxxx
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: dbname = dbispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: result_format = %s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_int: /etc/postfix/mysql-virtual_relaydomains.cf: expansion_limit = 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: query = <NULL>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: table = mail_transport
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: select_field = domain
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: where_field = domain
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: additional_conditions = and active = 'y' and server_id = 1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: domain = 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_relaydomains.cf: hosts = 127.0.0.1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: mysql:/etc/postfix/mysql-virtual_relaydomains.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: permit_mx_backup_networks ~? debug_peer_list
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: permit_mx_backup_networks ~? fast_flush_domains
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: permit_mx_backup_networks ~? mynetworks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: permit_mx_backup_networks ~? permit_mx_backup_networks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: connect to subsystem private/proxymap
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr request = open
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr table = unix:passwd.byname
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr flags = 16448
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: flags
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: flags
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 16464
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: (list terminator)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: (end)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_proxy_open: connect to map=unix:passwd.byname status=0 server_flags=fixed|lock|fold_fix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: proxy:unix:passwd.byname
Jul 26 09:35:01 dbl postfix/smtpd[3954]: Compiled against Berkeley DB: 4.7.25?
Jul 26 09:35:01 dbl postfix/smtpd[3954]: Run-time linked against Berkeley DB: 4.7.25?
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: hash:/etc/aliases
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr request = open
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr table = mysql:/etc/postfix/mysql-virtual_forwardings.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr flags = 16448
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: flags
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: flags
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 16464
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: (list terminator)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: (end)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_proxy_open: connect to map=mysql:/etc/postfix/mysql-virtual_forwardings.cf status=0 server_flags=fixed|lock|fold_fix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: proxy:mysql:/etc/postfix/mysql-virtual_forwardings.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: user = ispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: password = xxxxx
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: dbname = dbispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: result_format = %s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_int: /etc/postfix/mysql-virtual_email2email.cf: expansion_limit = 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: query = <NULL>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: table = mail_user
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: select_field = email
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: where_field = email
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: additional_conditions = and postfix = 'y' and server_id = 1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: domain = 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_email2email.cf: hosts = 127.0.0.1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: mysql:/etc/postfix/mysql-virtual_email2email.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr request = open
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr table = mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr flags = 16448
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: flags
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: flags
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 16464
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/proxymap socket: wanted attribute: (list terminator)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: (end)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_proxy_open: connect to map=mysql:/etc/postfix/mysql-virtual_mailboxes.cf status=0 server_flags=fixed|lock|fold_fix
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: smtpd_access_maps ~? debug_peer_list
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: smtpd_access_maps ~? fast_flush_domains
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: smtpd_access_maps ~? mynetworks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: smtpd_access_maps ~? permit_mx_backup_networks
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: smtpd_access_maps ~? qmqpd_authorized_clients
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: smtpd_access_maps ~? smtpd_access_maps
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: user = ispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: password = xxxx
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: dbname = dbispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: result_format = %s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_int: /etc/postfix/mysql-virtual_client.cf: expansion_limit = 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: query = <NULL>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: table = mail_access
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: select_field = access
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: where_field = source
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: additional_conditions = and type = 'client' and active = 'y'
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: domain = 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_client.cf: hosts = 127.0.0.1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: mysql:/etc/postfix/mysql-virtual_client.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: user = ispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: password = xxxxx
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: dbname = dbispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: result_format = %s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_int: /etc/postfix/mysql-virtual_sender.cf: expansion_limit = 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: query = <NULL>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: table = mail_access
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: select_field = access
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: where_field = source
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: additional_conditions = and type = 'sender' and active = 'y' and server_id = 1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: domain = 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_sender.cf: hosts = 127.0.0.1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: mysql:/etc/postfix/mysql-virtual_sender.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: user = ispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: password = xxxxx
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: dbname = dbispconfig
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: result_format = %s
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_int: /etc/postfix/mysql-virtual_recipient.cf: expansion_limit = 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: query = <NULL>
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: table = mail_access
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: select_field = access
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: where_field = source
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: additional_conditions = and type = 'recipient' and active = 'y' and server_id = 1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: domain = 
Jul 26 09:35:01 dbl postfix/smtpd[3954]: cfg_get_str: /etc/postfix/mysql-virtual_recipient.cf: hosts = 127.0.0.1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_open: mysql:/etc/postfix/mysql-virtual_recipient.cf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: xsasl_cyrus_server_init: SASL config file is smtpd.conf
Jul 26 09:35:01 dbl postfix/smtpd[3954]: initializing the server-side TLS engine
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  ALL:!EXPORT:!LOW:!MEDIUM:+RC4:@STRENGTH
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  ALL:!EXPORT:!LOW:+RC4:@STRENGTH
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  ALL:!EXPORT:+RC4:@STRENGTH
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  ALL:+RC4:@STRENGTH
Jul 26 09:35:01 dbl postfix/smtpd[3954]: dict_eval: const  eNULL:!aNULL
Jul 26 09:35:01 dbl postfix/smtpd[3954]: auto_clnt_create: transport=local endpoint=private/tlsmgr
Jul 26 09:35:01 dbl postfix/smtpd[3954]: auto_clnt_open: connected to private/tlsmgr
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr request = seed
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr size = 32
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/tlsmgr: wanted attribute: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/tlsmgr: wanted attribute: seed
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: seed
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: WEYFCOBi7k2aXPbjNu8QfZQQQsavlbOFY8UO+JLmfHw=
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/tlsmgr: wanted attribute: (list terminator)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: (end)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr request = policy
Jul 26 09:35:01 dbl postfix/smtpd[3954]: send attr cache_type = smtpd
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/tlsmgr: wanted attribute: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: status
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/tlsmgr: wanted attribute: cachable
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: cachable
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute value: 1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: private/tlsmgr: wanted attribute: (list terminator)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: input attribute name: (end)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: fast_flush_domains ~? debug_peer_list
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_string: fast_flush_domains ~? fast_flush_domains
Jul 26 09:35:01 dbl postfix/smtpd[3954]: name_mask: no_address_mappings
Jul 26 09:35:01 dbl postfix/smtpd[3954]: auto_clnt_create: transport=local endpoint=private/anvil
Jul 26 09:35:01 dbl postfix/smtpd[3954]: connection established
Jul 26 09:35:01 dbl postfix/smtpd[3954]: master_notify: status 0
Jul 26 09:35:01 dbl postfix/smtpd[3954]: name_mask: resource
Jul 26 09:35:01 dbl postfix/smtpd[3954]: name_mask: software
Jul 26 09:35:01 dbl postfix/smtpd[3954]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: name_mask: noanonymous
Jul 26 09:35:01 dbl postfix/smtpd[3954]: connect from localhost[127.0.0.1]
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_list_match: localhost: no match
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_list_match: 127.0.0.1: no match
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_list_match: localhost: no match
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_list_match: 127.0.0.1: no match
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_hostname: localhost ~? 127.0.0.0/8
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_hostaddr: 127.0.0.1 ~? 127.0.0.0/8
Jul 26 09:35:01 dbl postfix/smtpd[3954]: > localhost[127.0.0.1]: 220 <myserver> ESMTP Postfix (Ubuntu)
Jul 26 09:35:01 dbl postfix/smtpd[3954]: smtp_get: EOF
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_hostname: localhost ~? 127.0.0.0/8
Jul 26 09:35:01 dbl postfix/smtpd[3954]: match_hostaddr: 127.0.0.1 ~? 127.0.0.0/8
Jul 26 09:35:01 dbl postfix/smtpd[3954]: lost connection after CONNECT from localhost[127.0.0.1]
Jul 26 09:35:01 dbl postfix/smtpd[3954]: disconnect from localhost[127.0.0.1]
Jul 26 09:35:01 dbl postfix/smtpd[3954]: master_notify: status 1
Jul 26 09:35:01 dbl postfix/smtpd[3954]: connection closed
Jul 26 09:35:06 dbl postfix/smtpd[3954]: proxymap stream disconnect
Jul 26 09:35:06 dbl postfix/smtpd[3954]: auto_clnt_close: disconnect private/tlsmgr stream
Does that provide anymore insight? It looks like it's trying to look up the smtp relay options...

Last edited by madmerlin; 27th July 2009 at 16:20. Reason: edit
Reply With Quote