Hello Nigel! > Please enable full debugging in the config file for protocols and reply > with the full output.
I turned log_level to 4 (did not find anything specific about protocol logging), here's some output. Cheers, Martin [2011/09/28-09:30:02 - 7388] [CORE] INFO: Killing "1" children [2011/09/28-09:30:02 - 9257] [CBPOLICYD] DEBUG: Shutting down caching engine (9257) [2011/09/28-09:30:11 - 8850] [CORE] INFO: 2011/09/28-09:30:11 CONNECT TCP Peer: "127.0.0.1:59324" Local: "127.0.0.1:10031" [2011/09/28-09:30:11 - 7388] [CORE] INFO: Starting "1" children [2011/09/28-09:30:11 - 8850] [CBPOLICYD] ERROR: Protocol data validation error, required parameter 'instance' was not found or invalid format [2011/09/28-09:30:11 - 9676] [CORE] DEBUG: Child Preforked (9676) [2011/09/28-09:30:11 - 9676] [CBPOLICYD] DEBUG: Starting up caching engine [2011/09/28-09:30:51 - 7388] [CORE] INFO: Killing "1" children [2011/09/28-09:30:51 - 8126] [CBPOLICYD] DEBUG: Shutting down caching engine (8126) [2011/09/28-09:31:28 - 9200] [CORE] INFO: 2011/09/28-09:31:28 CONNECT TCP Peer: "127.0.0.1:59344" Local: "127.0.0.1:10031" [2011/09/28-09:31:28 - 7388] [CORE] INFO: Starting "1" children [2011/09/28-09:31:28 - 9200] [CBPOLICYD] ERROR: Protocol data validation error, required parameter 'instance' was not found or invalid format [2011/09/28-09:31:28 - 10573] [CORE] DEBUG: Child Preforked (10573) [2011/09/28-09:31:28 - 10573] [CBPOLICYD] DEBUG: Starting up caching engine [2011/09/28-09:31:30 - 7528] [CORE] INFO: 2011/09/28-09:31:30 CONNECT TCP Peer: "127.0.0.1:59353" Local: "127.0.0.1:10031" [2011/09/28-09:31:30 - 7528] [CHECKSPF] DEBUG: SPF result: bounces3.mailingwork.de: 62.169.6.172 is authorized to use '10-4463-335475-159765500-unatzna=tqatf...@bounces3.mailingwork.de' in 'mfrom' identity (mechanism 'ip4:62.169.6.128/26' matched) [2011/09/28-09:31:32 - 9676] [CORE] INFO: 2011/09/28-09:31:32 CONNECT TCP Peer: "127.0.0.1:59356" Local: "127.0.0.1:10031" [2011/09/28-09:31:56 - 7388] [CORE] INFO: Starting "1" children [2011/09/28-09:31:56 - 10573] [CORE] INFO: 2011/09/28-09:31:56 CONNECT TCP Peer: "127.0.0.1:59367" Local: "127.0.0.1:10031" [2011/09/28-09:31:56 - 10629] [CORE] DEBUG: Child Preforked (10629) [2011/09/28-09:31:56 - 10629] [CBPOLICYD] DEBUG: Starting up caching engine [2011/09/28-09:32:17 - 8850] [CORE] INFO: 2011/09/28-09:32:17 CONNECT TCP Peer: "127.0.0.1:59372" Local: "127.0.0.1:10031" [2011/09/28-09:32:17 - 8850] [CBPOLICYD] ERROR: Protocol data validation error, required parameter 'instance' was not found or invalid format [2011/09/28-09:32:57 - 7388] [CORE] INFO: Killing "1" children [2011/09/28-09:32:57 - 10573] [CBPOLICYD] DEBUG: Shutting down caching engine (10573) [2011/09/28-09:33:34 - 7388] [CORE] INFO: Starting "1" children [2011/09/28-09:33:34 - 9676] [CORE] INFO: 2011/09/28-09:33:34 CONNECT TCP Peer: "127.0.0.1:59392" Local: "127.0.0.1:10031" [2011/09/28-09:33:34 - 9676] [CBPOLICYD] ERROR: Protocol data validation error, required parameter 'instance' was not found or invalid format [2011/09/28-09:33:34 - 10689] [CORE] DEBUG: Child Preforked (10689) [2011/09/28-09:33:34 - 10689] [CBPOLICYD] DEBUG: Starting up caching engine [2011/09/28-09:33:58 - 9200] [CORE] INFO: 2011/09/28-09:33:58 CONNECT TCP Peer: "127.0.0.1:59400" Local: "127.0.0.1:10031" [2011/09/28-09:33:59 - 10629] [CORE] INFO: 2011/09/28-09:33:59 CONNECT TCP Peer: "127.0.0.1:59407" Local: "127.0.0.1:10031" [2011/09/28-09:34:00 - 9676] [CORE] INFO: 2011/09/28-09:34:00 CONNECT TCP Peer: "127.0.0.1:59408" Local: "127.0.0.1:10031" [2011/09/28-09:34:03 - 10689] [CORE] INFO: 2011/09/28-09:34:03 CONNECT TCP Peer: "127.0.0.1:59413" Local: "127.0.0.1:10031" [2011/09/28-09:34:12 - 8850] [CORE] INFO: 2011/09/28-09:34:12 CONNECT TCP Peer: "127.0.0.1:59417" Local: "127.0.0.1:10031" [2011/09/28-09:34:12 - 8850] [CHECKSPF] DEBUG: SPF result: news.intos.de: No applicable sender policy available [2011/09/28-09:34:13 - 10629] [CORE] INFO: 2011/09/28-09:34:13 CONNECT TCP Peer: "127.0.0.1:59418" Local: "127.0.0.1:10031" [2011/09/28-09:34:16 - 9676] [CORE] INFO: 2011/09/28-09:34:16 CONNECT TCP Peer: "127.0.0.1:59423" Local: "127.0.0.1:10031" [2011/09/28-09:34:23 - 9200] [CORE] INFO: 2011/09/28-09:34:23 CONNECT TCP Peer: "127.0.0.1:59429" Local: "127.0.0.1:10031" [2011/09/28-09:34:23 - 9200] [CBPOLICYD] ERROR: Protocol data validation error, required parameter 'instance' was not found or invalid format [2011/09/28-09:34:27 - 10629] [CORE] INFO: 2011/09/28-09:34:27 CONNECT TCP Peer: "127.0.0.1:59434" Local: "127.0.0.1:10031" [2011/09/28-09:34:27 - 7388] [CORE] INFO: Starting "1" children [2011/09/28-09:34:27 - 10846] [CORE] DEBUG: Child Preforked (10846) [2011/09/28-09:34:27 - 10846] [CBPOLICYD] DEBUG: Starting up caching engine [2011/09/28-09:34:28 - 9676] [CORE] INFO: 2011/09/28-09:34:28 CONNECT TCP Peer: "127.0.0.1:59435" Local: "127.0.0.1:10031" [2011/09/28-09:34:31 - 9200] [CORE] INFO: 2011/09/28-09:34:31 CONNECT TCP Peer: "127.0.0.1:59441" Local: "127.0.0.1:10031" [2011/09/28-09:35:11 - 7388] [CORE] INFO: Killing "1" children [2011/09/28-09:35:11 - 10629] [CBPOLICYD] DEBUG: Shutting down caching engine (10629) _______________________________________________ Users mailing list [email protected] http://lists.policyd.org/mailman/listinfo/users
