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 -------------/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 > _______________________________________________ Users mailing list [email protected] http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
