[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '1' in policy 'Default'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '2' in policy 'Default Outbound'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '3' in policy 'Default Inbound'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '4' in policy 'Default Internal'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '6' in policy 'whitelists'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '7' in policy 'blacklists'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '8' in policy 'no_greylisting'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: Found policy member with ID '9' in policy 'no_greylisting'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:1/Name:Default]: Source not defined or 'any', explicit match: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:1/Name:Default]: Source matching result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:1/Name:Default]: Destination not defined or 'any', explicit match: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:1/Name:Default]: Destination matching result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:2/Name:Default Outbound]: Main policy sources '%internal_ips,%internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:2/Name:Default Outbound]: Group 'internal_ips' has 1 source(s) => 10.0.0.0/8
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:2/Name:Default Outbound]=>(group:internal_ips): - Resolved source '10.0.0.0/8' to a IP/CIDR specification, match = 0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:2/Name:Default Outbound]=>(group:internal_ips): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:2/Name:Default Outbound]: Source matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]: Main policy sources '!%internal_ips,!%internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]: Group 'internal_ips' has 1 source(s) => 10.0.0.0/8
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]=>(group:internal_ips): - Resolved source '10.0.0.0/8' to a IP/CIDR specification, match = 0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]=>(group:internal_ips): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]: Group 'internal_domains' has 0 source(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:3/Name:Default Inbound]: No group members for source group 'internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]=>(group:internal_domains): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:3/Name:Default Inbound]: Source matching result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]: Main policy destinations '%internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]: Group 'internal_domains' has 0 destination(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:3/Name:Default Inbound]: No group members for destination group 'internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:3/Name:Default Inbound]=>(group:internal_domains): Destination group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:3/Name:Default Inbound]: Destination matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:4/Name:Default Internal]: Main policy sources '%internal_ips,%internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:4/Name:Default Internal]: Group 'internal_ips' has 1 source(s) => 10.0.0.0/8
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:4/Name:Default Internal]=>(group:internal_ips): - Resolved source '10.0.0.0/8' to a IP/CIDR specification, match = 0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:4/Name:Default Internal]=>(group:internal_ips): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:4/Name:Default Internal]: Source matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:6/Name:whitelists]: Main policy sources '%whitelists'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:6/Name:whitelists]: Group 'whitelists' has 0 source(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:6/Name:whitelists]: No group members for source group 'whitelists'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:6/Name:whitelists]=>(group:whitelists): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:6/Name:whitelists]: Source matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]: Main policy sources '%blacklists'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]: Group 'blacklists' has 1 source(s) => deviance@rambler.ru
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]=>(group:blacklists): - Resolved source 'deviance@rambler.ru' to a email address specification, match = 1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]=>(group:blacklists): Source group result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:7/Name:blacklists]: Source matching result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]: Main policy destinations '%internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]: Group 'internal_domains' has 0 destination(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:7/Name:blacklists]: No group members for destination group 'internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]=>(group:internal_domains): Destination group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:7/Name:blacklists]: Destination matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]: Main policy sources '!%internal_ips,!%internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]: Group 'internal_ips' has 1 source(s) => 10.0.0.0/8
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]=>(group:internal_ips): - Resolved source '10.0.0.0/8' to a IP/CIDR specification, match = 0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]=>(group:internal_ips): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]: Group 'internal_domains' has 0 source(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:8/Name:no_greylisting]: No group members for source group 'internal_domains'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]=>(group:internal_domains): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:8/Name:no_greylisting]: Source matching result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]: Main policy destinations '%no_greylisting_for_internal'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]: Group 'no_greylisting_for_internal' has 0 destination(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:8/Name:no_greylisting]: No group members for destination group 'no_greylisting_for_internal'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:8/Name:no_greylisting]=>(group:no_greylisting_for_internal): Destination group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:8/Name:no_greylisting]: Destination matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:9/Name:no_greylisting]: Main policy sources '%no_greylisting_for_external'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:9/Name:no_greylisting]: Group 'no_greylisting_for_external' has 0 source(s) =>
[2014/11/12-13:14:35 - 2734] [POLICIES] WARNING: [ID:9/Name:no_greylisting]: No group members for source group 'no_greylisting_for_external'
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:9/Name:no_greylisting]=>(group:no_greylisting_for_external): Source group result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:9/Name:no_greylisting]: Source matching result: matched=0
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: END RESULT: prio=0 => policy ids: 1
[2014/11/12-13:14:35 - 2734] [TRACKING] DEBUG: Policy resolved into: $VAR1 = {
'0' => [
'1'
]
};
[2014/11/12-13:14:35 - 2734] [TRACKING] DEBUG: Request translated into session data: $VAR1 = {
'Recipient' => 'parfenov@etpz.ru',
'SASLUsername' => '',
'QueueID' => '',
'RecipientData' => '',
'Instance' => 'acc.5463330b.5306e.0',
'EncryptionCipher' => '',
'Size' => '1141',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 1360216890,
'Network' => '81.19.67.58',
'IP_Long' => 1360216890,
'Broadcast' => '81.19.67.58',
'IP' => '81.19.67.58',
'Mask_Long' => 4294967295,
'Network_Long' => 1360216890
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'mxout1.rambler.ru',
'ClientAddress' => '81.19.67.58',
'ClientName' => 'mxout1.rambler.ru',
'Sender' => 'deviance@rambler.ru',
'SASLSender' => '',
'Timestamp' => 1415787275,
'ProtocolState' => 'RCPT',
'Policy' => {
'0' => [
'1'
]
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'mxout1.rambler.ru',
'SASLMethod' => ''
};
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Got request, running modules...
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Running module: Access Control Plugin
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Running module: HELO/EHLO Check Plugin
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Running module: SPF Check Plugin
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Running module: Greylisting Plugin
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2014/11/12-13:14:35 - 2734] [CBPOLICYD] DEBUG: Done with modules
[2014/11/12-13:14:36 - 2443] [CORE] INFO: 2014/11/12-13:14:36 CONNECT TCP Peer: "[127.0.0.1]:37232" Local: "[127.0.0.1]:10031"
[2014/11/12-13:14:36 - 2443] [TRACKING] DEBUG: Protocol state is 'END-OF-MESSAGE', decoding policy...
[2014/11/12-13:14:36 - 2443] [TRACKING] DEBUG: Decoded into: $VAR1 = {
'parfenov@etpz.ru' => {
'0' => [
'1'
]
}
};
[2014/11/12-13:14:36 - 2443] [TRACKING] DEBUG: Request translated into session data: $VAR1 = {
'SASLUsername' => '',
'QueueID' => '5D717E07D5',
'RecipientData' => '/<parfenov@etpz.ru>#0=1;',
'EncryptionCipher' => '',
'Instance' => 'acc.5463330b.5306e.0',
'Size' => '1107',
'EncryptionKeySize' => '0',
'ParsedClientAddress' => {
'Broadcast_Long' => 1360216890,
'Network' => '81.19.67.58',
'IP_Long' => 1360216890,
'Broadcast' => '81.19.67.58',
'IP' => '81.19.67.58',
'Mask_Long' => 4294967295,
'Network_Long' => 1360216890
},
'ProtocolTransport' => 'Postfix',
'EncryptionProtocol' => '',
'Helo' => 'mxout1.rambler.ru',
'ClientAddress' => '81.19.67.58',
'ClientName' => 'mxout1.rambler.ru',
'Sender' => 'deviance@rambler.ru',
'SASLSender' => '',
'Timestamp' => 1415787276,
'ProtocolState' => 'END-OF-MESSAGE',
'_Recipient_To_Policy' => {
'parfenov@etpz.ru' => {
'0' => [
'1'
]
}
},
'Protocol' => 'ESMTP',
'ClientReverseName' => 'mxout1.rambler.ru',
'SASLMethod' => ''
};
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Got request, running modules...
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Running module: Access Control Plugin
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Running module: HELO/EHLO Check Plugin
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Running module: SPF Check Plugin
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Running module: Greylisting Plugin
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Running module: Quotas Plugin
[2014/11/12-13:14:36 - 2443] [CBPOLICYD] DEBUG: Done with modules
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]: Group 'blacklists' has 1 source(s) => deviance@rambler.ru
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]=>(group:blacklists): - Resolved source 'deviance@rambler.ru' to a email address specification, match = 1
[2014/11/12-13:14:35 - 2734] [POLICIES] DEBUG: [ID:7/Name:blacklists]=>(group:blacklists): Source group result: matched=1
[2014/11/12-13:14:35 - 2734] [POLICIES] INFO: [ID:7/Name:blacklists]: Source matching result: matched=1