Hey any update regarding the error?
Please have a look at the attached debug log for the respective error:
------------
[2012/08/24-15:14:52 - 20660] [QUOTAS] ERROR: Failed to insert
quota_tracking item: cbp::dblayer::DBDo(125): Error executing command '
------------
Please let me know if you nede any more details.
On Fri, Aug 24, 2012 at 9:48 PM, Nigel Kukard <[email protected]> wrote:
> On 24/08/2012 15:57, Simon Hobson wrote:
>
>> Fabio Sangiovanni wrote:
>>
>> I'm using policyd to throttle incoming mail (size over time against
>>> sasl_username) and I'm experiencing a strange delay during submission.
>>> In particular, it seems some kind of delay due to policyd execution, and
>>> it's about 1 second per message.
>>>
>>
>> I hit a similar issue which is documented in a thread entitled
>> "Performance issues - restricted mail handling" from April 2011. In the end
>> I concluded that Postfix gives the appearance of having some sort of
>> throughput issue when calling an external policy server - I found a limit
>> of about 2 calls/second/smtp process.
>> Since this is per-process, it scales with the number of connections and
>> since it won't generally be an issue for me I never took it any further. I
>> haven't done any tests to see it it's still an issue (the boxes/software
>> have been upgraded since then).
>>
>>
> This could be entirely my fault ... I fixed the performance issue in v2.1
> by committing a pipelining patch which fixes pipelining of requests.
> However I do not see this comitted to v2.0.
>
> Please try the attached, if it works I'll commit it right away.
>
> -N
>
> _______________________________________________
> Users mailing list
> [email protected]
> http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
>
>
--
Regards,
Soumya
Linux Sytem Administrator
DirectI
<http://poornam.com>
"I like the dreams of the future better than the history of the past."
[2012/08/24-15:14:51 - 20318] [CBPOLICYD] DEBUG: Got request, running modules...
[2012/08/24-15:14:51 - 20318] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2012/08/24-15:14:51 - 20318] [CBPOLICYD] DEBUG: Done with modules
[2012/08/24-15:14:52 - 20660] [CORE] INFO: 2012/08/24-15:14:52 CONNECT TCP
Peer: "127.0.0.1:47385" Local: "127.0.0.1:10032"
[2012/08/24-15:14:52 - 20318] [CORE] INFO: 2012/08/24-15:14:52 CONNECT TCP
Peer: "127.0.0.1:47386" Local: "127.0.0.1:10032"
[2012/08/24-15:14:52 - 20660] [PROTOCOLS/Postfix] DEBUG: Possible Postfix
protocol
[2012/08/24-15:14:52 - 20660] [PROTOCOLS/Postfix] INFO: Identified Postfix
protocol
[2012/08/24-15:14:52 - 20318] [PROTOCOLS/Postfix] DEBUG: Possible Postfix
protocol
[2012/08/24-15:14:52 - 20318] [PROTOCOLS/Postfix] INFO: Identified Postfix
protocol
[2012/08/24-15:14:52 - 20318] [TRACKING] DEBUG: No session tracking data exists
for request: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 11677,
'_timestamp' => 1345821292,
'helo_name' => 'abc.com',
'reverse_client_name' => 'abc.com',
'queue_id' => '',
'encryption_cipher' => '',
'encryption_protocol' => '',
'etrn_domain' => '',
'ccert_subject' => '',
'request' => 'smtpd_access_policy',
'protocol_state' => 'RCPT',
'stress' => '',
'recipient' => '[email protected]',
'sasl_username' => '',
'instance' => '29cc.50379a6c.146cc.0',
'protocol_name' => 'ESMTP',
'encryption_keysize' => '0',
'recipient_count' => '0',
'ccert_issuer' => '',
'sender' => '[email protected]',
'client_name' => 'abc.com',
'client_address' => '192.168.63.101',
'_protocol_transport' => 'Postfix'
};
[2012/08/24-15:14:52 - 20660] [TRACKING] DEBUG: No session tracking data exists
for request: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 11677,
'_timestamp' => 1345821292,
'helo_name' => 'abc.com',
'reverse_client_name' => 'abc.com',
'queue_id' => '',
'encryption_cipher' => '',
'encryption_protocol' => '',
'etrn_domain' => '',
'ccert_subject' => '',
'request' => 'smtpd_access_policy',
'protocol_state' => 'RCPT',
'stress' => '',
'recipient' => '[email protected]',
'sasl_username' => '',
'instance' => '22b7.50379a6c.1468c.0',
'protocol_name' => 'ESMTP',
'encryption_keysize' => '0',
'recipient_count' => '0',
'ccert_issuer' => '',
'sender' => '[email protected]',
'client_name' => 'abc.com',
'client_address' => '192.168.63.101',
'_protocol_transport' => 'Postfix'
};
[2012/08/24-15:14:52 - 20318] [TRACKING] DEBUG: Added session tracking
information for: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 11677,
'_timestamp' => 1345821292,
'helo_name' => 'abc.com',
'reverse_client_name' => 'abc.com',
'queue_id' => '',
'encryption_cipher' => '',
'encryption_protocol' => '',
'etrn_domain' => '',
'ccert_subject' => '',
'request' => 'smtpd_access_policy',
'protocol_state' => 'RCPT',
'stress' => '',
'recipient' => '[email protected]',
'sasl_username' => '',
'instance' => '29cc.50379a6c.146cc.0',
'protocol_name' => 'ESMTP',
'encryption_keysize' => '0',
'recipient_count' => '0',
'ccert_issuer' => '',
'sender' => '[email protected]',
'client_name' => 'abc.com',
'client_address' => '192.168.63.101',
'_protocol_transport' => 'Postfix'
};
[2012/08/24-15:14:52 - 20660] [TRACKING] DEBUG: Added session tracking
information for: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 11677,
'_timestamp' => 1345821292,
'helo_name' => 'abc.com',
'reverse_client_name' => 'abc.com',
'queue_id' => '',
'encryption_cipher' => '',
'encryption_protocol' => '',
'etrn_domain' => '',
'ccert_subject' => '',
'request' => 'smtpd_access_policy',
'protocol_state' => 'RCPT',
'stress' => '',
'recipient' => '[email protected]',
'sasl_username' => '',
'instance' => '22b7.50379a6c.1468c.0',
'protocol_name' => 'ESMTP',
'encryption_keysize' => '0',
'recipient_count' => '0',
'ccert_issuer' => '',
'sender' => '[email protected]',
'client_name' => 'abc.com',
'client_address' => '192.168.63.101',
'_protocol_transport' => 'Postfix'
};
[2012/08/24-15:14:52 - 20318] [TRACKING] DEBUG: Protocol state is 'RCPT',
resolving policy...
[2012/08/24-15:14:52 - 20660] [TRACKING] DEBUG: Protocol state is 'RCPT',
resolving policy...
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: Going to resolve session data
into policy: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => '22b7.50379a6c.1468c.0',
'EncryptionCipher' => '',
'Size' => '12',
'EncryptionKeySize' => '0',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: Going to resolve session data
into policy: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => '29cc.50379a6c.146cc.0',
'EncryptionCipher' => '',
'Size' => '12',
'EncryptionKeySize' => '0',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: Found policy member with ID '4'
in policy 'Hosting Outbound Policy'
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Main policy sources '%track_ip'
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: Found policy member with ID '4'
in policy 'Hosting Outbound Policy'
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Main policy sources '%track_ip'
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Group 'track_ip' has 1 source(s) => 192.168.63.101
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): - Resolved source '192.168.63.101' to a IP/CIDR
specification, ma
tch = 1
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): Source group result: matched=1
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Group 'track_ip' has 1 source(s) => 192.168.63.101
[2012/08/24-15:14:52 - 20318] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Source matching result: matched=1
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Destination not defined or 'any', explicit match: matched=1
[2012/08/24-15:14:52 - 20318] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Destination matching result: matched=1
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): - Resolved source '192.168.63.101' to a IP/CIDR
specification, ma
tch = 1
[2012/08/24-15:14:52 - 20318] [POLICIES] DEBUG: END RESULT: prio=10 => policy
ids: 3
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): Source group result: matched=1
[2012/08/24-15:14:52 - 20660] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Source matching result: matched=1
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Destination not defined or 'any', explicit match: matched=1
[2012/08/24-15:14:52 - 20318] [TRACKING] DEBUG: Policy resolved into: $VAR1 = {
'10' => [
'3'
]
};
[2012/08/24-15:14:52 - 20660] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Destination matching result: matched=1
[2012/08/24-15:14:52 - 20660] [POLICIES] DEBUG: END RESULT: prio=10 => policy
ids: 3
[2012/08/24-15:14:52 - 20660] [TRACKING] DEBUG: Policy resolved into: $VAR1 = {
'10' => [
'3'
]
};
[2012/08/24-15:14:52 - 20318] [TRACKING] DEBUG: Request translated into session
data: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => '29cc.50379a6c.146cc.0',
'EncryptionCipher' => '',
'Size' => '12',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 3495675446,
'Network' => '192.168.63.101',
'IP_Long' => 3495675446,
'Broadcast' => '192.168.63.101',
'IP' => '192.168.63.101',
'Mask_Long' => 4294967295,
'Network_Long' => 3495675446
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => 1345821292,
'ProtocolState' => 'RCPT',
'Policy' => {
'10' => [
'3'
]
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:14:52 - 20318] [CBPOLICYD] DEBUG: Got request, running modules...
[2012/08/24-15:14:52 - 20318] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2012/08/24-15:14:52 - 20660] [TRACKING] DEBUG: Request translated into session
data: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => '22b7.50379a6c.1468c.0',
'EncryptionCipher' => '',
'Size' => '12',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 3495675446,
'Network' => '192.168.63.101',
'IP_Long' => 3495675446,
'Broadcast' => '192.168.63.101',
'IP' => '192.168.63.101',
'Mask_Long' => 4294967295,
'Network_Long' => 3495675446
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => 1345821292,
'ProtocolState' => 'RCPT',
'Policy' => {
'10' => [
'3'
]
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:14:52 - 20660] [CBPOLICYD] DEBUG: Got request, running modules...
[2012/08/24-15:14:52 - 20660] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2012/08/24-15:14:52 - 20660] [QUOTAS] ERROR: Failed to insert quota_tracking
item: cbp::dblayer::DBDo(125): Error executing command '
%09%09%09%09%09%09INSERT INTO quotas_tracking
%09%09%09%09%09%09%09(QuotasLimitsID,TrackKey,LastUpdate,Counter)
%09%09%09%09%09%09VALUES
%09%09%09%09%09%09%09(
%09%09%09%09%09%09%09%09'1',
%09%09%09%09%09%09%09%09'Sender:[email protected]',
%09%09%09%09%09%09%09%09'1345821292',
%09%09%09%09%09%09%09%09'1'
%09%09%09%09%09%09%09)
%09%09%09%09%09': Duplicate entry
'1-Sender:[email protected]' for key 1
[2012/08/24-15:14:52 - 20660] [CBPOLICYD] DEBUG: Done with modules
[....]
[2012/08/24-15:14:52 - 19376] [CBPOLICYD] DEBUG: Got request, running modules...
[2012/08/24-15:14:52 - 19376] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2012/08/24-15:14:52 - 19376] [CBPOLICYD] DEBUG: Done with modules
[2012/08/24-15:14:53 - 23318] [CORE] INFO: 2012/08/24-15:14:53 CONNECT TCP
Peer: "127.0.0.1:47393" Local: "127.0.0.1:10032"
[2012/08/24-15:14:53 - 23318] [PROTOCOLS/Postfix] DEBUG: Possible Postfix
protocol
[2012/08/24-15:14:53 - 23318] [PROTOCOLS/Postfix] INFO: Identified Postfix
protocol
[2012/08/24-15:14:53 - 23318] [TRACKING] DEBUG: Protocol state is 'RCPT',
resolving policy...
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: Going to resolve session data
into policy: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '/<[email protected]>#10=3;',
'EncryptionCipher' => '',
'Instance' => '29cc.50379a6c.146cc.0',
'Size' => '12',
'EncryptionKeySize' => '0',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => '1345821292',
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: Found policy member with ID '4'
in policy 'Hosting Outbound Policy'
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Main policy sources '%track_ip'
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Group 'track_ip' has 1 source(s) => 192.168.63.101
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): - Resolved source '192.168.63.101' to a IP/CIDR
specification, match = 1
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): Source group result: matched=1
[2012/08/24-15:14:53 - 23318] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Source matching result: matched=1
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Destination not defined or 'any', explicit match: matched=1
[2012/08/24-15:14:53 - 23318] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Destination matching result: matched=1
[2012/08/24-15:14:53 - 23318] [POLICIES] DEBUG: END RESULT: prio=10 => policy
ids: 3
[2012/08/24-15:14:53 - 23318] [TRACKING] DEBUG: Policy resolved into: $VAR1 = {
'10' => [
'3'
]
};
[2012/08/24-15:14:53 - 23318] [TRACKING] DEBUG: Request translated into session
data: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '/<[email protected]>#10=3;',
'EncryptionCipher' => '',
'Instance' => '29cc.50379a6c.146cc.0',
'Size' => '12',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 3495675446,
'Network' => '192.168.63.101',
'IP_Long' => 3495675446,
'Broadcast' => '192.168.63.101',
'IP' => '192.168.63.101',
'Mask_Long' => 4294967295,
'Network_Long' => 3495675446
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => 1345821293,
'ProtocolState' => 'RCPT',
'Policy' => {
'10' => [
'3'
]
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:14:54 - 19376] [TRACKING] DEBUG: Request translated into session
data: $VAR1 = {
'SASLUsername' => '',
'QueueID' => '17822173D0384',
'RecipientData' =>
'/<[email protected]>#10=3;/<[email protected]>#10=3;',
'EncryptionCipher' => '',
'Instance' => '29cc.50379a6c.146cc.0',
'Size' => '11',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 3495675446,
'Network' => '192.168.63.101',
'IP_Long' => 3495675446,
'Broadcast' => '192.168.63.101',
'IP' => '192.168.63.101',
'Mask_Long' => 4294967295,
'Network_Long' => 3495675446
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => 1345821294,
'ProtocolState' => 'END-OF-MESSAGE',
'_Recipient_To_Policy' => {
'[email protected]' => {
'10' => [
'3'
]
},
'[email protected]' =>
{
'10' => [
'3'
]
}
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:44:23 - 4715] [CBPOLICYD] DEBUG: Got request, running modules...
[2012/08/24-15:44:23 - 4715] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2012/08/24-15:44:23 - 4715] [CBPOLICYD] DEBUG: Done with modules
[2012/08/24-15:44:23 - 4719] [CORE] INFO: 2012/08/24-15:44:23 CONNECT TCP Peer:
"127.0.0.1:39819" Local: "127.0.0.1:10032"
[2012/08/24-15:44:23 - 4719] [PROTOCOLS/Postfix] DEBUG: Possible Postfix
protocol
[2012/08/24-15:44:23 - 4719] [PROTOCOLS/Postfix] INFO: Identified Postfix
protocol
[2012/08/24-15:44:23 - 4719] [TRACKING] DEBUG: No session tracking data exists
for request: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 11677,
'_timestamp' => 1345823063,
'helo_name' => 'abc.com',
'reverse_client_name' => 'abc.com',
'queue_id' => '',
'encryption_cipher' => '',
'encryption_protocol' => '',
'etrn_domain' => '',
'ccert_subject' => '',
'request' => 'smtpd_access_policy',
'protocol_state' => 'RCPT',
'stress' => '',
'recipient' => '[email protected]',
'sasl_username' => '',
'instance' => 'ce8.5037a157.eef24.0',
'protocol_name' => 'ESMTP',
'encryption_keysize' => '0',
'recipient_count' => '0',
'ccert_issuer' => '',
'sender' => '[email protected]',
'client_name' => 'abc.com',
'client_address' => '192.168.63.101',
'_protocol_transport' => 'Postfix'
};
[2012/08/24-15:44:23 - 4719] [TRACKING] DEBUG: Added session tracking
information for: $VAR1 = {
'ccert_fingerprint' => '',
'sasl_method' => '',
'sasl_sender' => '',
'size' => 11677,
'_timestamp' => 1345823063,
'helo_name' => 'abc.com',
'reverse_client_name' => 'abc.com',
'queue_id' => '',
'encryption_cipher' => '',
'encryption_protocol' => '',
'etrn_domain' => '',
'ccert_subject' => '',
'request' => 'smtpd_access_policy',
'protocol_state' => 'RCPT',
'stress' => '',
'recipient' => '[email protected]',
'sasl_username' => '',
'instance' => 'ce8.5037a157.eef24.0',
'protocol_name' => 'ESMTP',
'encryption_keysize' => '0',
'recipient_count' => '0',
'ccert_issuer' => '',
'sender' => '[email protected]',
'client_name' => 'abc.com',
'client_address' => '192.168.63.101',
'_protocol_transport' => 'Postfix'
};
[2012/08/24-15:44:23 - 4719] [TRACKING] DEBUG: Protocol state is 'RCPT',
resolving policy...
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: Going to resolve session data
into policy: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => 'ce8.5037a157.eef24.0',
'EncryptionCipher' => '',
'Size' => '12',
'EncryptionKeySize' => '0',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: Found policy member with ID '4'
in policy 'Hosting Outbound Policy'
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Main policy sources '%track_ip'
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Group 'track_ip' has 1 source(s) => 192.168.63.101
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): - Resolved source '192.168.63.101' to a IP/CIDR
specification, match = 1
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]=>(group:track_ip): Source group result: matched=1
[2012/08/24-15:44:23 - 4719] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Source matching result: matched=1
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: [ID:4/Name:Hosting Outbound
Policy]: Destination not defined or 'any', explicit match: matched=1
[2012/08/24-15:44:23 - 4719] [POLICIES] INFO: [ID:4/Name:Hosting Outbound
Policy]: Destination matching result: matched=1
[2012/08/24-15:44:23 - 4719] [POLICIES] DEBUG: END RESULT: prio=10 => policy
ids: 3
[2012/08/24-15:44:23 - 4719] [TRACKING] DEBUG: Policy resolved into: $VAR1 = {
'10' => [
'3'
]
};
[2012/08/24-15:44:23 - 4719] [TRACKING] DEBUG: Request translated into session
data: $VAR1 = {
'Recipient' => '[email protected]',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => 'ce8.5037a157.eef24.0',
'EncryptionCipher' => '',
'Size' => '12',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 3495675446,
'Network' => '192.168.63.101',
'IP_Long' => 3495675446,
'Broadcast' => '192.168.63.101',
'IP' => '192.168.63.101',
'Mask_Long' => 4294967295,
'Network_Long' => 3495675446
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => 1345823063,
'ProtocolState' => 'RCPT',
'Policy' => {
'10' => [
'3'
]
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
[....]
[2012/08/24-15:44:24 - 26183] [TRACKING] DEBUG: Protocol state is
'END-OF-MESSAGE', decoding policy...
[2012/08/24-15:44:24 - 26183] [TRACKING] DEBUG: Decoded into: $VAR1 = {
'[email protected]' => {
'10' => [
'3'
]
}
};
[2012/08/24-15:44:24 - 26183] [TRACKING] DEBUG: Request translated into session
data: $VAR1 = {
'SASLUsername' => '',
'QueueID' => 'F1094173E028B',
'RecipientData' => '/<[email protected]>#10=3;',
'EncryptionCipher' => '',
'Instance' => 'ce8.5037a157.eef24.0',
'Size' => '11',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 3495675446,
'Network' => '192.168.63.101',
'IP_Long' => 3495675446,
'Broadcast' => '192.168.63.101',
'IP' => '192.168.63.101',
'Mask_Long' => 4294967295,
'Network_Long' => 3495675446
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'abc.com',
'ClientAddress' => '192.168.63.101',
'ClientName' => 'abc.com',
'Sender' => '[email protected]',
'SASLSender' => '',
'Timestamp' => 1345823064,
'ProtocolState' => 'END-OF-MESSAGE',
'_Recipient_To_Policy' => {
'[email protected]' => {
'10'
=> [
'3'
]
}
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'abc.com',
'SASLMethod' => ''
};
_______________________________________________
Users mailing list
[email protected]
http://lists.policyd.org/mailman/listinfo/users_lists.policyd.org
