Aug 5, 2024: iRedMail-1.7.1 has been released.

**samtech** · 2015-12-26 16:58:51

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Topic: [SOLVED] Block attachment for only outbound emails

======== Required information ====
- iRedMail version (check /etc/iredmail-release): 0.9.2
- Linux/BSD distribution name and version: Debian 8.0
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): PGSQL
- Web server (Apache or Nginx): Apache
- Manage mail accounts with iRedAdmin-Pro? No
- Related log if you're reporting an issue: none
====

We want to force all our email users to use file-links (like dropbox, owncloud etc.) instead of attaching files to email, but still want to keep the functionality to receive attachments from outside (other domains).

So, i want to block all attachments (except jpg, png) for all *outbound* emails.

How do i achieve it. Can someone help?
I am new to iRedmail and it's my first mail-server that i install in my life.

Thanks in advance.

----

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

**ZhangHuangbin** · 2015-12-26 18:14:35

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

Maybe you can set 'header_checks =' for outbound messages in /etc/postfix/master.cf, for example:

submission ...
    ...
    -o header_checks=pcre:/etc/postfix/body_checks_outbound.pcre

Then add rule in /etc/postfix/header_checks_outbound.pcre to reject emails which contain attachment.

Postfix official document mentions this:
http://www.postfix.org/header_checks.5.html

**samtech** · 2015-12-26 19:18:34

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Thanks Zhang.

I have check that link, there i have to provide each extension that i want to block.
Is there any way to block all attachments except JPG, PNG (to include in signatures). i.e. instead of block, i could set allowed extensions.

Also can we use amavis-new to accomplish same.

----
Edit:
header_checks = for inbound mails
smtp_header_checks = for outbound mails

And that postfix doc says, DISCARD, REJECT etc actions are not supported for smtp_header_checks.

Does it mean i have no way to discard emails with attachments?

**samtech** · 2015-12-28 17:36:15

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Is there anyone to give some idea to implement that restriction either with Postfix or with amavis?

**ZhangHuangbin** · 2015-12-29 01:07:58

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

One possible solution is doing this with Amavisd:

Add setting 'banned_filename_maps' in policy bank for outbound to block all file types. e.g.

$policy_bank{'ORIGINATING'} = {
    ...
    banned_filename_maps => [
      new_RE(
      # block double extensions in names:
        qr'\.[^./]*\.(exe|vbs|pif|scr|bat|cmd|com|cpl|dll)\.?$'i,
      # allow any name or type (except viruses) within an archive:
        [ qr'^\.(Z|gz|bz2|rpm|cpio|tar|zip|rar|arc|arj|zoo)$' => 0],
      # blocks MS executable file(1) types, unless allowed above:
        qr'^\.(exe-ms)$',
      ),
  ],
}

**samtech** · 2015-12-29 21:52:21

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Thanks Zhang.
I wiill try and update with the results.

BTW, i am willing to purchase iRedAdmin Pro panel, but before burning my Credit card, i would like ensure that everything is working properly.

**ZhangHuangbin** · 2015-12-29 22:49:36

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

samtech wrote:

BTW, i am willing to purchase iRedAdmin Pro panel, but before burning my Credit card, i would like ensure that everything is working properly.

Take your time.

**samtech** · 2015-12-30 20:28:20

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Ok, I have added following to /etc/amavis/conf.d/50-user

# Block attachments
banned_filename_maps => [
new_RE(
# block double extensions in names:
qr'\.[^./]*\.(exe|vbs|pif|scr|bat|cmd|com|cpl|dll)\.?$'i,
# allow any name or type (except viruses) within an archive:
[ qr'^\.(Z|gz|bz2|rpm|cpio|tar|zip|rar|arc|arj|zoo)$' => 0],
# blocks MS executable file(1) types, unless allowed above:
qr'^\.(exe-ms)$',
# Office Documents
qr'^\.(doc|docx|xls|xlsx|pdf)$'i,
),
],

But it is not preveting me from sending .xlsx files.
Am i missing something?

---
Edit:
Forget to mention, i have also restarted amavis sevice after update.

$ sudo service amavis restart

**ZhangHuangbin** · 2015-12-30 23:48:19

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

Please try this on a TESTING machine (i didn't verify it yet, so please just try it on testing machine, not production server):

$policy_bank{'ORIGINATING'} = {
    ...
    banned_namepath_maps => [
      new_RE(
        # allow jpg, png
        [ qr'^\.(jpg|png)$' => 0],

        # blocks rest
        qr'^\.',
      ),
  ],
}

**ZhangHuangbin** · 2015-12-31 00:18:54

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

OK, got it working.

$policy_bank{'ORIGINATING'} = {
    ...
    banned_namepath_re => new_RE(
        # Allow email which contains PDF, JPEG attachments
        [qr'T=(jpg|png)'xmi => 0],

        # Block pdf, xlsx
        qr'T=(pdf|xlsx)'xmi,
    ),
    ...
}

Notes:

*) we're using 'banned_namepath_re', not 'banned_filename_re'.
*) Amavisd will detect attachment file type, sample output of file type is:

P=p003\tL=1\tM=multipart/mixed\nP=p002\tL=1/2\tM=application/pdf\tT=pdf\tN=srs.pdf

- 'M=' is MIME type. e.g. 'image/png', 'application/pdf'.
- 'T=' is file type. e.g. 'png', 'pdf'.
- 'N=' is attachment file name.

Searching "banned_namepath_re" in Amavisd official document will give you more details:
https://www.ijs.si/software/amavisd/release-notes.txt

**samtech** · 2015-12-31 14:01:36

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Thanks for your great support Zhang.
But unfortunately i couldn't make it working. Still i am not able to block attachments.

Here is ORIGINATING policy_bank from my 50-user file

$policy_bank{'ORIGINATING'} = { # mail supposedly originating from our users
originating => 1, # declare that mail was submitted by our smtp client
allow_disclaimers => 1, # enables disclaimer insertion if available
# notify administrator of locally originating malware
virus_admin_maps => ["root\@$mydomain"],
spam_admin_maps => [],
bad_header_admin_maps => [],
banned_admin_maps => ["root\@$mydomain"],
warnbadhsender => 0,
warnbannedsender => 0,
# forward to a smtpd service providing DKIM signing service
#forward_method => 'smtp:[amavis]:10027',
# force MTA conversion to 7-bit (e.g. before DKIM signing)
smtpd_discard_ehlo_keywords => ['8BITMIME'],
#bypass_banned_checks_maps => [1], # allow sending any file names and types
terminate_dsn_on_notify_success => 0, # don't remove NOTIFY=SUCCESS option
# Block attachments
banned_namepath_re => new_RE(
# allow JPG, PNG
[qr'T=(jpg|png)'xmi => 0],
# Block Office Documents
qr'T=(doc|docx|xls|xlsx|pdf)'xmi,
),
};

Can you please point out what is wrong there.
And i didn't forget to restart amavis service after making changes.

**ZhangHuangbin** · 2015-12-31 16:42:15

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

Please turn on debug mode in Amavisd and send one more testing email, paste ALL log of your testing email here.

FYI:
http://www.iredmail.org/docs/debug.amavisd.html

**samtech** · 2015-12-31 17:04:33

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Hi,
Here is log content after enabling debug mode.

Part-1

Dec 31 14:17:00 mail amavis[7119]: logging initialized, log level 5, syslog: amavis.mail
Dec 31 14:17:00 mail amavis[7119]: starting. /usr/sbin/amavisd-new at mail.localtest.com amavisd-new-2.10.1 (20141025), Unicode aware, LANG="en_IN"
Dec 31 14:17:00 mail amavis[7119]: perl=5.020002, user=, EUID: 118 (118); group=, EGID: 127 127 (127 127)
Dec 31 14:17:00 mail amavis[7119]: INFO: no optional modules: unicore::lib::Perl::SpacePer.pl unicore::lib::Nt::De.pl Unix::Getrusage
Dec 31 14:17:00 mail amavis[7119]: SpamControl: attempting to load scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin
Dec 31 14:17:00 mail amavis[7119]: SpamControl: scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin
Dec 31 14:17:00 mail amavis[7119]: INFO: SA version: 3.4.0, 3.004000, no optional modules: Net::CIDR::Lite Encode::Detect Razor2::Client::Agent Image::Info Image::Info::GIF Image::Info::JPEG Image::Info::PNG Image::Info::BMP Image::Info::TIFF
Dec 31 14:17:00 mail amavis[7119]: SpamControl: init_pre_chroot on SpamAssassin done
Dec 31 14:17:00 mail amavis[7119]: socket module IO::Socket::IP, protocol families available: INET, INET6
Dec 31 14:17:00 mail amavis[7119]: bind to /var/lib/amavis/amavisd.sock|unix, 127.0.0.1:10024/tcp, [::1]:10024/tcp, 127.0.0.1:9998/tcp, [::1]:9998/tcp
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Process Backgrounded
Dec 31 14:17:00 mail amavis[7129]: Net::Server: 2015/12/31-14:17:00 Amavis (type Net::Server::PreForkSimple) starting! pid(7129)
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Binding to UNIX socket file "/var/lib/amavis/amavisd.sock"
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 with IPv4
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Binding to TCP port 10024 on host ::1 with IPv6
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Binding to TCP port 9998 on host 127.0.0.1 with IPv4
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Binding to TCP port 9998 on host ::1 with IPv6
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Group Not Defined. Defaulting to EGID '127 127'
Dec 31 14:17:00 mail amavis[7129]: Net::Server: User Not Defined. Defaulting to EUID '118'
Dec 31 14:17:00 mail amavis[7129]: Net::Server: Setting up serialization via flock
Dec 31 14:17:00 mail amavis[7129]: after_chroot_init: EUID: 118 (118); EGID: 127 127 (127 127)
Dec 31 14:17:00 mail amavis[7129]: config files read: /usr/share/amavis/conf.d/10-debian_scripts, /usr/share/amavis/conf.d/20-package, /etc/amavis/conf.d/01-debian, /etc/amavis/conf.d/05-domain_id, /etc/amavis/conf.d/05-node_id, /etc/amavis/conf.d/15-av_scanners, /etc/amavis/conf.d/15-content_filter_mode, /etc/amavis/conf.d/20-debian_defaults, /etc/amavis/conf.d/25-amavis_helpers, /etc/amavis/conf.d/30-template_localization, /etc/amavis/conf.d/50-user
Dec 31 14:17:00 mail amavis[7129]: Module Amavis::Conf 2.404
Dec 31 14:17:00 mail amavis[7129]: Module Archive::Zip 1.39
Dec 31 14:17:00 mail amavis[7129]: Module BerkeleyDB 0.54
Dec 31 14:17:00 mail amavis[7129]: Module Compress::Raw::Zlib 2.065
Dec 31 14:17:00 mail amavis[7129]: Module Compress::Zlib 2.064
Dec 31 14:17:00 mail amavis[7129]: Module Crypt::OpenSSL::RSA 0.28
Dec 31 14:17:00 mail amavis[7129]: Module DBD::mysql 4.028
Dec 31 14:17:00 mail amavis[7129]: Module DBI 1.631
Dec 31 14:17:00 mail amavis[7129]: Module DB_File 1.831
Dec 31 14:17:00 mail amavis[7129]: Module Digest::MD5 2.53
Dec 31 14:17:00 mail amavis[7129]: Module Digest::SHA 5.88
Dec 31 14:17:00 mail amavis[7129]: Module Encode 2.60
Dec 31 14:17:00 mail amavis[7129]: Module File::Temp 0.2304
Dec 31 14:17:00 mail amavis[7129]: Module IO::Socket::INET6 2.72
Dec 31 14:17:00 mail amavis[7129]: Module IO::Socket::IP 0.32
Dec 31 14:17:00 mail amavis[7129]: Module MIME::Entity 5.505
Dec 31 14:17:00 mail amavis[7129]: Module MIME::Parser 5.505
Dec 31 14:17:00 mail amavis[7129]: Module MIME::Tools 5.505
Dec 31 14:17:00 mail amavis[7129]: Module Mail::DKIM::Signer 0.4
Dec 31 14:17:00 mail amavis[7129]: Module Mail::DKIM::Verifier 0.4
Dec 31 14:17:00 mail amavis[7129]: Module Mail::Header 2.13
Dec 31 14:17:00 mail amavis[7129]: Module Mail::Internet 2.13
Dec 31 14:17:00 mail amavis[7129]: Module Mail::SPF v2.009
Dec 31 14:17:00 mail amavis[7129]: Module Mail::SpamAssassin 3.004000
Dec 31 14:17:00 mail amavis[7129]: Module Net::DNS 0.81
Dec 31 14:17:00 mail amavis[7129]: Module Net::LibIDN 0.12
Dec 31 14:17:00 mail amavis[7129]: Module Net::Server 2.008
Dec 31 14:17:00 mail amavis[7129]: Module NetAddr::IP 4.075
Dec 31 14:17:00 mail amavis[7129]: Module Scalar::Util 1.38
Dec 31 14:17:00 mail amavis[7129]: Module Socket 2.013
Dec 31 14:17:00 mail amavis[7129]: Module Socket6 0.25
Dec 31 14:17:00 mail amavis[7129]: Module Time::HiRes 1.9726
Dec 31 14:17:00 mail amavis[7129]: Module URI 1.64
Dec 31 14:17:00 mail amavis[7129]: Module Unix::Syslog 1.1
Dec 31 14:17:00 mail amavis[7129]: Amavis::ZMQ code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: Amavis::DB code loaded
Dec 31 14:17:00 mail amavis[7129]: SQL base code loaded
Dec 31 14:17:00 mail amavis[7129]: SQL::Log code loaded
Dec 31 14:17:00 mail amavis[7129]: SQL::Quarantine loaded
Dec 31 14:17:00 mail amavis[7129]: Lookup::SQL code loaded
Dec 31 14:17:00 mail amavis[7129]: Lookup::LDAP code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: AM.PDP-in proto code loaded
Dec 31 14:17:00 mail amavis[7129]: SMTP-in proto code loaded
Dec 31 14:17:00 mail amavis[7129]: Courier proto code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: SMTP-out proto code loaded
Dec 31 14:17:00 mail amavis[7129]: Pipe-out proto code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: BSMTP-out proto code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: Local-out proto code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: OS_Fingerprint code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: ANTI-VIRUS code loaded
Dec 31 14:17:00 mail amavis[7129]: ANTI-SPAM code loaded
Dec 31 14:17:00 mail amavis[7129]: ANTI-SPAM-EXT code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: ANTI-SPAM-C code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: ANTI-SPAM-SA code loaded
Dec 31 14:17:00 mail amavis[7129]: Unpackers code loaded
Dec 31 14:17:00 mail amavis[7129]: DKIM code loaded
Dec 31 14:17:00 mail amavis[7129]: Tools code NOT loaded
Dec 31 14:17:00 mail amavis[7129]: Found $file at /usr/bin/file
Dec 31 14:17:00 mail amavis[7129]: Found $altermime at /usr/bin/altermime
Dec 31 14:17:00 mail amavis[7129]: Internal decoder for .mail
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .Z at /bin/uncompress
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .gz at /bin/gzip -d
Dec 31 14:17:00 mail amavis[7129]: Internal decoder for .gz (backup, not used)
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .bz2 at /bin/bzip2 -d
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .xz at /usr/bin/xz -dc
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .lzma at /usr/bin/xz -dc --format=lzma
Dec 31 14:17:00 mail amavis[7129]: No ext program for .lrz, tried: lrzip -q -k -d -o -, lrzcat -q -k
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .lzo at /usr/bin/lzop -d
Dec 31 14:17:00 mail amavis[7129]: No ext program for .lz4, tried: lz4c -d
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .rpm at /usr/bin/rpm2cpio
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .cpio at /bin/pax
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .tar at /bin/pax
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .deb at /usr/bin/ar
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .rar at /usr/bin/unrar-free
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .arj at /usr/bin/arj
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .arc at /usr/bin/nomarch
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .zoo at /usr/bin/zoo
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .doc at /usr/bin/ripole
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .cab at /usr/bin/cabextract
Dec 31 14:17:00 mail amavis[7129]: Internal decoder for .tnef
Dec 31 14:17:00 mail amavis[7129]: No ext program for .zip, tried: 7za, 7z
Dec 31 14:17:00 mail amavis[7129]: No ext program for .kmz, tried: 7za, 7z
Dec 31 14:17:00 mail amavis[7129]: Internal decoder for .zip
Dec 31 14:17:00 mail amavis[7129]: Internal decoder for .kmz
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .7z at /usr/bin/7zr
Dec 31 14:17:00 mail amavis[7129]: No ext program for .jar, tried: 7z
Dec 31 14:17:00 mail amavis[7129]: No ext program for .swf, tried: 7z
Dec 31 14:17:00 mail amavis[7129]: No ext program for .lha, tried: 7z
Dec 31 14:17:00 mail amavis[7129]: No ext program for .iso, tried: 7z
Dec 31 14:17:00 mail amavis[7129]: Found decoder for .exe at /usr/bin/unrar-free; /usr/bin/arj
Dec 31 14:17:00 mail amavis[7129]: No decoder for .F
Dec 31 14:17:00 mail amavis[7129]: No decoder for .iso
Dec 31 14:17:00 mail amavis[7129]: No decoder for .jar
Dec 31 14:17:00 mail amavis[7129]: No decoder for .lha
Dec 31 14:17:00 mail amavis[7129]: No decoder for .lrz
Dec 31 14:17:00 mail amavis[7129]: No decoder for .lz4
Dec 31 14:17:00 mail amavis[7129]: No decoder for .swf
Dec 31 14:17:00 mail amavis[7129]: Using primary internal av scanner code for ClamAV-clamd
Dec 31 14:17:00 mail amavis[7129]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
Dec 31 14:17:00 mail amavis[7129]: Using internal spam scanner code for SpamAssassin
Dec 31 14:17:00 mail amavis[7129]: Deleting db files nanny.db,__db.003,__db.002,snmp.db,__db.001 in /var/lib/amavis/db
Dec 31 14:17:00 mail amavis[7129]: Creating db in /var/lib/amavis/db/; BerkeleyDB 0.54, libdb 5.3
Dec 31 14:17:00 mail amavis[7129]: initializing Mail::SpamAssassin (0)
Dec 31 14:17:00 mail amavis[7129]: SpamAssassin debug facilities: info
Dec 31 14:17:01 mail amavis[7129]: SA info: zoom: able to use 347/347 'body_0' compiled rules (100%)
Dec 31 14:17:02 mail amavis[7129]: SpamAssassin loaded plugins: AskDNS, AutoLearnThreshold, Bayes, BodyEval, Check, DKIM, DNSEval, FreeMail, HTMLEval, HTTPSMismatch, Hashcash, HeaderEval, ImageInfo, MIMEEval, MIMEHeader, Pyzor, Razor2, RelayEval, ReplaceTags, Rule2XSBody, SPF, SpamCop, URIDNSBL, URIDetail, URIEval, VBounce, WLBLEval, WhiteListSubject
Dec 31 14:17:02 mail amavis[7129]: SpamControl: init_pre_fork on SpamAssassin done
Dec 31 14:17:02 mail amavis[7129]: extra modules loaded after daemonizing/chrooting: /etc/perl/Net/libnet.cfg, Mail/SpamAssassin/CompiledRegexps/body_0.pm, Mail/SpamAssassin/Plugin/FreeMail.pm, Mail/SpamAssassin/Plugin/SpamCop.pm, Net/Cmd.pm, Net/Config.pm, Net/SMTP.pm
Dec 31 14:17:02 mail amavis[7129]: Net::Server: Beginning prefork (4 processes)
Dec 31 14:17:02 mail amavis[7129]: Net::Server: Starting "4" children
Dec 31 14:17:02 mail amavis[7142]: Net::Server: Child Preforked (7142)
Dec 31 14:17:02 mail amavis[7142]: entered child_init_hook
Dec 31 14:17:02 mail amavis[7143]: Net::Server: Child Preforked (7143)
Dec 31 14:17:02 mail amavis[7143]: entered child_init_hook
Dec 31 14:17:02 mail amavis[7142]: storage and lookups will use the same connection to SQL
Dec 31 14:17:02 mail amavis[7144]: Net::Server: Child Preforked (7144)
Dec 31 14:17:02 mail amavis[7144]: entered child_init_hook
Dec 31 14:17:02 mail amavis[7143]: storage and lookups will use the same connection to SQL
Dec 31 14:17:02 mail amavis[7142]: SpamControl: init_child on SpamAssassin done
Dec 31 14:17:02 mail amavis[7143]: SpamControl: init_child on SpamAssassin done
Dec 31 14:17:02 mail amavis[7129]: Net::Server: Parent ready for children.
Dec 31 14:17:02 mail amavis[7145]: Net::Server: Child Preforked (7145)
Dec 31 14:17:02 mail amavis[7144]: storage and lookups will use the same connection to SQL
Dec 31 14:17:02 mail amavis[7145]: entered child_init_hook
Dec 31 14:17:02 mail amavis[7144]: SpamControl: init_child on SpamAssassin done
Dec 31 14:17:02 mail amavis[7145]: storage and lookups will use the same connection to SQL
Dec 31 14:17:02 mail amavis[7145]: SpamControl: init_child on SpamAssassin done
Dec 31 14:17:45 mail postfix/anvil[7030]: statistics: max connection rate 1/60s for (smtp:xx.xx.x.xxx) at Dec 31 14:14:23
Dec 31 14:17:45 mail postfix/anvil[7030]: statistics: max connection count 1 for (smtp:xx.xx.x.xxx) at Dec 31 14:14:23
Dec 31 14:17:45 mail postfix/anvil[7030]: statistics: max cache size 1 at Dec 31 14:14:23
Dec 31 14:18:27 mail postfix/smtpd[7157]: connect from unknown[172.25.12.1]
Dec 31 14:18:27 mail postfix/smtpd[7157]: C850D81C1C: client=unknown[172.25.12.1], sasl_method=PLAIN, sasl_username=testuser@localtest.com
Dec 31 14:18:27 mail postfix/cleanup[7164]: C850D81C1C: message-id=<5684EB22.60905@localtest.com>
Dec 31 14:18:27 mail postfix/qmgr[1609]: C850D81C1C: from=<testuser@localtest.com>, size=83768, nrcpt=1 (queue active)
Dec 31 14:18:27 mail postfix/smtpd[7157]: disconnect from unknown[172.25.12.1]
Dec 31 14:18:27 mail amavis[7142]: Net::Server: 2015/12/31-14:18:27 CONNECT TCP Peer: "[127.0.0.1]:45420" Local: "[127.0.0.1]:10024"
Dec 31 14:18:27 mail amavis[7142]: loaded base policy bank
Dec 31 14:18:27 mail amavis[7142]: lookup_ip_acl (inet_acl) arr.obj: key="127.0.0.1" matches "127.0.0.1", result=1
Dec 31 14:18:27 mail amavis[7142]: process_request: fileno sock=15, STDIN=0, STDOUT=1
Dec 31 14:18:27 mail amavis[7142]: get_deadline switch_to_my_time(new request) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: prolong_timer switch_to_my_time(new request): timer 288, was 0, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: process_request: suggested_protocol="" on a TCP socket
Dec 31 14:18:27 mail amavis[7142]: (07142-01) SMTP> 220 [127.0.0.1] ESMTP amavisd-new service ready
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 4: was busy, 6.6 ms, total idle 0.000 s, busy 0.007 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) smtp readline: read 30 bytes, new size: 30
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.000 s, busy 0.007 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) SMTP< EHLO mail.localtest.com\r\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP EHLO) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP EHLO): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-[127.0.0.1]
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-VRFY
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-PIPELINING
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-SIZE
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-ENHANCEDSTATUSCODES
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-8BITMIME
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-SMTPUTF8
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250-DSN
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250 XFORWARD NAME ADDR PORT PROTO HELO IDENT SOURCE
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 6: was busy, 1.1 ms, total idle 0.000 s, busy 0.008 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) smtp readline: read 247 bytes, new size: 247
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.000 s, busy 0.008 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP< XFORWARD ADDR=172.25.12.1 PORT=52752\r\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP XFORWARD) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP XFORWARD): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250 2.5.0 Ok XFORWARD
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 6: was busy, 1.3 ms, total idle 0.000 s, busy 0.009 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.3 ms, total idle 0.001 s, busy 0.009 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP< XFORWARD PROTO=ESMTP HELO=[192.168.60.149] IDENT=C850D81C1C SOURCE=REMOTE\r\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP XFORWARD) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP XFORWARD): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250 2.5.0 Ok XFORWARD
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 6: was busy, 3.7 ms, total idle 0.001 s, busy 0.013 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.013 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP< MAIL FROM:<testuser@localtest.com> SIZE=83768\r\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP MAIL) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP MAIL): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) check_mail_begin_task: task_count=1
Dec 31 14:18:27 mail amavis[7142]: (07142-01) TempDir::prepare_dir: created directory /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29
Dec 31 14:18:27 mail amavis[7142]: (07142-01) TempDir::prepare_file: creating file /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/email.txt
Dec 31 14:18:27 mail amavis[7142]: (07142-01) TempDir::prepare_file: layers: unix,perlio
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_ip_acl (client_ipaddr_policy) arr.obj: key="172.25.12.1" matches "172.16.0.0/12", result=1
Dec 31 14:18:27 mail amavis[7142]: (07142-01) loaded policy bank "MYNETS"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="1"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [local_domains] => true, "testuser@localtest.com" matches, result="1", matching_key="(constant:1)"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) query_keys: testuser@localtest.com, testuser, @localtest.com, @.localtest.com, @.com, @.
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql sel_policy "testuser@localtest.com", query args: [testuser@localtest.com,-3], [testuser,-3], [@localtest.com,-3], [@.localtest.com,-3], [@.com,-3], [@.,-3]
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql select: SELECT users.*, policy.*, users.id FROM users LEFT JOIN policy ON users.policy_id=policy.id WHERE users.email IN (?,?,?,?,?,?) ORDER BY users.priority DESC
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql begin, nontransaction
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Connecting to SQL database server
Dec 31 14:18:27 mail amavis[7142]: (07142-01) connect_to_sql: trying 'DBI:mysql:database=amavisd;host=127.0.0.1;port=3306'
Dec 31 14:18:27 mail amavis[7142]: (07142-01) connect_to_sql: 'DBI:mysql:database=amavisd;host=127.0.0.1;port=3306' succeeded
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql: preparing and executing (6 args): SELECT users.*, policy.*, users.id FROM users LEFT JOIN policy ON users.policy_id=policy.id WHERE users.email IN (?,?,?,?,?,?) ORDER BY users.priority DESC
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql, "testuser@localtest.com" no match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(local), "testuser@localtest.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="1"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [local_domains] => true, "testuser@localtest.com" matches, result="1", matching_key="(constant:1)"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) loaded policy bank "MYUSERS" over "MYNETS"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [debug_sender] => undef, "testuser@localtest.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) mesage size set to a declared size 83768
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250 2.1.0 Sender <testuser@localtest.com> OK
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 6: was busy, 12.0 ms, total idle 0.001 s, busy 0.025 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.025 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP< RCPT TO:<extuser@extdomain.com> ORCPT=rfc822;extuser@extdomain.com\r\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP RCPT) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP RCPT): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup => undef, "extuser@extdomain.com", no lookup tables
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="1"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [local_domains] => true, "extuser@extdomain.com" matches, result="1", matching_key="(constant:1)"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) query_keys: extuser@extdomain.com, extuser, @extdomain.com, @.extdomain.com, @.org, @.
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql sel_policy "extuser@extdomain.com", query args: [extuser@extdomain.com,-3], [extuser,-3], [@extdomain.com,-3], [@.extdomain.com,-3], [@.org,-3], [@.,-3]
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql select: SELECT users.*, policy.*, users.id FROM users LEFT JOIN policy ON users.policy_id=policy.id WHERE users.email IN (?,?,?,?,?,?) ORDER BY users.priority DESC
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql begin, nontransaction
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql: executing clause (6 args): SELECT users.*, policy.*, users.id FROM users LEFT JOIN policy ON users.policy_id=policy.id WHERE users.email IN (?,?,?,?,?,?) ORDER BY users.priority DESC
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql, "extuser@extdomain.com" no match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(message_size_limit), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [message_size_limit] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 250 2.1.5 Recipient <extuser@extdomain.com> OK
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 6: was busy, 3.3 ms, total idle 0.001 s, busy 0.028 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.2 ms, total idle 0.001 s, busy 0.028 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP< DATA\r\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP DATA) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP DATA): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP [127.0.0.1]:10024 /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29: <testuser@localtest.com> -> <extuser@extdomain.com> SIZE=83768 Received: from mail.localtest.com ([127.0.0.1]) by mail.localtest.com (mail.localtest.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <extuser@extdomain.com>; Thu, 31 Dec 2015 14:18:27 +0530 (IST)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:27 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, receiving data
Dec 31 14:18:27 mail amavis[7142]: (07142-01) smtp copy: read 43690 bytes into buffer, new size: 43690
Dec 31 14:18:27 mail amavis[7142]: (07142-01) smtp copy: read 40087 bytes into buffer, new size: 40087
Dec 31 14:18:27 mail amavis[7142]: (07142-01) smtp copy: 6 bytes still buffered at end
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx data-end) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx data-end): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ESMTP< .<CR><LF>
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline get_body_digest - deadline in 480.0 s, set to 30.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline digest_pre - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer digest_pre: timer 288, was 288, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) DNS resolver created, UDP payload size 1220, NS: 172.25.12.99
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_body_digest: reading header section from memory
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_body_digest: feeding header section to DKIM verifier
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_body_digest: sending h/b separator to DKIM
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline digest_hdr - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer digest_hdr: timer 288, was 288, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_body_digest: reading mail body from memory, 0 DKIM signatures
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline digest_body - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer digest_body: timer 288, was 288, deadline in 480.0 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_body_digest: message size 83768, header+sep 614, body 83154
Dec 31 14:18:27 mail amavis[7142]: (07142-01) body type (8bit-MIMEtransport): unlabeled, good (h=0, b=0)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) body hash: 53944c1228501aebe8f091940270bc1e
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ip_from_received: 172.25.12.1

**samtech** · 2015-12-31 17:06:17

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Part-2
(i had replaced Real IP addresses and emails with fake one)

Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_ip_acl (public_nets) arr.obj: key="127.0.0.1" matches "!127.0.0.0/8", result=0
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_ip_acl (public_nets) arr.obj: key="172.25.12.1" matches "!172.16.0.0/12", result=0
Dec 31 14:18:27 mail amavis[7142]: (07142-01) trace: ESMTP://[127.0.0.1]:45420 < ESMTPSA://[172.25.12.1]:52752
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Original mail size: 83768; quota set to: 41884000 bytes (fmin=5, fmax=500, qmin=102400, qmax=314572800)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql begin, nontransaction
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql: preparing and executing (2 args): SELECT id FROM maddr WHERE partition_tag=? AND email=?
Dec 31 14:18:27 mail amavis[7142]: (07142-01) save_info_preliminary VQrFXZ3q7qhB, sender id: 22, testuser@localtest.com, exists
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql begin, nontransaction
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql: executing clause (2 args): SELECT id FROM maddr WHERE partition_tag=? AND email=?
Dec 31 14:18:27 mail amavis[7142]: (07142-01) orcpt_encode rfc822, extuser@extdomain.com, smtputf8
Dec 31 14:18:27 mail amavis[7142]: (07142-01) save_info_preliminary VQrFXZ3q7qhB, recip id: 23, extuser@extdomain.com (ORCPT rfc822;extuser@extdomain.com), exists
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql begin transaction
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql: preparing and executing (11 args): INSERT INTO msgs (partition_tag, mail_id, secret_id, am_id, time_num, time_iso, sid, policy, client_addr, size, host) VALUES (?,?,?,?,?,?,?,?,?,?,?)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) sql commit
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Checking: VQrFXZ3q7qhB MYNETS/MYUSERS [172.25.12.1] <testuser@localtest.com> -> <extuser@extdomain.com>
Dec 31 14:18:27 mail amavis[7142]: (07142-01) 2822.From: <testuser@localtest.com>
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(local), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="1"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [local_domains] => true, "extuser@extdomain.com" matches, result="1", matching_key="(constant:1)"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(bypass_virus_checks), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [bypass_virus_checks] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(bypass_banned_checks), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [bypass_banned_checks] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(bypass_spam_checks), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [bypass_spam_checks] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(id), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [users.id] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(policy_id), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [users.policy_id] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Extracting mime components from a string
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Issued a new file name: p001
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Issued a new file name: p002
Dec 31 14:18:27 mail amavis[7142]: (07142-01) mime_decode_preamble: 1 lines
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Issued a new pseudo part: p003
Dec 31 14:18:27 mail amavis[7142]: (07142-01) p003 1 Content-Type: multipart/mixed
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Charging 23 bytes to remaining quota 41884000 (out of 41884000, (0%)) - by mime_decode
Dec 31 14:18:27 mail amavis[7142]: (07142-01) p001 1/1 Content-Type: text/plain, size: 23 B, name:
Dec 31 14:18:27 mail amavis[7142]: (07142-01) reparenting p001 from p000 to p003
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Charging 60325 bytes to remaining quota 41883977 (out of 41884000, (0%)) - by mime_decode
Dec 31 14:18:27 mail amavis[7142]: (07142-01) p002 1/2 Content-Type: application/pdf, size: 60325 B, name: Solution_Server_1A.pdf
Dec 31 14:18:27 mail amavis[7142]: (07142-01) reparenting p002 from p000 to p003
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline mime_decode - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer mime_decode: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline mime_decode-1 - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer mime_decode-1: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) decode_parts: level=1, #parts=3 : p001, p002, p003
Dec 31 14:18:27 mail amavis[7142]: (07142-01) running file(1) on 2 files, arglist size 23
Dec 31 14:18:27 mail amavis[7142]: (07142-01) run_command: [7172] /usr/bin/file p001 p002 </dev/null 2>&1
Dec 31 14:18:27 mail amavis[7172]: (07142-01) open_on_specific_fd: target fd0 closing, to become < /dev/null
Dec 31 14:18:27 mail amavis[7172]: (07142-01) open_on_specific_fd: target fd1 closing, to become (65) &=19
Dec 31 14:18:27 mail amavis[7172]: (07142-01) open_on_specific_fd: target fd1 dup2 from fd19 (65) &=19
Dec 31 14:18:27 mail amavis[7172]: (07142-01) open_on_specific_fd: source fd19 closed
Dec 31 14:18:27 mail amavis[7172]: (07142-01) open_on_specific_fd: target fd2 closing, to become (65) &1
Dec 31 14:18:27 mail amavis[7172]: (07142-01) open_on_specific_fd: target fd2 dup2 from fd1 (65) &1
Dec 31 14:18:27 mail amavis[7142]: (07142-01) result line from file(1): p001: ASCII text\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_re("ASCII text") matches key "(?^i:^(ASCII|text)\\b)", result="asc"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [map_full_type_to_short_type] => true, "ASCII text" matches, result="asc", matching_key="(?^i:^(ASCII|text)\\b)"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) File-type of p001: ASCII text; (asc)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) result line from file(1): p002: PDF document, version 1.5\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_re("PDF document, version 1.5") matches key "(?^:^PDF document\\b)", result="pdf"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [map_full_type_to_short_type] => true, "PDF document, version 1.5" matches, result="pdf", matching_key="(?^:^PDF document\\b)"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) File-type of p002: PDF document, version 1.5; (pdf)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) decompose_part: p001 - atomic
Dec 31 14:18:27 mail amavis[7142]: (07142-01) decompose_part: p002 - atomic
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline parts_decode - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer parts_decode: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(bypass_header_checks), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [bypass_header_checks] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) check_header: 0, OK
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(bypass_header_checks), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [bypass_header_checks] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Checking for banned types and filenames
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_sql_field(banned_rulenames), "extuser@extdomain.com" no matching records
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="DEFAULT"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [banned_filename], 1 matches for "extuser@extdomain.com", results: "(constant:DEFAULT)"=>"DEFAULT"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) collect banned table[0]: extuser@extdomain.com, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x23c3a70)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) starting banned checks - traversing message structure tree
Dec 31 14:18:27 mail amavis[7142]: (07142-01) check_for_banned (p003,p001) multipart/mixed | text/plain,.asc
Dec 31 14:18:27 mail amavis[7142]: (07142-01) doing banned check for extuser@extdomain.com on multipart/mixed | text/plain,.asc
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_re(["multipart/mixed","text/plain",".asc"]), no matches
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [check_bann:extuser@extdomain.com] => undef, ["multipart/mixed","text/plain",".asc"] does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [banned_namepath_re] => undef, "P=p003\tL=1\tM=multipart/mixed\nP=p001\tL=1/1\tM=text/plain\tT=asc" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) p.path extuser@extdomain.com: "P=p003,L=1,M=multipart/mixed | P=p001,L=1/1,M=text/plain,T=asc"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) check_for_banned (p003,p002) multipart/mixed | application/pdf,.pdf,Solution_Server_1A.pdf
Dec 31 14:18:27 mail amavis[7142]: (07142-01) doing banned check for extuser@extdomain.com on multipart/mixed | application/pdf,.pdf,Solution_Server_1A.pdf
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_re(["multipart/mixed","application/pdf",".pdf","Solution_Server_1A.pdf"]), no matches
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [check_bann:extuser@extdomain.com] => undef, ["multipart/mixed","application/pdf",".pdf","Solution_Server_1A.pdf"] does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [banned_namepath_re] => undef, "P=p003\tL=1\tM=multipart/mixed\nP=p002\tL=1/2\tM=application/pdf\tT=pdf\tN=Solution_Server_1A.pdf" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) p.path extuser@extdomain.com: "P=p003,L=1,M=multipart/mixed | P=p002,L=1/2,M=application/pdf,T=pdf,N=Solution_Server_1A.pdf"
Dec 31 14:18:27 mail amavis[7142]: (07142-01) banned check: any=0, all=N (1)
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup_re("MAIL"), no matches
Dec 31 14:18:27 mail amavis[7142]: (07142-01) lookup [keep_decoded_original] => undef, "MAIL" does not match
Dec 31 14:18:27 mail amavis[7142]: (07142-01) Calling virus scanners, 2 files to scan in /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts
Dec 31 14:18:27 mail amavis[7142]: (07142-01) invoking av-scanner ClamAV-clamd
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ask_daemon: proto=DFLT, spawn=0, (ClamAV-clamd) /var/run/clamav/clamd.ctl
Dec 31 14:18:27 mail amavis[7142]: (07142-01) run_av (ClamAV-clamd): query template(1,1): CONTSCAN {}\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline run_av_pre - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer run_av_pre: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline run_av_scan - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer run_av_scan: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) run_av Using (ClamAV-clamd): (code) CONTSCAN /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts\n
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline ask_daemon_internal_connect_pre - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) get_deadline ask_daemon_internal_connect - deadline in 479.9 s, set to 10.000 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) prolong_timer ask_daemon_internal_connect: timer 10, was 288, deadline in 479.9 s
Dec 31 14:18:27 mail amavis[7142]: (07142-01) ClamAV-clamd: Connecting to socket /var/run/clamav/clamd.ctl
Dec 31 14:18:27 mail amavis[7142]: (07142-01) new socket by IO::Socket::UNIX to /var/run/clamav/clamd.ctl, timeout set to 10
Dec 31 14:18:27 mail amavis[7142]: (07142-01) connected to /var/run/clamav/clamd.ctl successfully
Dec 31 14:18:28 mail amavis[7142]: (07142-01) ClamAV-clamd: Sending CONTSCAN /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts\n to socket /var/run/clamav/clamd.ctl
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=1, wr=1, timeout=10
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: sending 73 chars
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop sent 73> CONTSCAN /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline ask_daemon_internal_scan - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer ask_daemon_internal_scan: timer 288, was 10, deadline in 479.9 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=0, wr=0, timeout=287.998
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: receiving
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop read 68 chars< /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts: OK\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=0, wr=0, timeout=287.998
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: receiving
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop read: got eof
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline ask_daemon_internal - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer ask_daemon_internal: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline run_av_3 - deadline in 479.9 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer run_av_3: timer 288, was 288, deadline in 479.9 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) run_av (ClamAV-clamd) result: /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts: OK\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) run_av (ClamAV-clamd): CLEAN
Dec 31 14:18:28 mail amavis[7142]: (07142-01) run_av (ClamAV-clamd) result: clean
Dec 31 14:18:28 mail amavis[7142]: (07142-01) wbl: checking sender <testuser@localtest.com>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) wbl: (SQL) recip <extuser@extdomain.com>, 0 matches
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [blacklist_recip<extuser@extdomain.com>] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [blacklist_sender<testuser@localtest.com>,blacklist_sender] => undef, "testuser@localtest.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [whitelist_recip<extuser@extdomain.com>] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [whitelist_sender<testuser@localtest.com>,whitelist_sender] => undef, "testuser@localtest.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) query_keys: extuser@extdomain.com, extuser@, extdomain.com, .extdomain.com, .org, .
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_hash(extuser@extdomain.com) matches keys: "."=>ARRAY(0x23e1fa8)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [score_recip<extuser@extdomain.com>,score_sender], 1 matches for "extuser@extdomain.com", results: "."=>[Amavis::Lookup::RE=ARRAY(0x23f29e8),{}]
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_re("testuser@localtest.com"), no matches
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [score_sender<testuser@localtest.com>] => undef, "testuser@localtest.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) SpamControl: calling spam scanner SpamAssassin
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline spam_scan_sa_pre - deadline in 479.9 s, set to 476.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer spam_scan_sa_pre: timer 476, was 288, deadline in 479.9 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(sa_userconf), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [sa_userconf] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(sa_username), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [sa_username] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) SA user config: "", username: "amavis", 0, (0)extuser@extdomain.com
Dec 31 14:18:28 mail amavis[7142]: (07142-01) calling SA parse (0), SA vers 3.4.0, 3.004000, data as STRING_REF, recips_ind [0], user: "amavis"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline SA check - deadline in 479.9 s, set to 475.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) CALLING SA check (0)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) DONE SA check (0)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline spam_scan_sa - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer spam_scan_sa: timer 288, was 476, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) spam_scan: score=-1 autolearn=ham autolearn_force=no tests=[ALL_TRUSTED=-1] recips=0
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline spam_scan - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer spam_scan: timer 288, was 288, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(spam_tag_level), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="2"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_tag_level] => true, "extuser@extdomain.com" matches, result="2", matching_key="(constant:2)"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(spam_tag2_level), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="6.31"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_tag2_level] => true, "extuser@extdomain.com" matches, result="6.31", matching_key="(constant:6.31)"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(spam_tag3_level), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_tag3_level] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(spam_kill_level), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="6.31"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_kill_level] => true, "extuser@extdomain.com" matches, result="6.31", matching_key="(constant:6.31)"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(message_size_limit), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [message_size_limit] => undef, "extuser@extdomain.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) final_destiny (ccat=0) is PASS, recip extuser@extdomain.com
Dec 31 14:18:28 mail amavis[7142]: (07142-01) final_destiny PASS, recip extuser@extdomain.com
Dec 31 14:18:28 mail amavis[7142]: (07142-01) do_notify_and_quar: ccat=Clean (1,0) ("1":Clean, "0":CatchAll) ccat_block=(), qar_mth=
Dec 31 14:18:28 mail amavis[7142]: (07142-01) do_notify_and_quarantine: not quarantining, q_method off
Dec 31 14:18:28 mail amavis[7142]: (07142-01) skip admin notification, no administrators
Dec 31 14:18:28 mail amavis[7142]: (07142-01) do_notify_and_quarantine - done
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(forward_method), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup: (opaque) matches, result="smtp:[127.0.0.1]:10025"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [forward_method] => true, "extuser@extdomain.com" matches, result="smtp:[127.0.0.1]:10025", matching_key="(opaque:smtp:[127.0.0.1]:10025)"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) delivery method is 1, recips: extuser@extdomain.com
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(spam_tag_level), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="2"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_tag_level] => true, "extuser@extdomain.com" matches, result="2", matching_key="(constant:2)"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_sql_field(spam_tag2_level), "extuser@extdomain.com" no matching records
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup: (scalar) matches, result="6.31"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_tag2_level] => true, "extuser@extdomain.com" matches, result="6.31", matching_key="(constant:6.31)"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) headers CLUSTERING: NEW CLUSTER <extuser@extdomain.com>: score=-1, tag=0, tag2=0, local=1, bl=, s=, mangle=
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header encoded (all-ASCII): X-Virus-Scanned: Debian amavisd-new at mailserver
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header: X-Virus-Scanned: Debian amavisd-new at mailserver\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) fwd: scanner provided a header field X-Spam-Checker-Version, inhibited by %allowed_added_header_fields
Dec 31 14:18:28 mail amavis[7142]: (07142-01) fwd: scanner provided a header field X-Spam-Level, but we preferred our own
Dec 31 14:18:28 mail amavis[7142]: (07142-01) fwd: scanner provided a header field X-Spam-Status, but we preferred our own
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header encoded (all-ASCII): Received: from mail.mahendrasgroup.org ([127.0.0.1])\n by mail.mahendrasgroup.org (mail.mahendrasgroup.org [127.0.0.1]) (amavisd-new, port 10024)\n with ESMTP\n id VQrFXZ3q7qhB\n for <extuser@extdomain.com>;\n Thu, 31 Dec 2015 14:18:27 +0530 (IST)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header: Received: from mail.mahendrasgroup.org ([127.0.0.1])\n\tby mail.mahendrasgroup.org (mail.mahendrasgroup.org [127.0.0.1]) (amavisd-new, port 10024)\n\twith ESMTP id VQrFXZ3q7qhB for <extuser@extdomain.com>;\n\tThu, 31 Dec 2015 14:18:27 +0530 (IST)\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) headers CLUSTERING: done all 1 recips in one go
Dec 31 14:18:28 mail amavis[7142]: (07142-01) dkim: candidate originators: From:<testuser@localtest.com>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) query_keys: testuser@localtest.com, testuser@, localtest.com, .localtest.com, .com, .
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup_hash(testuser@localtest.com) matches keys: "localtest.com"=>HASH(0x24b0130), "."=>HASH(0x24affc8)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [dkim_signature_options_bysender], 2 matches for "testuser@localtest.com", results: "localtest.com"=>{d=>"mahendrasgroup.org",ttl=>"864000",a=>"rsa-sha256"}, "."=>{a=>"rsa-sha256",c=>"relaxed/simple",ttl=>"2592000"}
Dec 31 14:18:28 mail amavis[7142]: (07142-01) dkim: signature options for testuser@localtest.com(From): a=rsa-sha256; c=relaxed/simple; ttl=864000; d=mahendrasgroup.org
Dec 31 14:18:28 mail amavis[7142]: (07142-01) dkim: signing (3rd-party), From: <testuser@localtest.com> (From:<testuser@localtest.com>), KEY.key_ind=>0, a=>rsa-sha256, c=>relaxed/simple, d=>mahendrasgroup.org, s=>dkim, ttl=>864000, x=>1452415708
Dec 31 14:18:28 mail amavis[7142]: (07142-01) write_header: 1, Amavis::Out::SMTP=HASH(0x7e659e0)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header encoded (all-ASCII): DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=\n\tmahendrasgroup.org; h=content-type:content-type:mime-version\n\t:user-agent:date:date:message-id:subject:subject:from:from:to;\n\t s=dkim; t=1451551707; x=1452415708; bh=GcvVHRglqftu3xK0lQwBxBZV\n\tpluLmIIteFPYD0bZnps=; b=Rhxs2KoOWyrYK1zCEV86KeQPvek2Lth6iVlihr5s\n\tO0vijSqFkPzs8a3F7PiZCli6HPtU2TNbjQbo5wbxg6HdIEW6enP+4nNe3IoMqlgo\n\t4Wy+65L+Tjx44AnLxRtC5QusDgkDnfzt6wGcGu/WlnLU7JwMrERpHAfBtKeMQoEL\n\tPXfYDXaHudkepIUU3opV0f0QnDOWw12bCq+X4I9pmK8v9vCR9Q5FTegDgS1LkWWZ\n\ti3Ad+aY4mwR1kqOD4HR0SZT0xAOSIOP3flK/08QGE3Vpe72cZ4K2zZoqtt+EkroF\n\tjdXpaS1XSYFDGkNvUokgU72ES/8GWlD51s7gYOV1XATRkw==
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header: DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simple; d=\n\tmahendrasgroup.org; h=content-type:content-type:mime-version\n\t:user-agent:date:date:message-id:subject:subject:from:from:to;\n\t s=dkim; t=1451551707; x=1452415708; bh=GcvVHRglqftu3xK0lQwBxBZV\n\tpluLmIIteFPYD0bZnps=; b=Rhxs2KoOWyrYK1zCEV86KeQPvek2Lth6iVlihr5s\n\tO0vijSqFkPzs8a3F7PiZCli6HPtU2TNbjQbo5wbxg6HdIEW6enP+4nNe3IoMqlgo\n\t4Wy+65L+Tjx44AnLxRtC5QusDgkDnfzt6wGcGu/WlnLU7JwMrERpHAfBtKeMQoEL\n\tPXfYDXaHudkepIUU3opV0f0QnDOWw12bCq+X4I9pmK8v9vCR9Q5FTegDgS1LkWWZ\n\ti3Ad+aY4mwR1kqOD4HR0SZT0xAOSIOP3flK/08QGE3Vpe72cZ4K2zZoqtt+EkroF\n\tjdXpaS1XSYFDGkNvUokgU72ES/8GWlD51s7gYOV1XATRkw==\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header encoded (all-ASCII): Authentication-Results: mail.mahendrasgroup.org (amavisd-new);\n dkim=pass\n reason="pass (just generated, assumed good)"\n header.d=mahendrasgroup.org
Dec 31 14:18:28 mail amavis[7142]: (07142-01) header: Authentication-Results: mail.mahendrasgroup.org (amavisd-new); dkim=pass\n\treason="pass (just generated, assumed good)"\n\theader.d=mahendrasgroup.org\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) about to connect to smtp:[127.0.0.1]:10025, VQrFXZ3q7qhB FWD from <testuser@localtest.com> -> <extuser@extdomain.com>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline fwd_init - deadline in 479.7 s, set to 480.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp session: setting up a new session
Dec 31 14:18:28 mail amavis[7142]: (07142-01) establish_or_refresh, state: down
Dec 31 14:18:28 mail amavis[7142]: (07142-01) new socket using IO::Socket::IP to [127.0.0.1]:10025, timeout 35
Dec 31 14:18:28 mail amavis[7142]: (07142-01) connected to [127.0.0.1]:10025 successfully
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=1, flush=0, wr=0, timeout=35
Dec 31 14:18:28 mail postfix/smtpd[7175]: connect from mail.mahendrasgroup.org[127.0.0.1]
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: receiving
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop read 56 chars< 220 mail.mahendrasgroup.org ESMTP Postfix (Debian/GNU)\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp greeting: 220 mail.mahendrasgroup.org ESMTP Postfix (Debian/GNU), dt: 9.0 ms
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp cmd> EHLO mail.mahendrasgroup.org
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=1, wr=1, timeout=300
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: sending 30 chars
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop sent 30> EHLO mail.mahendrasgroup.org\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=1, flush=0, wr=0, timeout=300
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: receiving
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop read 121 chars< 250-mail.mahendrasgroup.org\r\n250-PIPELINING\r\n250-SIZE 1048576\r\n250-ETRN\r\n250-ENHANCEDSTATUSCODES\r\n250-8BITMIME\r\n250 DSN\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp resp to EHLO: 250 mail.mahendrasgroup.org\nPIPELINING\nSIZE 1048576\nETRN\nENHANCEDSTATUSCODES\n8BITMIME\nDSN
Dec 31 14:18:28 mail amavis[7142]: (07142-01) tls active=0, capable=, sec_level=0
Dec 31 14:18:28 mail amavis[7142]: (07142-01) Remote host presents itself as: mail.mahendrasgroup.org, handles DSN, PIPELINING, 8BITMIME
Dec 31 14:18:28 mail amavis[7142]: (07142-01) AUTH not needed, user='', MTA offers ''
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp cmd> MAIL FROM:<testuser@localtest.com> BODY=7BIT
Dec 31 14:18:28 mail amavis[7142]: (07142-01) orcpt_encode rfc822, extuser@extdomain.com, encode_for_smtp
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp cmd> RCPT TO:<extuser@extdomain.com> ORCPT=rfc822;extuser@extdomain.com
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp cmd> DATA
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=1, wr=1, timeout=120
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: sending 133 chars
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop sent 133> MAIL FROM:<testuser@localtest.com> BODY=7BIT\r\nRCPT TO:<extuser@extdomain.com> ORCPT=rfc822;extuser@extdomain.com\r\nDATA\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=1, flush=0, wr=0, timeout=300
Dec 31 14:18:28 mail postfix/smtpd[7175]: 36D4081FD8: client=mail.mahendrasgroup.org[127.0.0.1]
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: receiving
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop read 65 chars< 250 2.1.0 Ok\r\n250 2.1.5 Ok\r\n354 End data with <CR><LF>.<CR><LF>\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp resp to MAIL (pip): 250 2.1.0 Ok
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp resp to RCPT (pip) (<extuser@extdomain.com>): 250 2.1.5 Ok
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp resp to DATA: 354 End data with <CR><LF>.<CR><LF>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) write_header: 1, Amavis::Out::SMTP::Protocol=HASH(0x663e8b0)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=1, wr=1, timeout=180
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: sending 51537 chars
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop sent 51537> Authentication-Results: mail.mahendrasgroup.org (amavisd-new); dkim=pass\r\n\treason="pass (just generated, assumed good)"\r\n\theader.d=mahendrasgroup.org\r\nDKIM-Signature: v=1; a=rsa-sha256; c=relaxed/simp [...]
Dec 31 14:18:28 mail postfix/cleanup[7164]: 36D4081FD8: message-id=<5684EB22.60905@localtest.com>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp connection_cache disabled, sending QUIT
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp cmd> QUIT
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=0, flush=1, wr=1, timeout=479.981
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: sending 33328 chars
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop sent 33328> 1oPfo9eIBeFIP0AaUycT056iG6hgzTW+B6p\r\nNnqazlIS9aVSo1F9d66RF9LT7Pk1ayp9JL8/JpyaQz8N49iZu2pVfCclo5U8epTa0n602NkI\r\nwPtmYRFO1Mqjd7RJ5iSjq/ED1+tvGcX0FDvFIX0jvUtnuINOjXcZS4xVxmpqSec0S8Meo5sx\r\nE7XGUiHNpdsxggp [...]
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: needline=1, flush=0, wr=0, timeout=479.981
Dec 31 14:18:28 mail postfix/smtpd[7175]: disconnect from mail.mahendrasgroup.org[127.0.0.1]
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop: receiving
Dec 31 14:18:28 mail postfix/qmgr[1609]: 36D4081FD8: from=<testuser@localtest.com>, size=85070, nrcpt=1 (queue active)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rw_loop read 51 chars< 250 2.0.0 Ok: queued as 36D4081FD8\r\n221 2.0.0 Bye\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) smtp resp to data-dot (<extuser@extdomain.com>): 250 2.0.0 Ok: queued as 36D4081FD8, dt: 24.3 ms
Dec 31 14:18:28 mail amavis[7142]: (07142-01) Amavis::Out::SMTP::Session close, disconnecting
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline fwd-end-chkpnt - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer fwd-end-chkpnt: timer 288, was 0, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) VQrFXZ3q7qhB FWD from <testuser@localtest.com> -> <extuser@extdomain.com>, BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 36D4081FD8
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline forwarding - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer forwarding: timer 288, was 288, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) DSN: sender is credible (orig), SA: -1.000, <testuser@localtest.com>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) lookup [spam_crediblefrom_dsn_cutoff_level_bysender] => undef, "testuser@localtest.com" does not match
Dec 31 14:18:28 mail amavis[7142]: (07142-01) dsn: from MTA 250 NonBlocking:Clean <testuser@localtest.com> -> <extuser@extdomain.com>: 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 36D4081FD8"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) DSN: SUCC from MTA 250 NonBlocking:Clean, no DSN requested: <testuser@localtest.com> -> <extuser@extdomain.com>
Dec 31 14:18:28 mail amavis[7142]: (07142-01) delivery_status_notification: notif 0 bytes, suppressed: no
Dec 31 14:18:28 mail amavis[7142]: (07142-01) one_response_for_all, per_recip_capable: N, suppressed: N
Dec 31 14:18:28 mail amavis[7142]: (07142-01) one_response_for_all <testuser@localtest.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 36D4081FD8'
Dec 31 14:18:28 mail amavis[7142]: (07142-01) 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 36D4081FD8
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline delivery-notification - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer delivery-notification: timer 288, was 288, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) status counters: InMsgsStatus{Relayed,RelayedUntagged,RelayedUntaggedInternal,RelayedUntaggedOriginating}
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline snmp-counters - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer snmp-counters: timer 288, was 288, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) orcpt_encode rfc822, extuser@extdomain.com, smtputf8
Dec 31 14:18:28 mail amavis[7142]: (07142-01) Passed CLEAN {RelayedInternal}, MYNETS/MYUSERS LOCAL [172.25.12.1]:52752 <testuser@localtest.com> -> <extuser@extdomain.com>, Queue-ID: C850D81C1C, Message-ID: <5684EB22.60905@localtest.com>, mail_id: VQrFXZ3q7qhB, Hits: -1, size: 83768, queued_as: 36D4081FD8, dkim_new=dkim:mahendrasgroup.org, 397 ms
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline main_log_entry - deadline in 479.7 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer main_log_entry: timer 288, was 288, deadline in 479.7 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) sql begin transaction
Dec 31 14:18:28 mail amavis[7142]: (07142-01) sql: preparing and executing (12 args): INSERT INTO msgrcpt (partition_tag, mail_id, rseqnum, rid, is_local, content, ds, rs, bl, wl, bspam_level, smtp_resp) VALUES (?,?,?,?,?,?,?,?,?,?,?,?)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) save_info_final VQrFXZ3q7qhB, orig=Y, chks=VSHB, cont.ty=C, q.type= , q.to=, dsn=N, score=-1, Message-ID: <5684EB22.60905@localtest.com>, From: '"Test.User" <testuser@localtest.com>', Subject: 'Attachement test - 4'
Dec 31 14:18:28 mail amavis[7142]: (07142-01) sql: preparing and executing (12 args): UPDATE msgs SET content=?, quar_type=?, quar_loc=?, dsn_sent=?, spam_level=?, message_id=?, from_addr=?, subject=?, client_addr=?, originating=? WHERE partition_tag=? AND mail_id=?
Dec 31 14:18:28 mail amavis[7142]: (07142-01) sql commit
Dec 31 14:18:28 mail amavis[7142]: (07142-01) TIMING-SA total 135 ms - parse: 4.2 (3.1%), extract_message_metadata: 7 (4.8%), get_uri_detail_list: 0.25 (0.2%), tests_pri_-1000: 5.0 (3.7%), tests_pri_-950: 1.71 (1.3%), tests_pri_-900: 1.32 (1.0%), tests_pri_-400: 0.98 (0.7%), tests_pri_0: 43 (31.9%), check_spf: 0.40 (0.3%), check_dkim_adsp: 5 (4.0%), check_pyzor: 0.20 (0.1%), tests_pri_500: 3.6 (2.7%), learn: 50 (36.9%), b_learn: 47 (35.2%), b_count_change: 4.9 (3.6%), get_report: 0.36 (0.3%)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) updating snmp variables in BDB
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline check done - deadline in 479.6 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer check done: timer 288, was 288, deadline in 479.6 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) sending SMTP response: "250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 36D4081FD8"
Dec 31 14:18:28 mail amavis[7142]: (07142-01) ESMTP> 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 36D4081FD8
Dec 31 14:18:28 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:28 mail postfix/smtp[7169]: C850D81C1C: to=<extuser@extdomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.49, delays=0.05/0.01/0.01/0.41, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 36D4081FD8)
Dec 31 14:18:28 mail amavis[7142]: (07142-01) TempDir::strip: /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29
Dec 31 14:18:28 mail amavis[7142]: (07142-01) rmdir_recursively: /var/lib/amavis/tmp/amavis-20151231T141827-07142-iiGrcg29/parts, excl=1
Dec 31 14:18:28 mail postfix/qmgr[1609]: C850D81C1C: removed
Dec 31 14:18:28 mail amavis[7142]: (07142-01) size: 83768, TIMING [total 423 ms] - sql-prepare: 4.1 (1%)1, SMTP greeting: 2.4 (1%)2, SMTP EHLO: 1.4 (0%)2, SMTP pre-MAIL: 6 (1%)3, mkdir tempdir: 1.3 (0%)4, create email.txt: 0.3 (0%)4, sql-connect: 8 (2%)6, lookup_sql: 1.0 (0%)6, lookup_sql: 3.6 (1%)7, SMTP pre-DATA-flush: 3.1 (1%)7, SMTP DATA: 17 (4%)11, check_init: 0.6 (0%)11, digest_hdr: 2.2 (1%)12, digest_body_dkim: 1.1 (0%)12, collect_info: 2.1 (0%)13, gen_mail_id: 34 (8%)21, mkdir parts: 2.5 (1%)21, mime_decode: 16 (4%)25, get-file-type2: 19 (4%)30, parts_decode: 0.5 (0%)30, check_header: 1.2 (0%)30, AV-scan-1: 41 (10%)40, spam-wb-list: 1.9 (0%)40, SA msg read: 0.9 (0%)41, SA parse: 6 (1%)42, SA check: 129 (30%)72, decide_mail_destiny: 5 (1%)74, notif-quar: 0.4 (0%)74, write-header: 12 (3%)77, fwd-data-dkim: 16 (4%)80, fwd-connect: 16 (4%)84, fwd-mail-pip: 6 (1%)85, fwd-rcpt-pip: 0.2 (0%)86, fwd-data-chkpnt: 0.1 (0%)86, write-header: 0.5 (0%)86, fwd-data-contents: 1.2 (0%)86, fwd-end-chkpnt: 2...
Dec 31 14:18:28 mail amavis[7142]: (07142-01) ...5 (6%)92, prepare-dsn: 1.4 (0%)92, report: 2.0 (0%)93, main_log_entry: 5 (1%)94, sql-update: 20 (5%)99, update_snmp: 2.9 (1%)100, SMTP pre-response: 0.5 (0%)100, SMTP response: 0.3 (0%)100, unlink-2-files: 0.3 (0%)100, rundown: 0.8 (0%)100
Dec 31 14:18:28 mail amavis[7142]: (07142-01) idle_proc, 6: was busy, 394.3 ms, total idle 0.001 s, busy 0.422 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) idle_proc, 5: was idle, 0.1 ms, total idle 0.001 s, busy 0.422 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) ESMTP< QUIT\r\n
Dec 31 14:18:28 mail amavis[7142]: (07142-01) get_deadline switch_to_my_time(rx SMTP QUIT) - deadline in 480.0 s, set to 288.000 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) prolong_timer switch_to_my_time(rx SMTP QUIT): timer 288, was 480, deadline in 480.0 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) ESMTP> 221 2.0.0 [127.0.0.1] amavisd-new closing transmission channel
Dec 31 14:18:28 mail amavis[7142]: (07142-01) switch_to_client_time 480 s, smtp response sent
Dec 31 14:18:28 mail amavis[7142]: (07142-01) SMTP session over, timer stopped
Dec 31 14:18:28 mail amavis[7142]: (07142-01) exiting process_request
Dec 31 14:18:28 mail amavis[7142]: (07142-01) idle_proc, bye: was busy, 2.3 ms, total idle 0.001 s, busy 0.425 s
Dec 31 14:18:28 mail amavis[7142]: (07142-01) load: 100 %, total idle 0.001 s, busy 0.425 s
Dec 31 14:18:37 mail postfix/smtp[7176]: 36D4081FD8: to=<extuser@extdomain.com>, relay=mail.extdomain.com[xx.xx.x.xx]:25, delay=9.7, delays=0.03/0.01/2/7.7, dsn=2.0.0, status=sent (250 OK)
Dec 31 14:18:38 mail postfix/qmgr[1609]: 36D4081FD8: removed

**ZhangHuangbin** · 2015-12-31 17:26:20

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

Your Amavisd doesn't load policy bank "ORIGINATING" for this testing email. It loads MYUSERS instead. This policy bank was retired in iRedMail-0.9.3.

You didn't upgrade to iRedMail-0.9.3 which fixes an issue in amavisd:
http://www.iredmail.org/docs/upgrade.ir … ernal-user

Please apply fix in above link first, then try again. Also, please upgrade to iRedMail-0.9.3 as soon as possible.

**samtech** · 2015-12-31 17:51:27

samtech
Member
Offline

Registered: 2015-12-21
Posts: 34

Re: [SOLVED] Block attachment for only outbound emails

Thanks a lot Zhang, after applying patch it is working beautifully.
And sure, i will upgrade it to 0.9.3.

Thanks again!

**ZhangHuangbin** · 2015-12-31 18:23:36

ZhangHuangbin
iRedMail Developers
Offline

Registered: 2009-05-06
Posts: 31,094

Re: [SOLVED] Block attachment for only outbound emails

[thread closed]

Aug 5, 2024: iRedMail-1.7.1 has been released.

[ Closed ] [SOLVED] Block attachment for only outbound emails

Posts: 17

1 Topic by samtech 2015-12-26 16:58:51

Topic: [SOLVED] Block attachment for only outbound emails

2 Reply by ZhangHuangbin 2015-12-26 18:14:35

Re: [SOLVED] Block attachment for only outbound emails

3 Reply by samtech 2015-12-26 19:18:34 (edited by samtech 2015-12-26 19:31:24)

Re: [SOLVED] Block attachment for only outbound emails

4 Reply by samtech 2015-12-28 17:36:15

Re: [SOLVED] Block attachment for only outbound emails

5 Reply by ZhangHuangbin 2015-12-29 01:07:58

Re: [SOLVED] Block attachment for only outbound emails

6 Reply by samtech 2015-12-29 21:52:21 (edited by samtech 2015-12-29 21:53:56)

Re: [SOLVED] Block attachment for only outbound emails

7 Reply by ZhangHuangbin 2015-12-29 22:49:36

Re: [SOLVED] Block attachment for only outbound emails

8 Reply by samtech 2015-12-30 20:28:20 (edited by samtech 2015-12-30 21:06:36)

Re: [SOLVED] Block attachment for only outbound emails

9 Reply by ZhangHuangbin 2015-12-30 23:48:19

Re: [SOLVED] Block attachment for only outbound emails

10 Reply by ZhangHuangbin 2015-12-31 00:18:54

Re: [SOLVED] Block attachment for only outbound emails

11 Reply by samtech 2015-12-31 14:01:36

Re: [SOLVED] Block attachment for only outbound emails

12 Reply by ZhangHuangbin 2015-12-31 16:42:15

Re: [SOLVED] Block attachment for only outbound emails

13 Reply by samtech 2015-12-31 17:04:33 (edited by samtech 2015-12-31 17:08:11)

Re: [SOLVED] Block attachment for only outbound emails

14 Reply by samtech 2015-12-31 17:06:17 (edited by samtech 2015-12-31 17:09:19)

Re: [SOLVED] Block attachment for only outbound emails

15 Reply by ZhangHuangbin 2015-12-31 17:26:20

Re: [SOLVED] Block attachment for only outbound emails

16 Reply by samtech 2015-12-31 17:51:27

Re: [SOLVED] Block attachment for only outbound emails

17 Reply by ZhangHuangbin 2015-12-31 18:23:36

Re: [SOLVED] Block attachment for only outbound emails

Posts: 17