On 11/25/2014 10:03 AM, Tom Kinghorn wrote:
> On 2014/11/25, 11:49 AM, Nigel Kukard via Users wrote:
>>
>> Sorry, remove *any,* from the source.
>>
>> Lets see what it says then.
>>
>> -N
>>
>>
> postfix throws a config error:
> same as before:
>
> <snip>
> /Nov 25 08:54:19 smtp5 postfix/smtpd[22454]: warning: problem talking
> to server 10.115.244.114:10031: Success//
> //Nov 25 08:54:19 smtp5 postfix/smtpd[22454]: generic_checks:
> name=check_policy_service status=2//
> //Nov 25 08:54:19 smtp5 postfix/smtpd[22454]: >
> unknown[10.113.131.23]: 451 4.3.5 Server configuration problem/
> </snip>
>
> policyd debug below:
>
> /[2014/11/25-11:53:41 - 14430] [CORE] NOTICE: Process Backgrounded
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] NOTICE: Policyd v2 /
> Cluebringer - v2.1.x-201310261831
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] NOTICE: Initializing system
> modules.
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] NOTICE: System modules
> initialized.
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] NOTICE: Module load started...
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => AccessControl: enabled
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => Accounting: enabled
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => Amavis: disabled
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => CheckHelo: enabled
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => Quotas: enabled
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => Protocol(Postfix):
> enabled
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: => Protocol(Bizanga):
> enabled
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] NOTICE: Module load done.
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] NOTICE: Session tracking is
> ENABLED.
> [2014/11/25-11:53:41 - 14430] [CBPOLICYD] DEBUG: Opening syslog,
> destination = 'native', facility = 'mail'.
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: 2014/11/25-11:53:41 cbp
> (type Net::Server::PreFork) starting! pid(14430)
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: Resolved [*]:10031 to
> [::]:10031, IPv6
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: Not including resolved
> host [0.0.0.0] IPv4 because it will be handled by [::] IPv6
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: Binding to TCP port 10031
> on host :: with IPv6
> [2014/11/25-11:53:41 - 14430] [CORE] NOTICE: Setting gid to "0 0"
> [2014/11/25-11:53:41 - 14430] [CORE] INFO: Setting up serialization
> via flock
> [2014/11/25-11:53:41 - 14430] [CORE] INFO: Beginning prefork (8 processes)
> [2014/11/25-11:53:41 - 14430] [CORE] INFO: Starting "8" children
> [2014/11/25-11:53:41 - 14432] [CORE] DEBUG: Child Preforked (14432)
> [2014/11/25-11:53:41 - 14432] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14433] [CORE] DEBUG: Child Preforked (14433)
> [2014/11/25-11:53:41 - 14433] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14434] [CORE] DEBUG: Child Preforked (14434)
> [2014/11/25-11:53:41 - 14434] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14430] [CORE] DEBUG: Parent ready for children.
> [2014/11/25-11:53:41 - 14435] [CORE] DEBUG: Child Preforked (14435)
> [2014/11/25-11:53:41 - 14435] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14436] [CORE] DEBUG: Child Preforked (14436)
> [2014/11/25-11:53:41 - 14436] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14438] [CORE] DEBUG: Child Preforked (14438)
> [2014/11/25-11:53:41 - 14437] [CORE] DEBUG: Child Preforked (14437)
> [2014/11/25-11:53:41 - 14438] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14437] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:53:41 - 14439] [CORE] DEBUG: Child Preforked (14439)
> [2014/11/25-11:53:41 - 14439] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:54:08 - 14430] [CORE] INFO: Starting "1" children
> [2014/11/25-11:54:08 - 14432] [CORE] INFO: 2014/11/25-11:54:08 CONNECT
> TCP Peer: "[::ffff:10.113.154.84]:60783" Local:
> "[::ffff:10.115.244.114]:10031"
> [2014/11/25-11:54:08 - 14432] [PROTOCOLS/Postfix] DEBUG: Possible
> Postfix protocol
> [2014/11/25-11:54:08 - 14432] [PROTOCOLS/Postfix] INFO: Identified
> Postfix protocol
> [2014/11/25-11:54:08 - 14442] [CORE] DEBUG: Child Preforked (14442)
> [2014/11/25-11:54:08 - 14442] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:54:08 - 14432] [TRACKING] DEBUG: No session tracking
> data exists for request: $VAR1 = {
> 'ccert_fingerprint' => '',
> 'sasl_method' => '',
> 'sasl_sender' => '',
> 'size' => 3742,
> '_timestamp' => 1416909248,
> 'helo_name' => 'bbdjn.domain.local',
> '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' => '1e0d.547451c0.a1231.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/25-11:54:08 - 14432] [TRACKING] DEBUG: Added session tracking
> information for: $VAR1 = {
> 'ccert_fingerprint' => '',
> 'sasl_method' => '',
> 'sasl_sender' => '',
> 'size' => 3742,
> '_timestamp' => 1416909248,
> 'helo_name' => 'bbdjn.domain.local',
> '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' => '1e0d.547451c0.a1231.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/25-11:54:08 - 14432] [TRACKING] DEBUG: Protocol state is
> 'RCPT', resolving policy...
> [2014/11/25-11:54:08 - 14432] [POLICIES] DEBUG: Going to resolve
> session data into policy: $VAR1 = {
> 'Recipient' => '[email protected]',
> 'SASLUsername' => '',
> 'QueueID' => '',
> 'RecipientData' => '',
> 'Instance' => '1e0d.547451c0.a1231.0',
> 'EncryptionCipher' => '',
> 'Size' => '4',
> 'EncryptionKeySize' => '0',
> 'EncryptionProtocol' => '',
> 'Helo' => 'bbdjn.domain.local',
> '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/25-11:54:08 - 14432] [POLICIES] DEBUG: Found policy member
> with ID '20' in policy 'Default'
> [2014/11/25-11:54:08 - 14432] [POLICIES] DEBUG: Found policy member
> with ID '34' in policy 'Test'
> [2014/11/25-11:54:08 - 14432] [POLICIES] DEBUG: [ID:20/Name:Default]:
> Source not defined or 'any', explicit match: matched=1
> [2014/11/25-11:54:08 - 14432] [POLICIES] INFO: [ID:20/Name:Default]:
> Source matching result: matched=1
> [2014/11/25-11:54:08 - 14432] [POLICIES] DEBUG: [ID:20/Name:Default]:
> Destination not defined or 'any', explicit match: matched=1
> [2014/11/25-11:54:08 - 14432] [POLICIES] INFO: [ID:20/Name:Default]:
> Destination matching result: matched=1
> [2014/11/25-11:54:08 - 14432] [POLICIES] DEBUG: [ID:34/Name:Test]:
> Main policy sources '!%whitelist_quotas'
> Can't call method "get" on an undefined value at
> /usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 256.
> [2014/11/25-11:54:09 - 14434] [CORE] INFO: 2014/11/25-11:54:09 CONNECT
> TCP Peer: "[::ffff:10.113.154.84]:60784" Local:
> "[::ffff:10.115.244.114]:10031"
> [2014/11/25-11:54:09 - 14430] [CORE] INFO: Starting "1" children
> [2014/11/25-11:54:09 - 14434] [PROTOCOLS/Postfix] DEBUG: Possible
> Postfix protocol
> [2014/11/25-11:54:09 - 14434] [PROTOCOLS/Postfix] INFO: Identified
> Postfix protocol
> [2014/11/25-11:54:09 - 14443] [CORE] DEBUG: Child Preforked (14443)
> [2014/11/25-11:54:09 - 14443] [CBPOLICYD] DEBUG: Starting up caching
> engine
> [2014/11/25-11:54:09 - 14434] [TRACKING] DEBUG: Protocol state is
> 'RCPT', resolving policy...
> [2014/11/25-11:54:09 - 14434] [POLICIES] DEBUG: Going to resolve
> session data into policy: $VAR1 = {
> 'Recipient' => '[email protected]',
> 'SASLUsername' => '',
> 'QueueID' => '',
> 'RecipientData' => undef,
> 'EncryptionCipher' => '',
> 'Instance' => '1e0d.547451c0.a1231.0',
> 'Size' => '4',
> 'EncryptionKeySize' => '0',
> 'UnixTimestamp' => '1416909248',
> 'EncryptionProtocol' => '',
> 'Helo' => 'bbdjn.domain.local',
> '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/25-11:54:09 - 14434] [POLICIES] DEBUG: Found policy member
> with ID '20' in policy 'Default'
> [2014/11/25-11:54:09 - 14434] [POLICIES] DEBUG: Found policy member
> with ID '34' in policy 'Test'
> [2014/11/25-11:54:09 - 14434] [POLICIES] DEBUG: [ID:20/Name:Default]:
> Source not defined or 'any', explicit match: matched=1
> [2014/11/25-11:54:09 - 14434] [POLICIES] INFO: [ID:20/Name:Default]:
> Source matching result: matched=1
> [2014/11/25-11:54:09 - 14434] [POLICIES] DEBUG: [ID:20/Name:Default]:
> Destination not defined or 'any', explicit match: matched=1
> [2014/11/25-11:54:09 - 14434] [POLICIES] INFO: [ID:20/Name:Default]:
> Destination matching result: matched=1
> [2014/11/25-11:54:09 - 14434] [POLICIES] DEBUG: [ID:34/Name:Test]:
> Main policy sources '!%whitelist_quotas'
> Can't call method "get" on an undefined value at
> /usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 256./
Back to the error you're getting with the FastMmap caching.
Can you dump the config tables from mysql with the minimum amount of
config used to get this error? if you have a non-production machine I
can take a look at it would also help.
-N
_______________________________________________
Users mailing list
[email protected]
http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
