I'm using Google's Postini replacement as a spam filter before mail gets to my smtp server. I currently have a problem where most emails that get spam trapped by Google disappear when I attempt to have them delivered. Here are my smtpd logs from a case where I tried to deliver 2 spam emails; the first one failed and the second one worked. To me the logs are painful mess and I can't make heads nor tails of them. Can anyone figure out why the first one failed?

(I had to replace the at symbol with -at- in the logs below or this forum wouldn't let me post)
Code:
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: name_mask: ipv4
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: name_mask: host
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: inet_addr_local: configured 2 IPv4 addresses
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: been_here: 127.0.0.1/32: 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: been_here: 192.168.0.106/32: 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: mynetworks: 127.0.0.1/32 192.168.0.106/32
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: process generation: 5775 (5775)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: mynetworks ~? debug_peer_list
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: mynetworks ~? fast_flush_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: mynetworks ~? mynetworks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: relay_domains ~? debug_peer_list
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: relay_domains ~? fast_flush_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: relay_domains ~? mynetworks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: relay_domains ~? permit_mx_backup_networks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: relay_domains ~? qmqpd_authorized_clients
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: relay_domains ~? relay_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: permit_mx_backup_networks ~? debug_peer_list
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: permit_mx_backup_networks ~? fast_flush_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: permit_mx_backup_networks ~? mynetworks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: permit_mx_backup_networks ~? permit_mx_backup_networks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: user = mailsql
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: password = **********
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: dbname = mailsql
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: result_format = %s
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_int: /etc/postfix/mysql-aliases.cf: expansion_limit = 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: query = <NULL>
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: table = alias
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: select_field = destination
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: where_field = alias
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: additional_conditions =
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: domain =
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-aliases.cf: hosts = unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_open: mysql:/etc/postfix/mysql-aliases.cf
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: user = mailsql
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: password = **********
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: dbname = mailsql
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: result_format = %s
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_int: /etc/postfix/mysql-virtual-maps.cf: expansion_limit = 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: query = <NULL>
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: table = users
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: select_field = maildir
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: where_field = email
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: additional_conditions = and postfix = 'y'
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: domain =
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual-maps.cf: hosts = unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_open: mysql:/etc/postfix/mysql-virtual-maps.cf
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_open: unix:passwd.byname
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: user = mailsql
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: password = **********
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: dbname = mailsql
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: result_format = %s
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_int: /etc/postfix/mysql-virtual.cf: expansion_limit = 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: query = <NULL>
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: table = virtual
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: select_field = destination
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: where_field = email
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: additional_conditions =
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: domain =
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: cfg_get_str: /etc/postfix/mysql-virtual.cf: hosts = unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_open: mysql:/etc/postfix/mysql-virtual.cf
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? debug_peer_list
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? fast_flush_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? mynetworks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? permit_mx_backup_networks
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? qmqpd_authorized_clients
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? relay_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: smtpd_access_maps ~? smtpd_access_maps
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 64.18.0.0/20 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 64.233.160.0/19 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 66.102.0.0/20 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 66.249.80.0/20 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 72.14.192.0/18 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 74.125.0.0/16 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 173.194.0.0/16 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 207.126.144.0/20 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 209.85.128.0/17 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 216.239.32.0/19 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 74.125.45.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 209.85.137.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 209.85.139.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 64.233.161.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 72.14.235.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 74.125.95.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_cidr_open: add 74.125.77.9/32 OK
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: dict_open: cidr:/etc/postfix/allowed_clients.cidr
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: unknown_helo_hostname_tempfail_action = defer_if_permit
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: unknown_address_tempfail_action = defer_if_permit
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: unverified_recipient_tempfail_action = defer_if_permit
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: unverified_sender_tempfail_action = defer_if_permit
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: xsasl_cyrus_server_init: SASL config file is smtpd.conf
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: sql auxprop plugin using mysql engine
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: auto_clnt_create: transport=local endpoint=private/tlsmgr
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: auto_clnt_open: connected to private/tlsmgr
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: send attr request = seed
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: send attr size = 32
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: status
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: seed
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: seed
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: ATi45v1rKW/ez41F/dcZpDn3VVepb34uzqD4Tx3krag=
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: (list terminator)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: name_mask: CVE-2010-4180
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: send attr request = policy
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: send attr cache_type = smtpd
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: status
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: cachable
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: cachable
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: 1
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: (list terminator)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: fast_flush_domains ~? debug_peer_list
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_string: fast_flush_domains ~? fast_flush_domains
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: auto_clnt_create: transport=local endpoint=private/anvil
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: connection established
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: master_notify: status 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: name_mask: resource
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: name_mask: software
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: connect from mail-qa0-f77.google.com[209.85.216.77]
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_hostname: mail-qa0-f77.google.com ~? 127.0.0.1/32
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.216.77 ~? 127.0.0.1/32
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_hostname: mail-qa0-f77.google.com ~? 192.168.0.106/32
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.216.77 ~? 192.168.0.106/32
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: auto_clnt_open: connected to private/anvil
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: send attr request = connect
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: send attr ident = smtp:209.85.216.77
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: status
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: count
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: count
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: 1
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: rate
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: rate
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute value: 1
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: (list terminator)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:07 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 220 64x2.sendu.me.uk ESMTP Postfix
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: EHLO mail-qa0-f77.google.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-64x2.sendu.me.uk
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-PIPELINING
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-SIZE 10240000
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-VRFY
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-ETRN
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-STARTTLS
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-ENHANCEDSTATUSCODES
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-8BITMIME
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250 DSN
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: STARTTLS
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 220 2.0.0 Ready to start TLS
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr request = seed
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr size = 32
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: status
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: seed
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: seed
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: zFyzR3fX+T1/10nr9qYXnqPcVFfrnqnoV+Ydl++ZiBs=
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: (list terminator)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: name_mask: noanonymous
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: EHLO mail-qa0-f77.google.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-64x2.sendu.me.uk
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-PIPELINING
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-SIZE 10240000
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-VRFY
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-ETRN
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-AUTH LOGIN PLAIN
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-AUTH=LOGIN PLAIN
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-ENHANCEDSTATUSCODES
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250-8BITMIME
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250 DSN
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: MAIL FROM:<support-at-serverhub.com>
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: extract_addr: input: <support-at-serverhub.com>
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: smtpd_check_addr: addr=support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: connect to subsystem private/rewrite
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr request = rewrite
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr rule = local
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr address = support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: address
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: address
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: rewrite_clnt: local: support-at-serverhub.com -> support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr request = resolve
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr sender =
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: send attr address = support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: transport
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: transport
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: smtp
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: nexthop
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: nexthop
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: outbound.mailhop.org
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: recipient
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: recipient
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute value: 4096
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: resolve_clnt: `' -> `support-at-serverhub.com' -> transp=`smtp' host=`outbound.mailhop.org' rcpt=`support-at-serverhub.com' flags= class=default
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: ctable_locate: install entry key support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: extract_addr: in: <support-at-serverhub.com>, result: support-at-serverhub.com
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: fsspace: .: block size 4096, blocks free 3643693
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: smtpd_check_queue: blocks 4096 avail 3643693 min_free 0 msg_size_limit 10240000
Oct 12 22:49:08 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250 2.1.0 Ok
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: RCPT TO:<sendu-at-sendu.me.uk>
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: extract_addr: input: <sendu-at-sendu.me.uk>
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: smtpd_check_addr: addr=sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr request = rewrite
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr rule = local
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr address = sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: address
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: address
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: rewrite_clnt: local: sendu-at-sendu.me.uk -> sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr request = resolve
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr sender =
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr address = sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: transport
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: transport
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: local
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: nexthop
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: nexthop
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: recipient
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: recipient
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 256
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: resolve_clnt: `' -> `sendu-at-sendu.me.uk' -> transp=`local' host=`sendu.me.uk' rcpt=`sendu-at-sendu.me.uk' flags= class=local
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: ctable_locate: install entry key sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: extract_addr: in: <sendu-at-sendu.me.uk>, result: sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr request = rewrite
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr rule = local
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr address = double-bounce
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: address
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: address
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: double-bounce-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: rewrite_clnt: local: double-bounce -> double-bounce-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: >>> START Recipient address RESTRICTIONS <<<
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=permit_sasl_authenticated
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=permit_sasl_authenticated status=0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=permit_mynetworks
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: permit_mynetworks: mail-qa0-f77.google.com 209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostname: mail-qa0-f77.google.com ~? 127.0.0.1/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.216.77 ~? 127.0.0.1/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostname: mail-qa0-f77.google.com ~? 192.168.0.106/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.216.77 ~? 192.168.0.106/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=permit_mynetworks status=0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=reject_unauth_destination
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: reject_unauth_destination: sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: permit_auth_destination: sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: ctable_locate: leave existing entry key sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=reject_unauth_destination status=0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=check_client_access
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: check_namadr_access: name mail-qa0-f77.google.com addr 209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: check_domain_access: mail-qa0-f77.google.com
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_cidr_lookup: /etc/postfix/allowed_clients.cidr: mail-qa0-f77.google.com
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: check_addr_access: 209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_cidr_lookup: /etc/postfix/allowed_clients.cidr: 209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: check_table_result: cidr:/etc/postfix/allowed_clients.cidr OK 209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: generic_checks: name=check_client_access status=1
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: >>> CHECKING RECIPIENT MAPS <<<
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: ctable_locate: leave existing entry key sendu-at-sendu.me.uk
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: recipient_canonical_maps: sendu-at-sendu.me.uk: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: recipient_canonical_maps: sendu: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: recipient_canonical_maps: -at-sendu.me.uk: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> (not found)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: canonical_maps: sendu-at-sendu.me.uk: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: canonical_maps: sendu: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: canonical_maps: -at-sendu.me.uk: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> (not found)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_get_active: attempting to connect to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: virtual_alias_maps: sendu-at-sendu.me.uk: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: virtual_alias_maps: sendu: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: virtual_alias_maps: -at-sendu.me.uk: not found
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> (not found)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_get_active: attempting to connect to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_get_active: attempting to connect to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 1 rows
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: maps_find: local_recipient_maps: mysql:/etc/postfix/mysql-virtual-maps.cf(0,lock|fold_fix): sendu-at-sendu.me.uk = /home/sendu/.maildir/
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> /home/sendu/.maildir/
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: smtpd_check_rewrite: trying: permit_inet_interfaces
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: permit_inet_interfaces: mail-qa0-f77.google.com 209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: before input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping enable_milters
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: after input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: connect to subsystem public/cleanup
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: queue_id
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: queue_id
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 46C6C3381FE
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: (list terminator)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr flags = 178
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: 46C6C3381FE: client=mail-qa0-f77.google.com[209.85.216.77]
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250 2.1.5 Ok
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: DATA
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 354 End data with <CR><LF>.<CR><LF>
Oct 12 22:49:09 64x2 postfix/cleanup[15745]: 46C6C3381FE: message-id=<d0dc4b67c6d8ae1fa6407212281e4924-at-my.serverhub.com>
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: status
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: reason
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: reason
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: (list terminator)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 250 2.0.0 Ok: queued as 46C6C3381FE
Oct 12 22:49:09 64x2 postfix/qmgr[23817]: 46C6C3381FE: from=<support-at-serverhub.com>, size=7505, nrcpt=1 (queue active)
Oct 12 22:49:09 64x2 postfix/local[15746]: 46C6C3381FE: to=<sendu-at-sendu.me.uk>, relay=local, delay=0.91, delays=0.88/0.01/0/0.02, dsn=5.4.6, status=bounced (mail forwarding loop for sendu-at-sendu.me.uk)
Oct 12 22:49:09 64x2 postfix/cleanup[15745]: AFD0A33821A: message-id=<20131012214909.AFD0A33821A-at-64x2.sendu.me.uk>
Oct 12 22:49:09 64x2 postfix/bounce[15747]: 46C6C3381FE: sender non-delivery notification: AFD0A33821A
Oct 12 22:49:09 64x2 postfix/qmgr[23817]: AFD0A33821A: from=<>, size=9320, nrcpt=1 (queue active)
Oct 12 22:49:09 64x2 postfix/qmgr[23817]: 46C6C3381FE: removed
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: < mail-qa0-f77.google.com[209.85.216.77]: QUIT
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: > mail-qa0-f77.google.com[209.85.216.77]: 221 2.0.0 Bye
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostname: mail-qa0-f77.google.com ~? 127.0.0.1/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.216.77 ~? 127.0.0.1/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostname: mail-qa0-f77.google.com ~? 192.168.0.106/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.216.77 ~? 192.168.0.106/32
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_list_match: mail-qa0-f77.google.com: no match
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: match_list_match: 209.85.216.77: no match
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr request = disconnect
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: send attr ident = smtp:209.85.216.77
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: status
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: (list terminator)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: disconnect from mail-qa0-f77.google.com[209.85.216.77]
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: master_notify: status 1
Oct 12 22:49:09 64x2 postfix/smtpd[15742]: connection closed
Oct 12 22:49:11 64x2 postfix/smtp[15748]: AFD0A33821A: to=<support-at-serverhub.com>, relay=outbound.mailhop.org[204.13.248.71]:25, delay=2.3, delays=0/0.03/1.6/0.6, dsn=2.0.0, status=sent (250 OK id=1VV73f-0004sF-L5)
Oct 12 22:49:11 64x2 postfix/qmgr[23817]: AFD0A33821A: removed
Oct 12 22:49:13 64x2 postfix/smtpd[15742]: auto_clnt_close: disconnect private/tlsmgr stream
Oct 12 22:49:14 64x2 postfix/smtpd[15742]: rewrite stream disconnect
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: connection established
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: master_notify: status 0
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: name_mask: resource
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: name_mask: software
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: connect from mail-ie0-f208.google.com[209.85.223.208]
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_hostname: mail-ie0-f208.google.com ~? 127.0.0.1/32
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.223.208 ~? 127.0.0.1/32
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_hostname: mail-ie0-f208.google.com ~? 192.168.0.106/32
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.223.208 ~? 192.168.0.106/32
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr request = connect
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr ident = smtp:209.85.223.208
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: status
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: count
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: count
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 1
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: rate
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: rate
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 1
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: (list terminator)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 220 64x2.sendu.me.uk ESMTP Postfix
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: EHLO mail-ie0-f208.google.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-64x2.sendu.me.uk
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-PIPELINING
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-SIZE 10240000
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-VRFY
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-ETRN
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-STARTTLS
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-ENHANCEDSTATUSCODES
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-8BITMIME
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250 DSN
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: STARTTLS
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 220 2.0.0 Ready to start TLS
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: auto_clnt_open: connected to private/tlsmgr
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr request = seed
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr size = 32
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: status
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: seed
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: seed
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 762XaK72bgc3lZDoCAUoklae6FluonSTMIfRbsvPX3c=
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/tlsmgr: wanted attribute: (list terminator)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: name_mask: noanonymous
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: EHLO mail-ie0-f208.google.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-64x2.sendu.me.uk
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-PIPELINING
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-SIZE 10240000
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-VRFY
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-ETRN
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-AUTH LOGIN PLAIN
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-AUTH=LOGIN PLAIN
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-ENHANCEDSTATUSCODES
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250-8BITMIME
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250 DSN
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: MAIL FROM:<FWD_TfOMIRQX-at-opayq.com>
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: extract_addr: input: <FWD_TfOMIRQX-at-opayq.com>
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: smtpd_check_addr: addr=FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: connect to subsystem private/rewrite
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr request = rewrite
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr rule = local
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr address = FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: address
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: address
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: rewrite_clnt: local: FWD_TfOMIRQX-at-opayq.com -> FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr request = resolve
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr sender =
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: send attr address = FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: transport
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: transport
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: smtp
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: nexthop
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: nexthop
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: outbound.mailhop.org
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: recipient
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: recipient
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: flags
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: flags
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute value: 4096
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: private/rewrite socket: wanted attribute: (list terminator)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: resolve_clnt: `' -> `FWD_TfOMIRQX-at-opayq.com' -> transp=`smtp' host=`outbound.mailhop.org' rcpt=`FWD_TfOMIRQX-at-opayq.com' flags= class=default
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: ctable_locate: install entry key FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: extract_addr: in: <FWD_TfOMIRQX-at-opayq.com>, result: FWD_TfOMIRQX-at-opayq.com
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: fsspace: .: block size 4096, blocks free 3643687
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: smtpd_check_queue: blocks 4096 avail 3643687 min_free 0 msg_size_limit 10240000
Oct 12 22:49:23 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250 2.1.0 Ok
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: RCPT TO:<sendu-at-sendu.me.uk>
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: extract_addr: input: <sendu-at-sendu.me.uk>
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: smtpd_check_addr: addr=sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: ctable_locate: move existing entry key sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: extract_addr: in: <sendu-at-sendu.me.uk>, result: sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: >>> START Recipient address RESTRICTIONS <<<
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=permit_sasl_authenticated
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=permit_sasl_authenticated status=0
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=permit_mynetworks
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: permit_mynetworks: mail-ie0-f208.google.com 209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostname: mail-ie0-f208.google.com ~? 127.0.0.1/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.223.208 ~? 127.0.0.1/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostname: mail-ie0-f208.google.com ~? 192.168.0.106/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.223.208 ~? 192.168.0.106/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=permit_mynetworks status=0
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=reject_unauth_destination
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: reject_unauth_destination: sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: permit_auth_destination: sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: ctable_locate: leave existing entry key sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=reject_unauth_destination status=0
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=check_client_access
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: check_namadr_access: name mail-ie0-f208.google.com addr 209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: check_domain_access: mail-ie0-f208.google.com
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_cidr_lookup: /etc/postfix/allowed_clients.cidr: mail-ie0-f208.google.com
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: check_addr_access: 209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_cidr_lookup: /etc/postfix/allowed_clients.cidr: 209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: check_table_result: cidr:/etc/postfix/allowed_clients.cidr OK 209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: generic_checks: name=check_client_access status=1
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: >>> CHECKING RECIPIENT MAPS <<<
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: ctable_locate: leave existing entry key sendu-at-sendu.me.uk
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: recipient_canonical_maps: sendu-at-sendu.me.uk: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: recipient_canonical_maps: sendu: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: recipient_canonical_maps: -at-sendu.me.uk: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> (not found)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: canonical_maps: sendu-at-sendu.me.uk: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: canonical_maps: sendu: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: canonical_maps: -at-sendu.me.uk: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> (not found)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: virtual_alias_maps: sendu-at-sendu.me.uk: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: virtual_alias_maps: sendu: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: virtual_alias_maps: -at-sendu.me.uk: not found
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> (not found)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 0 rows
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_get_active: found active connection to host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql: successful query from host unix:/var/run/mysqld/mysqld.sock
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: dict_mysql_lookup: retrieved 1 rows
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: maps_find: local_recipient_maps: mysql:/etc/postfix/mysql-virtual-maps.cf(0,lock|fold_fix): sendu-at-sendu.me.uk = /home/sendu/.maildir/
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: mail_addr_find: sendu-at-sendu.me.uk -> /home/sendu/.maildir/
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: smtpd_check_rewrite: trying: permit_inet_interfaces
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: permit_inet_interfaces: mail-ie0-f208.google.com 209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: before input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping enable_milters
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: after input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: connect to subsystem public/cleanup
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: queue_id
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: queue_id
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute value: 1A2C43381FE
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: (list terminator)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: send attr flags = 178
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: 1A2C43381FE: client=mail-ie0-f208.google.com[209.85.223.208]
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250 2.1.5 Ok
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: DATA
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 354 End data with <CR><LF>.<CR><LF>
Oct 12 22:49:24 64x2 postfix/cleanup[15745]: 1A2C43381FE: message-id=<725921480.7881521381574873880.JavaMail.root-at-ip-10-204-86-63.ec2.internal>
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: status
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: reason
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: reason
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute value: (end)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: public/cleanup socket: wanted attribute: (list terminator)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 250 2.0.0 Ok: queued as 1A2C43381FE
Oct 12 22:49:24 64x2 postfix/qmgr[23817]: 1A2C43381FE: from=<FWD_TfOMIRQX-at-opayq.com>, size=6956, nrcpt=1 (queue active)
Oct 12 22:49:24 64x2 postfix/local[15746]: 1A2C43381FE: to=<sendu-at-sendu.me.uk>, relay=local, delay=0.38, delays=0.37/0/0/0.01, dsn=2.0.0, status=sent (delivered to maildir)
Oct 12 22:49:24 64x2 postfix/qmgr[23817]: 1A2C43381FE: removed
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: < mail-ie0-f208.google.com[209.85.223.208]: QUIT
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: > mail-ie0-f208.google.com[209.85.223.208]: 221 2.0.0 Bye
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostname: mail-ie0-f208.google.com ~? 127.0.0.1/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.223.208 ~? 127.0.0.1/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostname: mail-ie0-f208.google.com ~? 192.168.0.106/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_hostaddr: 209.85.223.208 ~? 192.168.0.106/32
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_list_match: mail-ie0-f208.google.com: no match
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: match_list_match: 209.85.223.208: no match
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: send attr request = disconnect
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: send attr ident = smtp:209.85.223.208
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: status
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: status
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute value: 0
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: private/anvil: wanted attribute: (list terminator)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: input attribute name: (end)
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: disconnect from mail-ie0-f208.google.com[209.85.223.208]
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: master_notify: status 1
Oct 12 22:49:24 64x2 postfix/smtpd[15742]: connection closed
At first I thought it was an issue with me not having all of Google's IPs in my allowed_clients.cidr file, but both Google IPs seemed to be accepted (check_table_result: cidr:/etc/postfix/allowed_clients.cidr OK 209.85.[...]) even though neither is explicitly in that file (I guess due to the magic of my "209.85.128.0/17 OK" entry?).

Anyway, why does the first message get bounced, then seconds later the other message get relayed?
Any suggestions gratefully received.