On 11/18/2014 09:33 AM, Tom Kinghorn wrote: > On 2014/11/14, 4:12 PM, Nigel Kukard wrote: >> On 11/14/2014 12:20 PM, Tom Kinghorn wrote: > Hi Nigel. > > I updated to the latest GIT version but problem persists. > > git clone https://gitlab.devlabs.linuxassist.net/policyd/policyd.git > > NOTE: I have noticed the version on GIT is way behind the snapshot. > > Should I be using thesnapshot & not git version found on > http://wiki.policyd.org/development ? > > > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] NOTICE: Policyd v2 / > Cluebringer - v2.1.x-201205100639 > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] NOTICE: Initializing system > modules. > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] NOTICE: System modules > initialized. > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] NOTICE: Module load started... > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => AccessControl: enabled > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => Accounting: enabled > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => Amavis: disabled > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => CheckHelo: enabled > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => Quotas: enabled > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => Protocol(Postfix): > enabled > [2014/11/18-11:20:54 - 9020] [CORE] NOTICE: => Protocol(Bizanga): > enabled > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] NOTICE: Module load done. > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] NOTICE: Session tracking is > ENABLED. > [2014/11/18-11:20:54 - 9020] [CBPOLICYD] DEBUG: Opening syslog, > destination = 'native', facility = 'mail'. > > > Full debug shows: > > /[2014/11/18-11:17:35 - 8976] [CORE] INFO: Starting "1" children > [2014/11/18-11:17:35 - 8988] [CORE] INFO: 2014/11/18-11:17:35 CONNECT > TCP Peer: "[::ffff:10.113.154.84]:56966" Local: > "[::ffff:10.115.244.114]:10031" > [2014/11/18-11:17:35 - 8988] [PROTOCOLS/Postfix] DEBUG: Possible > Postfix protocol > [2014/11/18-11:17:35 - 8988] [PROTOCOLS/Postfix] INFO: Identified > Postfix protocol > [2014/11/18-11:17:35 - 8996] [CORE] DEBUG: Child Preforked (8996) > [2014/11/18-11:17:35 - 8996] [CBPOLICYD] DEBUG: Starting up caching engine > [2014/11/18-11:17:35 - 8988] [TRACKING] DEBUG: No session tracking > data exists for request: $VAR1 = { > 'ccert_fingerprint' => '', > 'sasl_method' => '', > 'sasl_sender' => '', > 'size' => 400, > '_timestamp' => 1416302255, > 'helo_name' => 'ilab-czh.vodacom.corp', > 'reverse_client_name' => 'unknown', > 'queue_id' => '', > 'encryption_cipher' => '', > 'encryption_protocol' => '', > 'etrn_domain' => '', > 'ccert_subject' => '', > 'request' => 'smtpd_access_policy', > 'protocol_state' => 'RCPT', > 'stress' => '', > '_peer_address' => '::ffff:10.113.154.84', > 'recipient' => '[email protected]', > 'sasl_username' => '', > 'instance' => '65da.546b0eaf.70c09.0', > 'protocol_name' => 'ESMTP', > 'encryption_keysize' => '0', > 'recipient_count' => '0', > 'ccert_issuer' => '', > 'sender' => '[email protected]', > 'client_name' => 'unknown', > 'client_address' => '10.113.131.23', > '_protocol_transport' => 'Postfix' > }; > [2014/11/18-11:17:35 - 8988] [TRACKING] DEBUG: Added session tracking > information for: $VAR1 = { > 'ccert_fingerprint' => '', > 'sasl_method' => '', > 'sasl_sender' => '', > 'size' => 400, > '_timestamp' => 1416302255, > 'helo_name' => 'ilab-czh.vodacom.corp', > 'reverse_client_name' => 'unknown', > 'queue_id' => '', > 'encryption_cipher' => '', > 'encryption_protocol' => '', > 'etrn_domain' => '', > 'ccert_subject' => '', > 'request' => 'smtpd_access_policy', > 'protocol_state' => 'RCPT', > 'stress' => '', > '_peer_address' => '::ffff:10.113.154.84', > 'recipient' => '[email protected]', > 'sasl_username' => '', > 'instance' => '65da.546b0eaf.70c09.0', > 'protocol_name' => 'ESMTP', > 'encryption_keysize' => '0', > 'recipient_count' => '0', > 'ccert_issuer' => '', > 'sender' => '[email protected]', > 'client_name' => 'unknown', > 'client_address' => '10.113.131.23', > '_protocol_transport' => 'Postfix' > }; > [2014/11/18-11:17:35 - 8988] [TRACKING] DEBUG: Protocol state is > 'RCPT', resolving policy... > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: Going to resolve > session data into policy: $VAR1 = { > 'Recipient' => '[email protected]', > 'SASLUsername' => '', > 'QueueID' => '', > 'RecipientData' => '', > 'Instance' => '65da.546b0eaf.70c09.0', > 'EncryptionCipher' => '', > 'Size' => '1', > 'EncryptionKeySize' => '0', > 'EncryptionProtocol' => '', > 'Helo' => 'ilab-czh.vodacom.corp', > 'ClientAddress' => '10.113.131.23', > 'ClientName' => 'unknown', > 'Sender' => '[email protected]', > 'SASLSender' => '', > '_ClientAddress' => bless( { > 'raw_ip' => '10.113.131.23', > 'ip' => '10.113.131.23', > 'ip_version' => 4, > 'cidr' => 32 > }, 'awitpt::netip' ), > 'Protocol' => 'ESMTP', > 'ClientReverseName' => 'unknown', > 'SASLMethod' => '' > }; > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: Found policy member > with ID '1' in policy 'Default' > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: Found policy member > with ID '2' in policy 'Default Outbound' > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: Found policy member > with ID '3' in policy 'Default Inbound' > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: Found policy member > with ID '4' in policy 'Default Internal' > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: Found policy member > with ID '5' in policy 'Test' > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: [ID:1/Name:Default]: > Source not defined or 'any', explicit match: matched=1 > [2014/11/18-11:17:35 - 8988] [POLICIES] INFO: [ID:1/Name:Default]: > Source matching result: matched=1 > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: [ID:1/Name:Default]: > Destination not defined or 'any', explicit match: matched=1 > [2014/11/18-11:17:35 - 8988] [POLICIES] INFO: [ID:1/Name:Default]: > Destination matching result: matched=1 > [2014/11/18-11:17:35 - 8988] [POLICIES] DEBUG: [ID:2/Name:Default > Outbound]: Main policy sources '%internal_ips,%internal_domains' > *Can't call method "get" on an undefined value at > /usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 256, <$read> line 1.* > [2014/11/18-11:17:36 - 8976] [CORE] INFO: Starting "1" children > [2014/11/18-11:17:36 - 8990] [CORE] INFO: 2014/11/18-11:17:36 CONNECT > TCP Peer: "[::ffff:10.113.154.84]:56967" Local: > "[::ffff:10.115.244.114]:10031" > [2014/11/18-11:17:36 - 8990] [PROTOCOLS/Postfix] DEBUG: Possible > Postfix protocol > [2014/11/18-11:17:36 - 8990] [PROTOCOLS/Postfix] INFO: Identified > Postfix protocol > [2014/11/18-11:17:36 - 8997] [CORE] DEBUG: Child Preforked (8997) > [2014/11/18-11:17:36 - 8997] [CBPOLICYD] DEBUG: Starting up caching engine > [2014/11/18-11:17:36 - 8990] [TRACKING] DEBUG: Protocol state is > 'RCPT', resolving policy... > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: Going to resolve > session data into policy: $VAR1 = { > 'Recipient' => '[email protected]', > 'SASLUsername' => '', > 'QueueID' => '', > 'RecipientData' => undef, > 'EncryptionCipher' => '', > 'Instance' => '65da.546b0eaf.70c09.0', > 'Size' => '1', > 'EncryptionKeySize' => '0', > 'UnixTimestamp' => '1416302255', > 'EncryptionProtocol' => '', > 'Helo' => 'ilab-czh.vodacom.corp', > 'ClientAddress' => '10.113.131.23', > 'ClientName' => 'unknown', > 'Sender' => '[email protected]', > 'SASLSender' => '', > '_ClientAddress' => bless( { > 'raw_ip' => '10.113.131.23', > 'ip' => '10.113.131.23', > 'ip_version' => 4, > 'cidr' => 32 > }, 'awitpt::netip' ), > 'Protocol' => 'ESMTP', > 'ClientReverseName' => 'unknown', > 'SASLMethod' => '' > }; > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: Found policy member > with ID '1' in policy 'Default' > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: Found policy member > with ID '2' in policy 'Default Outbound' > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: Found policy member > with ID '3' in policy 'Default Inbound' > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: Found policy member > with ID '4' in policy 'Default Internal' > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: Found policy member > with ID '5' in policy 'Test' > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: [ID:1/Name:Default]: > Source not defined or 'any', explicit match: matched=1 > [2014/11/18-11:17:36 - 8990] [POLICIES] INFO: [ID:1/Name:Default]: > Source matching result: matched=1 > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: [ID:1/Name:Default]: > Destination not defined or 'any', explicit match: matched=1 > [2014/11/18-11:17:36 - 8990] [POLICIES] INFO: [ID:1/Name:Default]: > Destination matching result: matched=1 > [2014/11/18-11:17:36 - 8990] [POLICIES] DEBUG: [ID:2/Name:Default > Outbound]: Main policy sources '%internal_ips,%internal_domains' > *Can't call method "get" on an undefined value at > /usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 256, <$read> line 2.*/ > > > > restarting shows: > > # /etc/init.d/cbpolicyd restart > Shutting down cbpolicyd: [ OK ] > Starting cbpolicyd: Constant subroutine awitpt::netip::AF_INET6 > redefined at /usr/share/perl5/Exporter.pm line 67. > at /usr/local/lib/cbpolicyd-2.1/awitpt/netip.pm line 38 > Prototype mismatch: sub awitpt::netip::AF_INET6 () vs none at > /usr/share/perl5/Exporter.pm line 67. > at /usr/local/lib/cbpolicyd-2.1/awitpt/netip.pm line 38 > > [ OK ] > > > Nothing appears in /var/log/maillog
I am almost certain this was reported about a year ago and had to do with the version of cache fastmmap, that or its not initializing. Latest git is what you're after, the version is sort of misleading though as this is tagged when I create the tarballs. I think you missing more debugging though, you should see something about the cache being initialized. -N
_______________________________________________ Users mailing list [email protected] http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
