1 (edited by k-hussein 2015-06-25 22:26:23)

Topic: Mails with attachments delayed

==== Required information ====
- iRedMail version (check /etc/iredmail-release): 0.9.0
- Linux/BSD distribution name and version: Ubuntu 14.04 LTS
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): LDAP
- Web server (Apache or Nginx): Nginx
- Manage mail accounts with iRedAdmin-Pro? yes
- Related log if you're reporting an issue:
====
Hello guys,

some of my employees complained that they are not receiving mails with attachment and sometimes it take about 2 hours to get that mails , there is 2 mail back ends in my environment Office365 and Iredmail
this happens when we send from Office365 to iredmail
when I did message trace from the office365 side the message status is :
Message size: 3639 KB
Status :Pending
Reason : 451 4.4.0 SMTPSEND.SuspiciousRemoteServerError: remote server disconnected abruptlyl; retry will be delayed. OutboundProxyTargetIP:*****(My Iredmail Server IP). OutboundProxyTargetHostNamet: *******.net

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2

Re: Mails with attachments delayed

any ideas , any logs I can check on my iredmail server I already added all my domains and Ips to white list , and finally disabled the cluebringer but still the same issue.

3

Re: Mails with attachments delayed

No idea yet. Does sending email with attachment from your mail client application (e.g. Thunderbird, Outlook) with iRedMail server slow?

4

Re: Mails with attachments delayed

no , sending is not a problem

5 (edited by k-hussein 2015-06-27 03:00:34)

Re: Mails with attachments delayed

I tried to analyze a delayed mail header  sent from yahoo to my iredmail domain as per the attached screenshot the cause of the delay is the amavis ? any ia

Post's attachments

delay.JPG 157.93 kb, file has never been downloaded. 

You don't have the permssions to download the attachments of this post.

6

Re: Mails with attachments delayed

Could you please turn on debug mode in Amavisd and try again? Paste FULL log here.
Reference: http://www.iredmail.org/docs/debug.amavisd.html

7 (edited by k-hussein 2015-06-29 20:57:33)

Re: Mails with attachments delayed

the log is 11 MB should I upload it somewhere and get you the link ?
thanks
HYG
http://www.4shared.com/file/UqtWm6coce/mail.html

8

Re: Mails with attachments delayed

Please just paste log related to your testing email, not the whole maillog file.

9

Re: Mails with attachments delayed

helo=omp1013.mail.bf1.yahoo.com, from=mss4t@yahoo.com, to=postmaster@centro-gs.net, reason=greylisted
Jun 29 08:09:32 Exchange-P01 postfix/smtpd[39911]: NOQUEUE: reject: RCPT from omp1013.mail.bf1.yahoo.com[98.139.212.204]: 451 4.7.1 <postmaster@centro-gs.net>: Recipient address rejected: Greylisting in effect, please come back later; from=<mss4t@yahoo.com> to=<postmaster@centro-gs.net> proto=ESMTP helo=<omp1013.mail.bf1.yahoo.com>
Jun 29 08:09:32 Exchange-P01 postfix/smtpd[39911]: disconnect from omp1013.mail.bf1.yahoo.com[98.139.212.204]
Jun 29 08:09:35 Exchange-P01 postfix/smtpd[39911]: connect from mail-by2on0099.outbound.protection.outlook.com[207.46.100.99]
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) switch_to_client_time 480 s, smtp response sent
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) switch_to_client_time 480 s, receiving data
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) enforcing size limit 20971520 during DATA
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) smtp copy: read 32768 bytes into buffer, new size: 32768
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) smtp copy: read 10922 bytes into buffer, new size: 10922
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) smtp copy: read 10081 bytes into buffer, new size: 10081
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) smtp copy: 6 bytes still buffered at end
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) get_deadline switch_to_my_time(rx data-end) - deadline in 480.0 s, set to 336.000 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) prolong_timer switch_to_my_time(rx data-end): timer 336, was 480, deadline in 480.0 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) ESMTP< .<CR><LF>
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) Actual message size 53762 B less than the declared 53764 B
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) smtp connection cache, dt: 34.3, state: 0
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) get_deadline get_body_digest - deadline in 480.0 s, set to 30.000 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) get_deadline digest_pre - deadline in 480.0 s, set to 336.000 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) prolong_timer digest_pre: timer 336, was 336, deadline in 480.0 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) get_body_digest: reading header section
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) get_deadline digest_hdr - deadline in 480.0 s, set to 336.000 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02) prolong_timer digest_hdr: timer 336, was 336, deadline in 480.0 s
Jun 29 08:09:38 Exchange-P01 amavis[43131]: (43131-02)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p157: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p158: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p158: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p159: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p159: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p160: C++ source, ASCII text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, ASCII text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, ASCII text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p160: C++ source, ASCII text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p161: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p161: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p162: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p162: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p163: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p163: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p164: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p164: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p165: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p165: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p166: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p166: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p167: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p167: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) result line from file(1): p168: C++ source, UTF-8 Unicode text, with very long lines\n
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re("C++ source, UTF-8 Unicode text, with very long lines") matches key "(?^i:\btext\b)", result="asc"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [map_full_type_to_short_type] => true,  "C++ source, UTF-8 Unicode text, with very long lines" matches, result="asc", matching_key="(?^i:\\btext\\b)"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) File-type of p168: C++ source, UTF-8 Unicode text, with very long lines; (asc)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p006 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p007 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p008 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p009 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p010 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p011 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p012 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p013 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p014 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p015 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p016 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p017 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p018 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p019 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p020 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p021 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p022 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p023 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p024 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p025 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p026 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p027 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p028 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p029 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p030 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p031 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p032 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p033 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p034 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p035 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p036 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p037 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p038 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p039 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p040 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p041 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p042 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p043 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p044 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p045 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p046 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p047 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p048 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p049 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p050 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p051 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p052 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p053 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p054 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p055 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p056 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p057 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p058 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p059 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p060 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p061 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p062 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p063 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p064 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p065 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p066 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p067 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p068 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p069 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p070 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p071 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p072 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p073 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p074 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p075 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p076 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p077 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p078 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p079 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p080 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p081 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p082 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p083 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p084 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p085 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p086 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p087 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p088 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p089 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p090 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p091 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p092 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p093 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p094 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p095 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p096 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p097 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p098 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p099 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p100 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p101 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p102 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p103 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p104 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p105 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p106 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p107 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p108 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p109 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p110 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p111 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p112 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p113 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p114 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p115 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p116 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p117 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p118 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p119 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p120 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p121 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p122 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p123 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p124 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p125 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p126 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p127 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p128 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p129 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p130 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p131 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p132 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p133 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p134 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p135 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p136 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p137 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p138 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p139 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p140 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p141 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p142 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p143 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p144 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p145 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p146 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p147 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p148 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p149 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p150 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p151 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p152 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p153 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p154 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p155 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p156 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p157 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p158 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p159 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p160 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p161 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p162 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p163 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p164 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p165 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p166 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p167 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) decompose_part: p168 - atomic
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) get_deadline parts_decode - deadline in 477.9 s, set to 335.000 s
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) prolong_timer parts_decode: timer 335, was 334, deadline in 477.9 s
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_sql_field(bypass_header_checks) rec=0, "postmaster@centro-gs.net" result: undef
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_sql_field(bypass_header_checks) rec=1, "postmaster@centro-gs.net" result: undef
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [bypass_header_checks] => undef, "postmaster@centro-gs.net" does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) check_header: 0, OK
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_sql_field(bypass_header_checks) rec=0, "postmaster@centro-gs.net" result: undef
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_sql_field(bypass_header_checks) rec=1, "postmaster@centro-gs.net" result: undef
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [bypass_header_checks] => undef, "postmaster@centro-gs.net" does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) Checking for banned types and filenames
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_sql_field(banned_rulenames) rec=0, "postmaster@centro-gs.net" result: undef
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_sql_field(banned_rulenames) rec=1, "postmaster@centro-gs.net" result: undef
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup: (scalar) matches, result="DEFAULT"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [banned_filename], 1 matches for "postmaster@centro-gs.net", results: "(constant:DEFAULT)"=>"DEFAULT"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) collect banned table[0]: postmaster@centro-gs.net, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x2289580)
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) starting banned checks - traversing message structure tree
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) check_for_banned (p004,p005,p001) multipart/mixed | multipart/alternative | text/plain,.asc
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) doing banned check for postmaster@centro-gs.net on multipart/mixed | multipart/alternative | text/plain,.asc
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re(["multipart/mixed","multipart/alternative","text/plain",".asc"]), no matches
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [check_bann:postmaster@centro-gs.net] => undef, ["multipart/mixed","multipart/alternative","text/plain",".asc"] does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [banned_namepath_re] => undef, "P=p004\tL=1\tM=multipart/mixed\nP=p003\tL=1/2\tM=application/x-zip-compressed\tT=zip\tN=policy_templates.zip\nP=p043\tL=1/2/38\tT=asc\tN=windows/admx/nl/chrome.adml" does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) p.path postmaster@centro-gs.net: "P=p004,L=1,M=multipart/mixed | P=p003,L=1/2,M=application/x-zip-compressed,T=zip,N=policy_templates.zip | P=p043,L=1/2/38,T=asc,N=windows/admx/nl/chrome.adml"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) check_for_banned (p004,p003,p044) multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/nb/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) doing banned check for postmaster@centro-gs.net on multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/nb/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re(["multipart/mixed","application/x-zip-compressed",".zip","policy_templates.zip",".asc","windows/admx/nb/chrome.adml"]), no matches
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [check_bann:postmaster@centro-gs.net] => undef, ["multipart/mixed","application/x-zip-compressed",".zip","policy_templates.zip",".asc","windows/admx/nb/chrome.adml"] does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [banned_namepath_re] => undef, "P=p004\tL=1\tM=multipart/mixed\nP=p003\tL=1/2\tM=application/x-zip-compressed\tT=zip\tN=policy_templates.zip\nP=p044\tL=1/2/39\tT=asc\tN=windows/admx/nb/chrome.adml" does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) p.path postmaster@centro-gs.net: "P=p004,L=1,M=multipart/mixed | P=p003,L=1/2,M=application/x-zip-compressed,T=zip,N=policy_templates.zip | P=p044,L=1/2/39,T=asc,N=windows/admx/nb/chrome.adml"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) check_for_banned (p004,p003,p045) multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/pl/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) doing banned check for postmaster@centro-gs.net on multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/pl/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re(["multipart/mixed","application/x-zip-compressed",".zip","policy_templates.zip",".asc","windows/admx/pl/chrome.adml"]), no matches
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [check_bann:postmaster@centro-gs.net] => undef, ["multipart/mixed","application/x-zip-compressed",".zip","policy_templates.zip",".asc","windows/admx/pl/chrome.adml"] does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [banned_namepath_re] => undef, "P=p004\tL=1\tM=multipart/mixed\nP=p003\tL=1/2\tM=application/x-zip-compressed\tT=zip\tN=policy_templates.zip\nP=p045\tL=1/2/40\tT=asc\tN=windows/admx/pl/chrome.adml" does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) p.path postmaster@centro-gs.net: "P=p004,L=1,M=multipart/mixed | P=p003,L=1/2,M=application/x-zip-compressed,T=zip,N=policy_templates.zip | P=p045,L=1/2/40,T=asc,N=windows/admx/pl/chrome.adml"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) check_for_banned (p004,p003,p046) multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/pt-BR/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) doing banned check for postmaster@centro-gs.net on multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/pt-BR/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup_re(["multipart/mixed","application/x-zip-compressed",".zip","policy_templates.zip",".asc","windows/admx/pt-BR/chrome.adml"]), no matches
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [check_bann:postmaster@centro-gs.net] => undef, ["multipart/mixed","application/x-zip-compressed",".zip","policy_templates.zip",".asc","windows/admx/pt-BR/chrome.adml"] does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) lookup [banned_namepath_re] => undef, "P=p004\tL=1\tM=multipart/mixed\nP=p003\tL=1/2\tM=application/x-zip-compressed\tT=zip\tN=policy_templates.zip\nP=p046\tL=1/2/41\tT=asc\tN=windows/admx/pt-BR/chrome.adml" does not match
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) p.path postmaster@centro-gs.net: "P=p004,L=1,M=multipart/mixed | P=p003,L=1/2,M=application/x-zip-compressed,T=zip,N=policy_templates.zip | P=p046,L=1/2/41,T=asc,N=windows/admx/pt-BR/chrome.adml"
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) check_for_banned (p004,p003,p047) multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/pt-PT/chrome.adml
Jun 29 08:17:42 Exchange-P01 amavis[43131]: (43131-15) doing banned check for postmaster@centro-gs.net on multipart/mixed | application/x-zip-compressed,.zip,policy_templates.zip | .asc,windows/admx/pt-PT/chrome.adml
Z6\r\nSpsIuqlVD1C1H9/x3RR8OD7vzfUry [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49790> TsWgEPA2CNtjrUXEvAj/LBIm/vFAjIP8FxwN0M8cx5CB9nG67qYX2zpkV49rgF0r\r\natYx+rlcX6+Ga2tY3T+s1slTElDd66BKCql/OhBI/dMaUheG1BPo4jDweB9spGsl7AExZicTVKuK\r\nGiEEDc9ReY61ckSUvLiNPAitc/Hscxgq8+2wfe2hCRiOkQQE9x2vAwmy [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49790> TiO5YGOqbL+ZjV4Zm25bP2byJImfxiKORFVSjL\r\nlhRKSsoyNiPLC7u2Gde5QDsIsAyr5fZgOnKX0uZfBAz4L73KzN30ksz1mHTlHT31eTznPU/phR/M\r\nc/PXTqqjQr05RA0/oM+bs2NDDnSPqWWDG/yUUlvTzhWiT/3FDz/ie6GuvimtLefFXQ9GjOB30rfE\r\niMA7 [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49791> OnAK3JKHPUGu\r\netVwbkjFTAMymal5vPbGGthNxileaC0xqZX0CwjJDrSYq4AiEaz30AN7ehny8SyUaUa5LNyvngz5\r\nS+BJdNeGIqhikCBuEtPkmJB2U83XXIBAJ345wYlUAOB+9QQALxGaBWBY459kpQOS96Mvo1/1GTM3\r\nnUxELOfgXFrxSbHCW2vSiykn8BoBiv [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49790> WeYWiqIPQ0RuQY4BA5CObHiWjYukV4AYYlKPQEefy9OoO\r\nKTsOMirdJfXepjo/UlMRFPwiejPJI3JIHQ3g0jAda8cm6JCFBOBjGw4ZjHaZMLDt7ECACQvjco1G\r\n9rNi5UxkvuHNNK0k0KNVmzyAt0OJmt0TskiHMGiqXA6o9XEUwgpkIgW/y1AsBqM16Sb5+Nyxibb [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49791> EISuzIO/Zys5/mNAV0E\r\nPOx53VRxNwsqArjU1jd1UJ4/cOZfEbxu2dvtwI+81m2/xRMQCosrAsUmnXbiAvncNja5RgSrFjmM\r\nObhG3pdBRE2/cxJ4MlHGnCPo+vb2ZscJWhtWIPqrvr9N1sDi8opguCrdbG0iSinzTXTdpJjarDln\r\nTkM5TKBfXSvYvm6ll/vyZRX [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49790> 1Bv/JOaVuq+sr+UkIttDcOymyvhxWJ1dY77ouw6Ax7SipdVduKxTBwPZEgsU7keVkcixD/\r\nxsAMuYWdTpamUZhQ6XZo7Ol9pzNW3cpnbYdGMal3eOSTVBAw0mN4iz+lqsU0JJxVV50ooEf10XLE\r\nCMbmH8pv1SzJmsbLZBL7/YjKuTyC3xJgCaCk+QWnsD7V2MW7ht [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49790> g1RurQswAKZucXTssr+/ubUUKiqWjLI7Nfo8UA0BMtnp\r\nnLXqL2lXhdYbfEMd/O3tO93Mdb8qTPfRLtg93fnuq1nXEyCUiHQSky01itq057uvoDE7JJAz2SGF\r\n6D60MvWjvOMKJs/+y6ettn8ko0Igo3xsL1D9RpDYiqikhzvnEbVGUZGB1y4cCi0y/zIygpakYqKC [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49791> EY7CjFBaDIRP19bYJj\r\n1kK/aFOwDcA9NwEtqza72idqdiJHo/RmL40AnJHvE0tO4zbhRMrG6llvAXWZQNjHIXrtfiEzlZuc\r\nLo69lr8jJMCICRtT+TvjcmQ7HK++V8WxF4JngXSKtw/iTxZoBzkIkPNi0HHo65UlTDt9DRgNtQKv\r\nThvGRR2gfvk6gA8XkFcn6TXm [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=180
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 49790> PLDdsxu3eYmMdJOzWUuP4Ko6YpS90lNdag/omaUKTkhJ0RdCSJCbVNgkgjsBwuXLpnCbA\r\n6BJELETiWEJiKAoFY+OIICaT+F8ZCtSTBqJVHDhQrYsqWiBQ3qMYj391RJB0QdJglEDVcam2Voql\r\nEvrqladAbAlaTJWSuibUQmWCktI1KU6wWat6anfpUHOFUKWSa5K [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) smtp connection_cache disabled, sending QUIT
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) smtp cmd> QUIT
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=0, flush=1, wr=1, timeout=471.840
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: sending
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop sent 27279> UuUOrp1i1q0dak9xAtZKDGyJOqIbnoZoxvXTXzT8roh\r\nWVbm1P5wyyQ7Y+2NP2kWz1AKk57uC/oIejsAM0YHwkNhE6v3pTvbhsNCVWF46Vj2BpscZmCr0z2y\r\nMwLH7jJMlEM68Zpaup3n46ILkx1uGjxp0zO63ePdBTjZJ3u8U0c7jJ3mYHY0uuF1pOuat3hPs5qW\r [...]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: needline=1, flush=0, wr=0, timeout=471.840
Jun 29 08:17:49 Exchange-P01 postfix/qmgr[44824]: DA5F75CC19F1: from=<mss4t@yahoo.com>, size=10336510, nrcpt=1 (queue active)
Jun 29 08:17:49 Exchange-P01 postfix/smtpd[45308]: disconnect from localhost[127.0.0.1]
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop: receiving
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) rw_loop read 53 chars< 250 2.0.0 Ok: queued as DA5F75CC19F1\r\n221 2.0.0 Bye\r\n
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) smtp resp to data-dot (<postmaster@centro-gs.net>): 250 2.0.0 Ok: queued as DA5F75CC19F1
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) Amavis::Out::SMTP::Session close, disconnecting
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) get_deadline fwd-end-chkpnt - deadline in 471.5 s, set to 331.000 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) prolong_timer fwd-end-chkpnt: timer 331, was 0, deadline in 471.5 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) FWD from <mss4t@yahoo.com> -> <postmaster@centro-gs.net>,BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as DA5F75CC19F1
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) get_deadline forwarding - deadline in 471.5 s, set to 331.000 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) prolong_timer forwarding: timer 331, was 331, deadline in 471.5 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) DSN: sender is credible (orig,dkim), SA: -0.670, <mss4t@yahoo.com>
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) lookup [spam_crediblefrom_dsn_cutoff_level_bysender] => undef, "mss4t@yahoo.com" does not match
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) dsn: from MTA 250 NonBlocking:Clean <mss4t@yahoo.com> -> <postmaster@centro-gs.net>: on_succ=0, on_dly=1, on_fail=1, never=0, warn_sender=, DSN_passed_on=1, destiny=1, mta_resp: "250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as DA5F75CC19F1"
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) DSN: SUCC from MTA 250 NonBlocking:Clean, no DSN requested: <mss4t@yahoo.com> -> <postmaster@centro-gs.net>
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) one_response_for_all <mss4t@yahoo.com>: success, r=0,b=0,d=0, ndn_needed=0, '250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as DA5F75CC19F1'
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) notif=N, suppressed=0, ndn_needed=0, exit=0, 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as DA5F75CC19F1
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) status counters: InMsgsStatus{Relayed,RelayedUntagged,RelayedUntaggedInternal,RelayedUntaggedOriginating}
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) get_deadline delivery-notification - deadline in 471.5 s, set to 331.000 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) prolong_timer delivery-notification: timer 331, was 331, deadline in 471.5 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) fish_out_ip_from_received: 98.139.212.204
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) lookup_ip_acl (publicnetworks) arr.obj: key="98.139.212.204" matches "::FFFF:0:0/96", result=1
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) parse_ip_address_from_received: 98.139.212.204
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [98.139.212.204]:26408 [98.139.212.204] <mss4t@yahoo.com> -> <postmaster@centro-gs.net>, Queue-ID: F36945CC1907, Message-ID: <349532032.1407547.1435580088633.JavaMail.yahoo@mail.yahoo.com>, mail_id: zbJzguVYT6vw, Hits: -0.67, size: 10335948, queued_as: DA5F75CC19F1, dkim_sd=s2048:yahoo.com, 8646 ms
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) get_deadline main_log_entry - deadline in 471.5 s, set to 331.000 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) prolong_timer main_log_entry: timer 331, was 331, deadline in 471.5 s
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) sql begin transaction
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) sql: executing clause: INSERT INTO msgrcpt (partition_tag, mail_id, rseqnum, rid, is_local, content, ds, rs, bl, wl, bspam_level, smtp_resp) VALUES (?,?,?,?,?,?,?,?,?,?,?,?)
Jun 29 08:17:49 Exchange-P01 amavis[43131]: (43131-15) save_info_final zbJzguVYT6vw, orig=Y, chks=VSHBD, cont.ty=C, q.type= , q.to=, dsn=N, score=-0.67, Message-ID: <349532032.1407547.1435580088633.JavaMail.yahoo@mail.yahoo.com>, From: 'KHaLeD HuSsEiN <mss4t@yahoo.com>', Subject: 'Fw: testing Yahoo'
lookup_re("HTML document, ASCII text") matches key "(?^i:\btext\b)", result="asc"

10

Re: Mails with attachments delayed

Wow, it takes so long.
I cannot help in this case, it might be an Amavisd bug. I suggest report this issue to Amavisd mailing list:
http://www.amavis.org/#support

11

Re: Mails with attachments delayed

they support via mail list and I already mailed them 2 days ago and no reply , do you think updating amavis or restarting the server would make any difference ?

12

Re: Mails with attachments delayed

k-hussein wrote:

they support via mail list and I already mailed them 2 days ago and no reply

Sad news.

k-hussein wrote:

do you think updating amavis or restarting the server would make any difference ?

It's worth a try.