As the list refuses me to post such long debugs, I'm splitting my mail in two:
This is application specific mail (actually some delivery reports for MMS) and I need to do a lot of envelope and content rewrites as different operators have rather specific approach to standards.. But luckily postfix can fix this, at least when this mystery gets resolved.. Here are the specifics: header_checks file (REGEXP): /^To: \+1234567\/TYPE=PLMN$/ REPLACE To: 1919/TYPE=PLMN ------------------------------------------------- This is the mail I've been testing with, I just moved the To: header above or below Received: EHLO mms-mta2.domain1.com MAIL FROM:<+11111111/type=p...@mms.domain2.com> SIZE=2087 RCPT TO:<+1234567/type=p...@mms.domain1.com> ORCPT=rfc822;+2B1234567/ type+3dp...@mms.domain3.com <type%2b3dp...@mms.domain3.com> DATA X-Mms-3GPP-MMS-Version: 6.3.0 X-Mms-Ack-Request: Yes X-Mms-Message-Type: MM4_forward.REQ X-Mms-Delivery-Report: Yes Received: from smtp.domain1.com ([127.0.0.1]) .by localhost (mobi-mailv78.domain1.com [127.0.0.1]) (amavisd-new, port 10024) To: +1234567/TYPE=PLMN From: +11111111/TYPE=PLMN Date: Mon, 06 Sep 2010 11:09:56 +0000 Content-Type: multipart/related; Type="application/smil"; Start="<YoCEK>"; boundary="Nokia-mm-messageHandler-BoUnDaRy-=_ -1883574436" Sender: +11111111/type=p...@mms.domain2.com X-Mms-Originator-System: system-u...@mms.domain2.com X-Mms-Message-ID: "TITMBAoEBgQAAEmtAAAGzAAAAHgAAAAB" X-Mms-Transaction-ID: "TITMBAoEBgQAAEmtAAAGzAAAAHgAAAAA1" Message-ID: <titmbqoebgqaac74aaatlqaaaaeaa...@mms.domain2.com> X-Mms-Originator-R/S-Delivery-Report: NO . QUIT ------------------------------------------------- This are the debug logs on these tests, first the one with the To: header before Received:, hence matched: Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] connect from unknown[10.40.0.20] Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostname: unknown ~? 10.40.0.33/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostaddr: 10.40.0.20 ~? 10.40.0.33/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostname: unknown ~? 10.40.0.20/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostaddr: 10.40.0.20 ~? 10.40.0.20/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 220 mmc.domain1.com ESMTP Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] watchdog_pat: 8c000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] < unknown[10.40.0.20]: EHLO mms-mta2.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] improper command pipelining after EHLO from unknown[10.40.0.20] Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-mms-mta3.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-PIPELINING Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-SIZE 10240000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-VRFY Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_list_match: unknown: no match Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_list_match: 10.40.0.20: no match Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-ETRN Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-ENHANCEDSTATUSCODES Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250-8BITMIME Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250 DSN Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] watchdog_pat: 8c000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] < unknown[10.40.0.20]: MAIL FROM:<+11111111/type=p...@mms.domain2.com> SIZE=2087 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] extract_addr: input: <+11111111/type=p...@mms.domain2.com> Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] smtpd_check_addr: addr=+11111111/type=p...@mms.domain2.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] ctable_locate: move existing entry key +11111111/type=p...@mms.domain2.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] extract_addr: in: <+11111111/type=p...@mms.domain2.com>, result: +11111111/type=p...@mms.domain2.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] fsspace: .: block size 512, blocks free 10195766 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] smtpd_check_queue: blocks 512 avail 10195766 min_free 0 msg_size_limit 10240000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250 2.1.0 Ok Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] watchdog_pat: 8c000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] < unknown[10.40.0.20]: RCPT TO:<+1234567/type=p...@mms.domain1.com> ORCPT=rfc822;+2B1234567/type+3dp...@mms.domain3.com<type%2b3dp...@mms.domain3.com> Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] extract_addr: input: <+1234567/type=p...@mms.domain1.com> Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] smtpd_check_addr: addr=+1234567/type=p...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] ctable_locate: move existing entry key +1234567/type=p...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] extract_addr: in: <+1234567/type=p...@mms.domain1.com>, result: +1234567/type=p...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] >>> START Recipient address RESTRICTIONS <<< Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] generic_checks: name=permit_mynetworks Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] permit_mynetworks: unknown 10.40.0.20 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostname: unknown ~? 10.40.0.33/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostaddr: 10.40.0.20 ~? 10.40.0.33/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostname: unknown ~? 10.40.0.20/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostaddr: 10.40.0.20 ~? 10.40.0.20/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] generic_checks: name=permit_mynetworks status=1 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] >>> CHECKING RECIPIENT MAPS <<< Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] ctable_locate: leave existing entry key +1234567/type=p...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] dict_regexp_lookup: /etc/postfix/canonical-recipient: +1234567/type= p...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] maps_find: recipient_canonical_maps: regexp:/etc/postfix/canonical-recipient(0,lock|fold_fix): +1234567/type= p...@mms.domain1.com = 1...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] mail_addr_find: +1234567/type=p...@mms.domain1.com -> 1...@mms.domain1.com Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] smtpd_check_rewrite: trying: permit_inet_interfaces Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] permit_inet_interfaces: unknown 10.40.0.20 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] before input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping enable_milters Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] after input_transp_cleanup: cleanup flags = enable_header_body_filter enable_automatic_bcc enable_address_mapping Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] connect to subsystem public/cleanup Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] public/cleanup socket: wanted attribute: queue_id Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute name: queue_id Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute value: 4C1198C01 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] public/cleanup socket: wanted attribute: (list terminator) Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute name: (end) Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] send attr flags = 178 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] 4C1198C01: client=unknown[10.40.0.20] Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250 2.1.5 Ok Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] watchdog_pat: 8c000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] < unknown[10.40.0.20]: DATA Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 354 End data with <CR><LF>.<CR><LF> Sep 13 13:26:37 fps-mms-mta3 postfix/cleanup[16757]: [ID 197553 mail.info] 4C1198C01: replace: header To: +1234567/TYPE=PLMN from unknown[10.40.0.20]; from=<+11111111/type=p...@mms.domain2.com> to=<1...@mms.domain1.com> proto=ESMTP helo=<mms-mta2.domain1.com>: To: 1919/TYPE=PLMN Sep 13 13:26:37 fps-mms-mta3 postfix/cleanup[16757]: [ID 197553 mail.info] 4C1198C01: message-id=<> Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] public/cleanup socket: wanted attribute: status Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute name: status Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute value: 0 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] public/cleanup socket: wanted attribute: reason Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute name: reason Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute value: (end) Sep 13 13:26:37 fps-mms-mta3 postfix/qmgr[16753]: [ID 197553 mail.info] 4C1198C01: from=<+11111111/type=p...@mms.domain2.com>, size=1017, nrcpt=1 (queue active) Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] public/cleanup socket: wanted attribute: (list terminator) Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] input attribute name: (end) Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 250 2.0.0 Ok: queued as 4C1198C01 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] watchdog_pat: 8c000 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] < unknown[10.40.0.20]: QUIT Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] > unknown[10.40.0.20]: 221 2.0.0 Bye Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] smtp_flush: EOF Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostname: unknown ~? 10.40.0.33/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostaddr: 10.40.0.20 ~? 10.40.0.33/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostname: unknown ~? 10.40.0.20/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] match_hostaddr: 10.40.0.20 ~? 10.40.0.20/32 Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] lost connection after QUIT from unknown[10.40.0.20] Sep 13 13:26:37 fps-mms-mta3 postfix/smtpd[16755]: [ID 197553 mail.info] disconnect from unknown[10.40.0.20] Sep 13 13:26:37 fps-mms-mta3 postfix/smtp[16758]: [ID 197553 mail.info] 4C1198C01: to=<1...@mms.domain1.com>, orig_to=<+1234567/TYPE= p...@mms.domain1.com>, relay=10.40.0.75[10.40.0.75]:25, delay=0.03, delays=0.02/0/0/0.01, dsn=2.0.0, status=sent (250 <4C2C5DE400179E4C> Mail accepted) Sep 13 13:26:37 fps-mms-mta3 postfix/qmgr[16753]: [ID 197553 mail.info] 4C1198C01: removed
