On 2014/11/18, 11: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.
as per my previous mail, I installed the latest snapshot & enabled full
debugging.
The startup produces:
/<snip>
Can't call method "get" on an undefined value at
/usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 321.
[2014/11/18-13:16:43 - 13493] [CORE] INFO: Starting "3" children
Can't call method "get" on an undefined value at
/usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 321.
[2014/11/18-13:16:43 - 13513] [CORE] DEBUG: Child Preforked (13513)
[2014/11/18-13:16:43 - 13513] [CBPOLICYD] DEBUG: Starting up caching engine
Can't call method "get" on an undefined value at
/usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 321.
[2014/11/18-13:16:43 - 13493] [CORE] NOTICE: 2014/11/18-13:16:43 Server
closing!
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13512
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13500
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13496
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13497
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13498
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13513
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13514
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Kill TERM pid 13495
[2014/11/18-13:16:43 - 13514] [CORE] DEBUG: Child Preforked (13514)
[2014/11/18-13:16:43 - 13514] [CBPOLICYD] DEBUG: Starting up caching engine
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Destroying system modules.
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: System modules destroyed.
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Closing syslog.
[2014/11/18-13:16:43 - 13493] [CORE] DEBUG: Syslog closed.
install_driver(mysql) failed: Can't call method "get" on an undefined
value at /usr/local/lib/cbpolicyd-2.1/awitpt/cache.pm line 321.
BEGIN failed--compilation aborted at /usr/lib64/perl5/DBD/mysql.pm line 100.
Compilation failed in require at (eval 32) line 3.
at /usr/local/lib/cbpolicyd-2.1/awitpt/db/dbilayer.pm line 187
[2014/11/18-13:16:43 - 13512] [CORE] DEBUG: Child Preforked (13512)
[2014/11/18-13:16:43 - 13512] [CBPOLICYD] DEBUG: Starting up caching engine
[2014/11/18-13:16:43 - 13514] [CORE] NOTICE: Accept failed with 29 tries
left: Invalid argument
[2014/11/18-13:16:43 - 13512] [CORE] NOTICE: Accept failed with 29 tries
left: Invalid argument
[2014/11/18-13:16:44 - 13522] [CORE] NOTICE: Process Backgrounded
*[2014/11/18-13:16:44 - 13522] [CBPOLICYD] NOTICE: Policyd v2 /
Cluebringer - v2.1.x-201310261831*
[2014/11/18-13:16:44 - 13522] [CBPOLICYD] NOTICE: Initializing system
modules.
on sending a mail, logs show:
[2014/11/18-13:21:20 - 13565] [CORE] INFO: Starting "1" children
[2014/11/18-13:21:20 - 13568] [CORE] INFO: 2014/11/18-13:21:20 CONNECT
TCP Peer: "[::ffff:10.113.154.84]:50575" Local:
"[::ffff:10.115.244.114]:10031"
[2014/11/18-13:21:20 - 13568] [PROTOCOLS/Postfix] DEBUG: Possible
Postfix protocol
[2014/11/18-13:21:20 - 13568] [PROTOCOLS/Postfix] INFO: Identified
Postfix protocol
[2014/11/18-13:21:20 - 13577] [CORE] DEBUG: Child Preforked (13577)
[2014/11/18-13:21:20 - 13577] [CBPOLICYD] DEBUG: Starting up caching engine
[2014/11/18-13:21:20 - 13568] [TRACKING] DEBUG: No session tracking data
exists for request: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 400,
'_timestamp' => 1416309680,
'helo_name' => 'Toms-MacBook-Pro.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' => '636.546b2bb0.1ac10.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-13:21:20 - 13568] [TRACKING] DEBUG: Added session tracking
information for: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 400,
'_timestamp' => 1416309680,
'helo_name' => 'Toms-MacBook-Pro.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' => '636.546b2bb0.1ac10.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-13:21:20 - 13568] [TRACKING] DEBUG: Protocol state is
'RCPT', resolving policy...
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: Going to resolve session
data into policy: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => '636.546b2bb0.1ac10.0',
'EncryptionCipher' => '',
'Size' => '1',
'EncryptionKeySize' => '0',
'EncryptionProtocol' => '',
'Helo' => 'Toms-MacBook-Pro.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/18-13:21:20 - 13568] [POLICIES] DEBUG: Found policy member with
ID '1' in policy 'Default'
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: Found policy member with
ID '2' in policy 'Default Outbound'
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: Found policy member with
ID '3' in policy 'Default Inbound'
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: Found policy member with
ID '4' in policy 'Default Internal'
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: Found policy member with
ID '5' in policy 'Test'
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: [ID:1/Name:Default]:
Source not defined or 'any', explicit match: matched=1
[2014/11/18-13:21:20 - 13568] [POLICIES] INFO: [ID:1/Name:Default]:
Source matching result: matched=1
[2014/11/18-13:21:20 - 13568] [POLICIES] DEBUG: [ID:1/Name:Default]:
Destination not defined or 'any', explicit match: matched=1
[2014/11/18-13:21:20 - 13568] [POLICIES] INFO: [ID:1/Name:Default]:
Destination matching result: matched=1
[2014/11/18-13:21:20 - 13568] [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.
[2014/11/18-13:21:21 - 13565] [CORE] INFO: Starting "1" children
[2014/11/18-13:21:21 - 13572] [CORE] INFO: 2014/11/18-13:21:21 CONNECT
TCP Peer: "[::ffff:10.113.154.84]:50576" Local:
"[::ffff:10.115.244.114]:10031"
[2014/11/18-13:21:21 - 13572] [PROTOCOLS/Postfix] DEBUG: Possible
Postfix protocol
[2014/11/18-13:21:21 - 13572] [PROTOCOLS/Postfix] INFO: Identified
Postfix protocol
[2014/11/18-13:21:21 - 13578] [CORE] DEBUG: Child Preforked (13578)
[2014/11/18-13:21:21 - 13578] [CBPOLICYD] DEBUG: Starting up caching engine
[2014/11/18-13:21:21 - 13572] [TRACKING] DEBUG: Protocol state is
'RCPT', resolving policy...
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: Going to resolve session
data into policy: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => undef,
'EncryptionCipher' => '',
'Instance' => '636.546b2bb0.1ac10.0',
'Size' => '1',
'EncryptionKeySize' => '0',
'UnixTimestamp' => '1416309680',
'EncryptionProtocol' => '',
'Helo' => 'Toms-MacBook-Pro.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/18-13:21:21 - 13572] [POLICIES] DEBUG: Found policy member with
ID '1' in policy 'Default'
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: Found policy member with
ID '2' in policy 'Default Outbound'
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: Found policy member with
ID '3' in policy 'Default Inbound'
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: Found policy member with
ID '4' in policy 'Default Internal'
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: Found policy member with
ID '5' in policy 'Test'
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: [ID:1/Name:Default]:
Source not defined or 'any', explicit match: matched=1
[2014/11/18-13:21:21 - 13572] [POLICIES] INFO: [ID:1/Name:Default]:
Source matching result: matched=1
[2014/11/18-13:21:21 - 13572] [POLICIES] DEBUG: [ID:1/Name:Default]:
Destination not defined or 'any', explicit match: matched=1
[2014/11/18-13:21:21 - 13572] [POLICIES] INFO: [ID:1/Name:Default]:
Destination matching result: matched=1
[2014/11/18-13:21:21 - 13572] [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.
again, nothing appears in the maillog
the postfix server shows:
Nov 18 13:21:21 smtp5 postfix/smtpd[1590]: warning: problem talking to
server 10.115.244.114:10031: Success
Nov 18 13:21:21 smtp5 postfix/smtpd[1590]: NOQUEUE: reject: RCPT from
unknown[10.113.131.23]: 451 4.3.5 Server configuration problem;
from=<[email protected]> to=<[email protected]> proto=ESMTP
helo=<Toms-MacBook-Pro.local>
Nov 18 13:21:21 smtp5 postfix/smtpd[1590]: generic_checks:
name=check_policy_service status=2
Nov 18 13:21:21 smtp5 postfix/smtpd[1590]: > unknown[10.113.131.23]: 451
4.3.5 Server configuration problem
thanks
Tom
/
_______________________________________________
Users mailing list
[email protected]
http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
