On 11/08/2013 01:53 PM, Steffen Wegner wrote: > Thanks Nigel, > again you were right about the comment in cluebringer.conf. After removing it > from /etc/cluebringer.conf, Amavis seems to be enabled now (at least it looks > like it). > Yet the 'BCC all mails' rule still is not working. I created new logs and > then restarted the server and send an outgoing mail again. > Please find enclosed the related logs. > > My Amavis related rules in cluebringer look like this: > ======================================= > "Amavis Rule"(Disabled:no): > --------------------------------------------------- > Link to policy: "Default" > BCC To: "anymail at any.de" > --------------------------------------------------- > > Policy "Default" ( Priority:0; Disabled:no) > > Policy "Default" - Members (Disabled:no): > --------------------------------------------------- > Source: any > Destination: any > --------------------------------------------------- > > Following the logic of this rule set I should receive a BCC-mail for every > out- and incomming mail. > But it is not working. All other policyd rules (e.g. quotas) are working. > > > logfiles after rebooting the server and sending an outgoing mail: > ============================================= > --------------/var/log/cbpolicyd/cbpolicyd.log---on > log_level=4---------------------- > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: Process Backgrounded > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] NOTICE: Policyd v2 / Cluebringer - > v2.1.x-201310261831 > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] NOTICE: Initializing system modules. > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] NOTICE: System modules initialized. > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] NOTICE: Module load started... > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => AccessControl: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => Accounting: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => Amavis: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => CheckHelo: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => CheckSPF: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => Greylisting: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => Quotas: enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => Protocol(Postfix): enabled > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: => Protocol(Bizanga): enabled > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] NOTICE: Module load done. > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] NOTICE: Session tracking is ENABLED. > [2013/11/08-14:07:30 - 1864] [CBPOLICYD] DEBUG: Opening syslog, destination = > 'native', facility = 'ARRAY(0x16c3f68)'. > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: 2013/11/08-14:07:30 cbp (type > Net::Server::PreFork) starting! pid(1864) > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: Resolved [*]:10031 to [::]:10031, > IPv6 > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: Not including resolved host > [0.0.0.0] IPv4 because it will be handled by [::] IPv6 > [2013/11/08-14:07:30 - 1864] [CORE] NOTICE: Binding to TCP port 10031 on host > :: with IPv6 > [2013/11/08-14:07:30 - 1864] [CORE] INFO: Setting up serialization via flock > [2013/11/08-14:07:30 - 1864] [CORE] INFO: Beginning prefork (4 processes) > [2013/11/08-14:07:30 - 1864] [CORE] INFO: Starting "4" children > [2013/11/08-14:07:30 - 1864] [CORE] DEBUG: Parent ready for children. > [2013/11/08-14:07:30 - 1909] [CORE] DEBUG: Child Preforked (1909) > [2013/11/08-14:07:30 - 1909] [CBPOLICYD] DEBUG: Starting up caching engine > [2013/11/08-14:07:30 - 1910] [CORE] DEBUG: Child Preforked (1910) > [2013/11/08-14:07:30 - 1910] [CBPOLICYD] DEBUG: Starting up caching engine > [2013/11/08-14:07:30 - 1911] [CORE] DEBUG: Child Preforked (1911) > [2013/11/08-14:07:30 - 1911] [CBPOLICYD] DEBUG: Starting up caching engine > [2013/11/08-14:07:30 - 1912] [CORE] DEBUG: Child Preforked (1912) > [2013/11/08-14:07:30 - 1912] [CBPOLICYD] DEBUG: Starting up caching engine > [2013/11/08-14:09:57 - 1864] [CORE] INFO: Starting "1" children > [2013/11/08-14:09:57 - 1910] [CORE] INFO: 2013/11/08-14:09:57 CONNECT TCP > Peer: "[::ffff:127.0.0.1]:33194" Local: "[::ffff:127.0.0.1]:10031" > [2013/11/08-14:09:57 - 2516] [CORE] DEBUG: Child Preforked (2516) > [2013/11/08-14:09:57 - 2516] [CBPOLICYD] DEBUG: Starting up caching engine > [2013/11/08-14:09:57 - 1910] [CBPOLICYD] INFO: Got request #1 > [2013/11/08-14:09:57 - 1910] [CBPOLICYD] INFO: Got request #2 (pipelined) > [2013/11/08-14:09:59 - 1912] [CORE] INFO: 2013/11/08-14:09:59 CONNECT TCP > Peer: "[::ffff:127.0.0.1]:33209" Local: "[::ffff:127.0.0.1]:10031" > [2013/11/08-14:09:59 - 1864] [CORE] INFO: Starting "1" children > [2013/11/08-14:09:59 - 2543] [CORE] DEBUG: Child Preforked (2543) > [2013/11/08-14:09:59 - 2543] [CBPOLICYD] DEBUG: Starting up caching engine > [2013/11/08-14:09:59 - 1912] [CBPOLICYD] INFO: Got request #1
Please enable full debugging. Please paste your main.cf configuration too where check_policy_service is used. > > -------------/var/log/mail.log--------------------------------------------------------- > Nov 8 14:07:04 server1 dovecot: master: Dovecot v2.0.19 starting up (core > dumps disabled) > Nov 8 14:07:06 server1 amavis[1009]: starting. /usr/sbin/amavisd-new at > server1.anydomain.de amavisd-new-2.6.5 (20110407), Unicode aware > Nov 8 14:07:06 server1 amavis[1009]: Perl version 5.014002 > Nov 8 14:07:07 server1 amavis[1309]: Net::Server: Group Not Defined. > Defaulting to EGID '122 122' > Nov 8 14:07:07 server1 amavis[1309]: Net::Server: User Not Defined. > Defaulting to EUID '114' > Nov 8 14:07:07 server1 amavis[1309]: Module Amavis::Conf 2.208 > Nov 8 14:07:07 server1 amavis[1309]: Module Archive::Zip 1.30 > Nov 8 14:07:07 server1 amavis[1309]: Module BerkeleyDB 0.49 > Nov 8 14:07:07 server1 amavis[1309]: Module Compress::Zlib 2.033 > Nov 8 14:07:07 server1 amavis[1309]: Module Convert::TNEF 0.17 > Nov 8 14:07:07 server1 amavis[1309]: Module Convert::UUlib 1.4 > Nov 8 14:07:07 server1 amavis[1309]: Module Crypt::OpenSSL::RSA 0.27 > Nov 8 14:07:07 server1 amavis[1309]: Module DBD::mysql 4.020 > Nov 8 14:07:07 server1 amavis[1309]: Module DBI 1.616 > Nov 8 14:07:07 server1 amavis[1309]: Module DB_File 1.821 > Nov 8 14:07:07 server1 amavis[1309]: Module Digest::MD5 2.51 > Nov 8 14:07:07 server1 amavis[1309]: Module Digest::SHA 5.61 > Nov 8 14:07:07 server1 amavis[1309]: Module IO::Socket::INET6 2.69 > Nov 8 14:07:07 server1 amavis[1309]: Module MIME::Entity 5.502 > Nov 8 14:07:07 server1 amavis[1309]: Module MIME::Parser 5.502 > Nov 8 14:07:07 server1 amavis[1309]: Module MIME::Tools 5.502 > Nov 8 14:07:07 server1 amavis[1309]: Module Mail::DKIM::Signer 0.39 > Nov 8 14:07:07 server1 amavis[1309]: Module Mail::DKIM::Verifier 0.39 > Nov 8 14:07:07 server1 amavis[1309]: Module Mail::Header 2.08 > Nov 8 14:07:07 server1 amavis[1309]: Module Mail::Internet 2.08 > Nov 8 14:07:07 server1 amavis[1309]: Module Mail::SPF v2.009 > Nov 8 14:07:07 server1 amavis[1309]: Module Mail::SpamAssassin 3.003002 > Nov 8 14:07:07 server1 amavis[1309]: Module Net::DNS 0.66 > Nov 8 14:07:07 server1 amavis[1309]: Module Net::Server 2.007 > Nov 8 14:07:07 server1 amavis[1309]: Module NetAddr::IP 4.058 > Nov 8 14:07:07 server1 amavis[1309]: Module Razor2::Client::Version 2.84 > Nov 8 14:07:07 server1 amavis[1309]: Module Socket6 0.23 > Nov 8 14:07:07 server1 amavis[1309]: Module Time::HiRes 1.972101 > Nov 8 14:07:07 server1 amavis[1309]: Module URI 1.59 > Nov 8 14:07:07 server1 amavis[1309]: Module Unix::Syslog 1.1 > Nov 8 14:07:07 server1 amavis[1309]: Amavis::DB code loaded > Nov 8 14:07:07 server1 amavis[1309]: Amavis::Cache code loaded > Nov 8 14:07:07 server1 amavis[1309]: SQL base code loaded > Nov 8 14:07:07 server1 amavis[1309]: SQL::Log code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: SQL::Quarantine NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: Lookup::SQL code loaded > Nov 8 14:07:07 server1 amavis[1309]: Lookup::LDAP code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: AM.PDP-in proto code loaded > Nov 8 14:07:07 server1 amavis[1309]: SMTP-in proto code loaded > Nov 8 14:07:07 server1 amavis[1309]: Courier proto code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: SMTP-out proto code loaded > Nov 8 14:07:07 server1 amavis[1309]: Pipe-out proto code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: BSMTP-out proto code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: Local-out proto code loaded > Nov 8 14:07:07 server1 amavis[1309]: OS_Fingerprint code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: ANTI-VIRUS code loaded > Nov 8 14:07:07 server1 amavis[1309]: ANTI-SPAM code loaded > Nov 8 14:07:07 server1 amavis[1309]: ANTI-SPAM-EXT code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: ANTI-SPAM-C code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: ANTI-SPAM-SA code loaded > Nov 8 14:07:07 server1 amavis[1309]: Unpackers code loaded > Nov 8 14:07:07 server1 amavis[1309]: DKIM code loaded > Nov 8 14:07:07 server1 amavis[1309]: Tools code NOT loaded > Nov 8 14:07:07 server1 amavis[1309]: Found $file at /usr/bin/file > Nov 8 14:07:07 server1 amavis[1309]: No $altermime, not using it > Nov 8 14:07:07 server1 amavis[1309]: Internal decoder for .mail > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .F > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .Z at > /bin/uncompress > Nov 8 14:07:07 server1 amavis[1309]: Internal decoder for .gz > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .bz2 at > /bin/bzip2 -d > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .lzo tried: lzop > -d > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .rpm tried: > rpm2cpio.pl, rpm2cpio > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .cpio at /bin/pax > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .tar at /bin/pax > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .deb at > /usr/bin/ar > Nov 8 14:07:07 server1 amavis[1309]: Internal decoder for .zip > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .7z tried: 7zr, > 7za, 7z > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .rar tried: > unrar-free > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .arj at > /usr/bin/arj > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .arc at > /usr/bin/nomarch > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .zoo tried: zoo > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .lha > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .doc tried: ripole > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .cab at > /usr/bin/cabextract > Nov 8 14:07:07 server1 amavis[1309]: No decoder for .tnef > Nov 8 14:07:07 server1 amavis[1309]: Internal decoder for .tnef > Nov 8 14:07:07 server1 amavis[1309]: Found decoder for .exe at > /usr/bin/arj > Nov 8 14:07:07 server1 amavis[1309]: Using primary internal av scanner code > for ClamAV-clamd > Nov 8 14:07:07 server1 amavis[1309]: Found secondary av scanner > ClamAV-clamscan at /usr/bin/clamscan > Nov 8 14:07:07 server1 amavis[1309]: Creating db in /var/lib/amavis/db/; > BerkeleyDB 0.49, libdb 5.1 > Nov 8 14:07:07 server1 postgrey[1333]: Process Backgrounded > Nov 8 14:07:07 server1 postgrey[1333]: 2013/11/08-14:07:07 postgrey (type > Net::Server::Multiplex) starting! pid(1333) > Nov 8 14:07:07 server1 postgrey[1333]: Resolved [localhost]:10023 to > [127.0.0.1]:10023, IPv4 > Nov 8 14:07:07 server1 postgrey[1333]: Binding to TCP port 10023 on host > 127.0.0.1 with IPv4 > Nov 8 14:07:07 server1 postgrey[1333]: Setting gid to "120 120" > Nov 8 14:07:07 server1 postgrey[1333]: Setting uid to "112" > Nov 8 14:07:08 server1 spamd[1345]: logger: removing stderr method > Nov 8 14:07:18 server1 spamd[1347]: pyzor: [1350] error: TERMINATED, signal > 15 (000f) > Nov 8 14:07:18 server1 spamd[1347]: spamd: server started on port 783/tcp > (running version 3.3.2) > Nov 8 14:07:18 server1 spamd[1347]: spamd: server pid: 1347 > Nov 8 14:07:18 server1 spamd[1347]: spamd: server successfully spawned child > process, pid 1355 > Nov 8 14:07:18 server1 spamd[1347]: spamd: server successfully spawned child > process, pid 1356 > Nov 8 14:07:18 server1 spamd[1347]: prefork: child states: IS > Nov 8 14:07:18 server1 spamd[1347]: prefork: child states: II > Nov 8 14:07:29 server1 postfix/master[1838]: daemon started -- version > 2.9.6, configuration /etc/postfix > Nov 8 14:09:57 server1 postfix/smtps/smtpd[2508]: warning: dict_nis_init: > NIS domain name not set - NIS lookups disabled > Nov 8 14:09:57 server1 postfix/smtps/smtpd[2508]: connect from > server1.anydomain.de[***.***.***.***] > Nov 8 14:09:57 server1 postfix/smtps/smtpd[2508]: Anonymous TLS connection > established from server1.anydomain.de[***.***.***.***]: TLSv1.1 with cipher > ECDHE-RSA-AES256-SHA (256/256 bits) > Nov 8 14:09:57 server1 postfix/smtps/smtpd[2508]: C3A9F340073: > client=server1.anydomain.de[***.***.***.***], sasl_method=PLAIN, > [email protected] > Nov 8 14:09:57 server1 postfix/cleanup[2522]: C3A9F340073: > message-id=<[email protected]> > Nov 8 14:09:57 server1 postfix/qmgr[1843]: C3A9F340073: > from=<[email protected]>, size=350, nrcpt=1 (queue active) > Nov 8 14:09:57 server1 postfix/smtps/smtpd[2508]: disconnect from > server1.anydomain.de[***.***.***.***] > Nov 8 14:09:57 server1 amavis[1353]: (01353-01) (!)policyd/process_policy: > Failed to parse in queue id from received line '' > Nov 8 14:09:59 server1 postfix/smtpd[2542]: connect from localhost[127.0.0.1] > Nov 8 14:09:59 server1 postfix/smtpd[2542]: B36AB340077: > client=localhost[127.0.0.1] > Nov 8 14:09:59 server1 postfix/cleanup[2522]: B36AB340077: > message-id=<[email protected]> > Nov 8 14:09:59 server1 postfix/qmgr[1843]: B36AB340077: > from=<[email protected]>, size=789, nrcpt=1 (queue active) > Nov 8 14:09:59 server1 amavis[1353]: (01353-01) Passed CLEAN, > [***.***.***.***] <[email protected]> -> <[email protected]>, > Message-ID: <[email protected]>, mail_id: > Id+WXJ7XETSq, Hits: 0.997, size: 350, queued_as: B36AB340077, 1881 ms > Nov 8 14:09:59 server1 postfix/smtp[2526]: C3A9F340073: > to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.1, > delays=0.14/0.02/0.01/1.9, dsn=2.0.0, status=sent (250 2.0.0 from > MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as B36AB340077) > Nov 8 14:09:59 server1 postfix/qmgr[1843]: C3A9F340073: removed > Nov 8 14:10:00 server1 postfix/smtp[2544]: B36AB340077: > to=<[email protected]>, relay=mx-ha02.web.de[213.165.67.120]:25, > delay=0.47, delays=0.02/0.02/0.18/0.25, dsn=2.0.0, status=sent (250 Requested > mail action okay, completed: id=0MMU6c-1VXeHm05ja-008IzW) > Nov 8 14:10:00 server1 postfix/qmgr[1843]: B36AB340077: removed > >> Gesendet: Freitag, 08. November 2013 um 12:48 Uhr >> Von: "Nigel Kukard" <[email protected]> >> An: [email protected] >> Betreff: Re: [policyd-users] Optional Amavisd Support - BCC rule not working? >> >> On 11/08/2013 09:45 AM, Steffen Wegner wrote: >>> Dear Nigel, >>> AMAVIS is enabled in the cluebringer.conf. >>> This is my cluebringer.conf: >>> ------------------------/etc/cluebringer.conf------------------------------------ >> ..snip.. >> >>> # Amavis module >>> [Amavis] >>> enable=1 # Disabled by default >> Try remove the comment from the end of the line. IniFiles may not like it. -N _______________________________________________ Users mailing list [email protected] http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
