I didn't want to post such a large log, but here goes. The first line is where the message is actually moved to MailDir (it's desired location). The entries after that result after the email is moved from Maildir/new to Maildir/Cur:
Sep 1 23:44:07 production postfix/local[10458]: 0352A760851: to=<myacco...@mydomain.com>, orig_to=<10...@mysubdomain.mydomain.com>, relay=local, delay=0.47, delays=0.41/0.01/0/0.05, dsn=2.0.0, status=sent (delivered to maildir) Sep 1 23:44:07 production postfix/qmgr[1728]: 0352A760851: removed Sep 1 23:44:08 production postfix/smtpd[10454]: connect from localhost[127.0.0.1] Sep 1 23:44:08 production postfix/smtpd[10454]: smtp_stream_setup: maxtime=300 enable_deadline=0 Sep 1 23:44:08 production postfix/smtpd[10454]: match_hostname: localhost ~? 127.0.0.1/32 Sep 1 23:44:08 production postfix/smtpd[10454]: match_hostaddr: 127.0.0.1 ~? 127.0.0.1/32 Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 220 myDomain.com ESMTP Postfix (Ubuntu) Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: EHLO localhost.localdomain Sep 1 23:44:08 production postfix/smtpd[10454]: match_list_match: localhost: no match Sep 1 23:44:08 production postfix/smtpd[10454]: match_list_match: 127.0.0.1: no match Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-PIPELINING Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-SIZE Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-VRFY Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-ETRN Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-STARTTLS Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-ENHANCEDSTATUSCODES Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-8BITMIME Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250 DSN Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: STARTTLS Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 220 2.0.0 Ready to start TLS Sep 1 23:44:08 production postfix/smtpd[10454]: send attr request = seed Sep 1 23:44:08 production postfix/smtpd[10454]: send attr size = 32 Sep 1 23:44:08 production postfix/smtpd[10454]: private/tlsmgr: wanted attribute: status Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: status Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute value: 0 Sep 1 23:44:08 production postfix/smtpd[10454]: private/tlsmgr: wanted attribute: seed Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: seed Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute value: GfF1kSUUVHfu1f+KP5dM5mDi57+9pZaXXZfosy4x1ZY= Sep 1 23:44:08 production postfix/smtpd[10454]: private/tlsmgr: wanted attribute: (list terminator) Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: (end) Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: EHLO localhost.localdomain Sep 1 23:44:08 production postfix/smtpd[10454]: match_list_match: localhost: no match Sep 1 23:44:08 production postfix/smtpd[10454]: match_list_match: 127.0.0.1: no match Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-PIPELINING Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-SIZE Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-VRFY Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-ETRN Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-ENHANCEDSTATUSCODES Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250-8BITMIME Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250 DSN Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: MAIL FROM:<myotheracco...@mysubdomain.com> Sep 1 23:44:08 production postfix/smtpd[10454]: extract_addr: input: <myotheracco...@mysubdomain.com> Sep 1 23:44:08 production postfix/smtpd[10454]: smtpd_check_addr: addr=myotheracco...@mysubdomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: ctable_locate: move existing entry key myotheracco...@mysubdomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: extract_addr: in: <myotheracco...@mysubdomain.com>, result: myotheracco...@mysubdomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: smtpd_check_rewrite: trying: permit_inet_interfaces Sep 1 23:44:08 production postfix/smtpd[10454]: permit_inet_interfaces: localhost 127.0.0.1 Sep 1 23:44:08 production postfix/smtpd[10454]: fsspace: .: block size 4096, blocks free 191205988 Sep 1 23:44:08 production postfix/smtpd[10454]: smtpd_check_queue: blocks 4096 avail 191205988 min_free 0 msg_size_limit 0 Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250 2.1.0 Ok Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: RCPT TO:<10...@mysubdomain.mydomain.com> Sep 1 23:44:08 production postfix/smtpd[10454]: extract_addr: input: <10...@mysubdomain.mydomain.com> Sep 1 23:44:08 production postfix/smtpd[10454]: smtpd_check_addr: addr=10...@mysubdomain.mydomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: ctable_locate: move existing entry key 10...@mysubdomain.mydomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: extract_addr: in: <10...@mysubdomain.mydomain.com>, result: 10...@mysubdomain.mydomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: >>> START Recipient address RESTRICTIONS <<< Sep 1 23:44:08 production postfix/smtpd[10454]: generic_checks: name=permit_mynetworks Sep 1 23:44:08 production postfix/smtpd[10454]: permit_mynetworks: localhost 127.0.0.1 Sep 1 23:44:08 production postfix/smtpd[10454]: match_hostname: localhost ~? 127.0.0.1/32 Sep 1 23:44:08 production postfix/smtpd[10454]: match_hostaddr: 127.0.0.1 ~? 127.0.0.1/32 Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: permit_mynetworks ~? all Sep 1 23:44:08 production postfix/smtpd[10454]: match_list_match: permit_mynetworks: no match Sep 1 23:44:08 production postfix/smtpd[10454]: generic_checks: name=permit_mynetworks status=1 Sep 1 23:44:08 production postfix/smtpd[10454]: >>> END Recipient address RESTRICTIONS <<< Sep 1 23:44:08 production postfix/smtpd[10454]: >>> CHECKING RECIPIENT MAPS <<< Sep 1 23:44:08 production postfix/smtpd[10454]: ctable_locate: leave existing entry key 10...@mysubdomain.mydomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: recipient_canonical_maps: 10...@mysubdomain.mydomain.com: not found Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? localhost.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? localhost Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? production.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? hetzner2.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? aws2.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? mySubDomain.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: recipient_canonical_maps: 10711: not found Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: recipient_canonical_maps: @mySubDomain.myDomain.com: not found Sep 1 23:44:08 production postfix/smtpd[10454]: mail_addr_find: 10...@mysubdomain.mydomain.com -> (not found) Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: canonical_maps: 10...@mysubdomain.mydomain.com: not found Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? localhost.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? localhost Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? production.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? hetzner2.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? aws2.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: match_string: mySubDomain.myDomain.com ~? mySubDomain.myDomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: canonical_maps: 10711: not found Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: canonical_maps: @mySubDomain.myDomain.com: not found Sep 1 23:44:08 production postfix/smtpd[10454]: mail_addr_find: 10...@mysubdomain.mydomain.com -> (not found) Sep 1 23:44:08 production postfix/smtpd[10454]: dict_regexp_lookup: /etc/postfix/virtual_maps: 10...@mysubdomain.mydomain.com Sep 1 23:44:08 production postfix/smtpd[10454]: maps_find: virtual_alias_maps: regexp:/etc/postfix/virtual_maps(0,lock|fold_fix): 10...@mysubdomain.mydomain.com = myAccount Sep 1 23:44:08 production postfix/smtpd[10454]: mail_addr_find: 10...@mysubdomain.mydomain.com -> myAccount Sep 1 23:44:08 production postfix/smtpd[10454]: before input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping enable_milters Sep 1 23:44:08 production postfix/smtpd[10454]: after input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping Sep 1 23:44:08 production postfix/smtpd[10454]: connect to subsystem public/cleanup Sep 1 23:44:08 production postfix/smtpd[10454]: public/cleanup socket: wanted attribute: queue_id Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: queue_id Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute value: CFBFB760851 Sep 1 23:44:08 production postfix/smtpd[10454]: public/cleanup socket: wanted attribute: (list terminator) Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: (end) Sep 1 23:44:08 production postfix/smtpd[10454]: send attr flags = 178 Sep 1 23:44:08 production postfix/smtpd[10454]: CFBFB760851: client=localhost[127.0.0.1] Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250 2.1.5 Ok Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: DATA Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 354 End data with <CR><LF>.<CR><LF> Sep 1 23:44:08 production postfix/cleanup[10457]: CFBFB760851: message-id=<8785078f-25f2-45a6-a162-f6a8ef8e2...@mysubdomain.com> Sep 1 23:44:08 production postfix/smtpd[10454]: public/cleanup socket: wanted attribute: status Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: status Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute value: 0 Sep 1 23:44:08 production postfix/smtpd[10454]: public/cleanup socket: wanted attribute: reason Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: reason Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute value: (end) Sep 1 23:44:08 production postfix/smtpd[10454]: public/cleanup socket: wanted attribute: (list terminator) Sep 1 23:44:08 production postfix/smtpd[10454]: input attribute name: (end) Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 250 2.0.0 Ok: queued as CFBFB760851 Sep 1 23:44:08 production postfix/qmgr[1728]: CFBFB760851: from=<myotheracco...@mysubdomain.com>, size=15299, nrcpt=1 (queue active) Sep 1 23:44:08 production postfix/smtpd[10454]: watchdog_pat: 0x7f8fcad48ea0 Sep 1 23:44:08 production postfix/smtpd[10454]: < localhost[127.0.0.1]: QUIT Sep 1 23:44:08 production postfix/smtpd[10454]: > localhost[127.0.0.1]: 221 2.0.0 Bye Sep 1 23:44:08 production postfix/smtpd[10454]: match_hostname: localhost ~? 127.0.0.1/32 Sep 1 23:44:08 production postfix/smtpd[10454]: match_hostaddr: 127.0.0.1 ~? 127.0.0.1/32 Sep 1 23:44:08 production postfix/smtpd[10454]: disconnect from localhost[127.0.0.1] Sep 1 23:44:08 production postfix/local[10458]: CFBFB760851: to=<myacco...@mydomain.com>, orig_to=<10...@mysubdomain.mydomain.com>, relay=local, delay=0.12, delays=0.08/0/0/0.04, dsn=5.4.6, status=bounced (mail forwarding loop for myacco...@mydomain.com) Sep 1 23:44:08 production postfix/cleanup[10457]: EC86B7608BA: message-id=<20130901234408.ec86b760...@mydomain.com> Sep 1 23:44:09 production postfix/bounce[10464]: CFBFB760851: sender non-delivery notification: EC86B7608BA Sep 1 23:44:09 production postfix/qmgr[1728]: EC86B7608BA: from=<>, size=17198, nrcpt=1 (queue active) Sep 1 23:44:09 production postfix/qmgr[1728]: CFBFB760851: removed On Sep 1, 2013, at 12:24 PM, Wietse Venema <wie...@porcupine.org> wrote: > Warren H. Prince: >> My problem is that after mailman grabs the email, some postfix >> process kicks off that attempts to communicate with the original >> mail server that sent the email. That communication results in a >> forwarding loop and a bounced email warning. I can't seem to >> figure out what process is attempting to forward the email after >> we grab it from Maildir/new. I don't want the message to be > > Maybe you can show the Postfix logging, then someone can figure out > where the new email comes from. > > You can anonymize the @domain portion of the email addresses. > > Wietse
