weixin_39595008
weixin_39595008
2020-11-27 16:48

[BUG]request_header MTA-Tag not working in user settings for {daemon_name} Postfix macro

Prerequisites

  • [X] Put an X between the brackets on this line if you have done all of the following:
    • Read about bug reporting in general: https://rspamd.com/doc/faq.html#how-to-report-bugs-found-in-rspamd
    • Enabled relevant debugging logs: https://rspamd.com/doc/faq.html#how-to-debug-some-module-in-rspamd
    • Checked the FAQs about Core files in case of fatal crash: https://rspamd.com/doc/faq.html#how-to-figure-out-why-rspamd-process-crashed
    • Checked that your issue isn't already filed: https://github.com/issues?utf8=%E2%9C%93&q=is%3Aissue+user%3Arspamd
    • Checked that there is not already an experimental package or master branch

Describe the bug I have set milter_macro_daemon_name=TESTING in Postfix and have defined user settings in Rspamd that shall match, if that macro was found.

Unfortunately it never matches. So the defined rule is not used.

Steps to Reproduce

  1. In Postfix define for example:

127.0.1.1:1125
          inet  n       -       n       -       -       smtpd -v
    -o syslog_name=postfix/testing
    -o smtpd_milters=${rspamd}
    -o mynetworks=127.0.0.0/8
    -o milter_macro_daemon_name=TESTING
    ...
  1. Use the following settings:

    TESTING {
        id = "testing";
        symbols = [ "POLICY_TESTING" ];
        priority = 100;
        request_header {
            "MTA-Tag" = "TESTING";
        }
        apply {
            POLICY_TESTING = -5.0;
            add_headers {
                X-Rspamd-Policy = "Policy set to POLICY_TESTING";
            }
            actions {
                reject = null;
                greylist = null;
            }
            symbols_disabled = [
                "GREYLIST_SAVE",
                "GREYLIST_CHECK"
            ]
        }
    }
  1. Send an email with swaks:

swaks --local-interface 127.0.1.1 --ehlo mx.example.com --server 127.0.1.1:1125 --from sender.com --to recipient.com

Expected behavior The rule TESTING should match.

Versions

(master branch) Rspamd daemon version 2.0

Additional Information

Here is my debug log with "milter" turned on:


2019-10-09 18:12:50.90316 #31819(rspamd_proxy) <59b7fa>; proxy; proxy_accept_socket: accepted milter connection from 127.0.0.1 port 58776
2019-10-09 18:12:50.90327 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_handle_session: read -1 bytes, 0 remain, 65541 allocated
2019-10-09 18:12:50.90346 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_handle_session: read 17 bytes, 0 remain, 65541 allocated
2019-10-09 18:12:50.90347 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 0, state: 0
2019-10-09 18:12:50.90348 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 1, state: 1
2019-10-09 18:12:50.90349 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 2, state: 2
2019-10-09 18:12:50.90349 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 3, state: 3
2019-10-09 18:12:50.90350 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 4, state: 4
2019-10-09 18:12:50.90351 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 5, state: 5
2019-10-09 18:12:50.90353 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: optneg: version: 6, actions: 511, protocol: 2097151
2019-10-09 18:12:50.90354 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: optneg reply: ver=6, actions=511, protocol=1044608
2019-10-09 18:12:50.92695 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_handle_session: read 514 bytes, 0 remain, 65541 allocated
2019-10-09 18:12:50.92700 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 0, state: 0
2019-10-09 18:12:50.92701 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 1, state: 1
2019-10-09 18:12:50.92701 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 2, state: 2
2019-10-09 18:12:50.92702 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 3, state: 3
2019-10-09 18:12:50.92703 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 4, state: 4
2019-10-09 18:12:50.92704 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 5, state: 5
2019-10-09 18:12:50.92705 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.92707 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: j -> mx.roessner-net.de
2019-10-09 18:12:50.92711 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: v -> Postfix 3.4.7
2019-10-09 18:12:50.92713 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {client_ptr} -> unknown
2019-10-09 18:12:50.92715 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {daemon_name} -> TESTING
2019-10-09 18:12:50.92716 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {daemon_addr} -> 127.0.1.1
2019-10-09 18:12:50.92716 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {daemon_port} -> 1125
2019-10-09 18:12:50.92717 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 129, state: 0
2019-10-09 18:12:50.92718 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 130, state: 1
2019-10-09 18:12:50.92718 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 131, state: 2
2019-10-09 18:12:50.92719 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 132, state: 3
2019-10-09 18:12:50.92720 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 133, state: 4
2019-10-09 18:12:50.92720 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 134, state: 5
2019-10-09 18:12:50.92721 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got connect command
2019-10-09 18:12:50.92722 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got hostname on connect phase: [127.0.1.1]
2019-10-09 18:12:50.92724 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got connection from 127.0.1.1:37559
2019-10-09 18:12:50.92725 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 159, state: 0
2019-10-09 18:12:50.92725 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 160, state: 1
2019-10-09 18:12:50.92726 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 161, state: 2
2019-10-09 18:12:50.92726 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 162, state: 3
2019-10-09 18:12:50.92727 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 163, state: 4
2019-10-09 18:12:50.92728 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 164, state: 5
2019-10-09 18:12:50.92728 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.92729 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 165, state: 0
2019-10-09 18:12:50.92729 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 166, state: 1
2019-10-09 18:12:50.92730 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 167, state: 2
2019-10-09 18:12:50.92731 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 168, state: 3
2019-10-09 18:12:50.92731 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 169, state: 4
2019-10-09 18:12:50.92732 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 170, state: 5
2019-10-09 18:12:50.92732 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got helo command
2019-10-09 18:12:50.92733 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got helo value: mx.roessner-net.de
2019-10-09 18:12:50.92734 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 189, state: 0
2019-10-09 18:12:50.92734 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 190, state: 1
2019-10-09 18:12:50.92735 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 191, state: 2
2019-10-09 18:12:50.92735 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 192, state: 3
2019-10-09 18:12:50.92736 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 193, state: 4
2019-10-09 18:12:50.92737 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 194, state: 5
2019-10-09 18:12:50.92738 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.92739 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {mail_addr} -> system.net
2019-10-09 18:12:50.92740 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {mail_host} -> localhost.localdomain:24
2019-10-09 18:12:50.92741 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {mail_mailer} -> lmtps
2019-10-09 18:12:50.92742 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {client_name} -> unknown
2019-10-09 18:12:50.92742 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {client_addr} -> 127.0.1.1
2019-10-09 18:12:50.92743 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 328, state: 0
2019-10-09 18:12:50.92744 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 329, state: 1
2019-10-09 18:12:50.92744 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 330, state: 2
2019-10-09 18:12:50.92745 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 331, state: 3
2019-10-09 18:12:50.92746 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 332, state: 4
2019-10-09 18:12:50.92746 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 333, state: 5
2019-10-09 18:12:50.92747 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: mail command
2019-10-09 18:12:50.92748 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got mail: <system.net>
2019-10-09 18:12:50.92750 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 353, state: 0
2019-10-09 18:12:50.92751 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 354, state: 1
2019-10-09 18:12:50.92752 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 355, state: 2
2019-10-09 18:12:50.92753 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 356, state: 3
2019-10-09 18:12:50.92753 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 357, state: 4
2019-10-09 18:12:50.92754 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 358, state: 5
2019-10-09 18:12:50.92754 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.92755 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {rcpt_addr} -> christian.email
2019-10-09 18:12:50.92756 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {rcpt_host} -> localhost.localdomain:24
2019-10-09 18:12:50.92757 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: {rcpt_mailer} -> lmtps
2019-10-09 18:12:50.92757 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 453, state: 0
2019-10-09 18:12:50.92758 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 454, state: 1
2019-10-09 18:12:50.92759 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 455, state: 2
2019-10-09 18:12:50.92759 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 456, state: 3
2019-10-09 18:12:50.92760 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 457, state: 4
2019-10-09 18:12:50.92761 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 458, state: 5
2019-10-09 18:12:50.92761 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: rcpt command
2019-10-09 18:12:50.92762 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got rcpt: <christian.email>
2019-10-09 18:12:50.92763 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 485, state: 0
2019-10-09 18:12:50.92764 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 486, state: 1
2019-10-09 18:12:50.92765 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 487, state: 2
2019-10-09 18:12:50.92765 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 488, state: 3
2019-10-09 18:12:50.92766 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 489, state: 4
2019-10-09 18:12:50.92767 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 490, state: 5
2019-10-09 18:12:50.92767 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.92768 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.92769 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 509, state: 0
2019-10-09 18:12:50.92769 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 510, state: 1
2019-10-09 18:12:50.92770 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 511, state: 2
2019-10-09 18:12:50.92771 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 512, state: 3
2019-10-09 18:12:50.92771 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 513, state: 4
2019-10-09 18:12:50.92773 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got data command
2019-10-09 18:12:50.96727 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_handle_session: read 499 bytes, 0 remain, 65541 allocated
2019-10-09 18:12:50.96730 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 0, state: 0
2019-10-09 18:12:50.96731 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 1, state: 1
2019-10-09 18:12:50.96732 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 2, state: 2
2019-10-09 18:12:50.96733 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 3, state: 3
2019-10-09 18:12:50.96734 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 4, state: 4
2019-10-09 18:12:50.96735 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 5, state: 5
2019-10-09 18:12:50.96735 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96736 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96737 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 24, state: 0
2019-10-09 18:12:50.96738 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 25, state: 1
2019-10-09 18:12:50.96738 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 26, state: 2
2019-10-09 18:12:50.96739 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 27, state: 3
2019-10-09 18:12:50.96740 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 28, state: 4
2019-10-09 18:12:50.96740 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 29, state: 5
2019-10-09 18:12:50.96741 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got header command
2019-10-09 18:12:50.96742 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 52, state: 0
2019-10-09 18:12:50.96743 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 53, state: 1
2019-10-09 18:12:50.96743 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 54, state: 2
2019-10-09 18:12:50.96744 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 55, state: 3
2019-10-09 18:12:50.96745 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 56, state: 4
2019-10-09 18:12:50.96745 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 57, state: 5
2019-10-09 18:12:50.96748 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96749 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96749 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 76, state: 0
2019-10-09 18:12:50.96750 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 77, state: 1
2019-10-09 18:12:50.96751 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 78, state: 2
2019-10-09 18:12:50.96751 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 79, state: 3
2019-10-09 18:12:50.96752 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 80, state: 4
2019-10-09 18:12:50.96753 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 81, state: 5
2019-10-09 18:12:50.96753 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got header command
2019-10-09 18:12:50.96754 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 118, state: 0
2019-10-09 18:12:50.96755 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 119, state: 1
2019-10-09 18:12:50.96755 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 120, state: 2
2019-10-09 18:12:50.96756 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 121, state: 3
2019-10-09 18:12:50.96757 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 122, state: 4
2019-10-09 18:12:50.96757 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 123, state: 5
2019-10-09 18:12:50.96758 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96758 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96759 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 142, state: 0
2019-10-09 18:12:50.96760 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 143, state: 1
2019-10-09 18:12:50.96760 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 144, state: 2
2019-10-09 18:12:50.96761 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 145, state: 3
2019-10-09 18:12:50.96762 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 146, state: 4
2019-10-09 18:12:50.96762 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 147, state: 5
2019-10-09 18:12:50.96763 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got header command
2019-10-09 18:12:50.96764 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 175, state: 0
2019-10-09 18:12:50.96764 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 176, state: 1
2019-10-09 18:12:50.96765 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 177, state: 2
2019-10-09 18:12:50.96766 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 178, state: 3
2019-10-09 18:12:50.96766 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 179, state: 4
2019-10-09 18:12:50.96767 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 180, state: 5
2019-10-09 18:12:50.96767 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96768 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96769 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 199, state: 0
2019-10-09 18:12:50.96769 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 200, state: 1
2019-10-09 18:12:50.96770 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 201, state: 2
2019-10-09 18:12:50.96771 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 202, state: 3
2019-10-09 18:12:50.96772 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 203, state: 4
2019-10-09 18:12:50.96773 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 204, state: 5
2019-10-09 18:12:50.96773 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got header command
2019-10-09 18:12:50.96774 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 227, state: 0
2019-10-09 18:12:50.96775 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 228, state: 1
2019-10-09 18:12:50.96775 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 229, state: 2
2019-10-09 18:12:50.96776 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 230, state: 3
2019-10-09 18:12:50.96777 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 231, state: 4
2019-10-09 18:12:50.96777 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 232, state: 5
2019-10-09 18:12:50.96778 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96778 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96779 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 251, state: 0
2019-10-09 18:12:50.96780 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 252, state: 1
2019-10-09 18:12:50.96780 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 253, state: 2
2019-10-09 18:12:50.96781 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 254, state: 3
2019-10-09 18:12:50.96782 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 255, state: 4
2019-10-09 18:12:50.96782 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 256, state: 5
2019-10-09 18:12:50.96783 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got header command
2019-10-09 18:12:50.96783 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 301, state: 0
2019-10-09 18:12:50.96784 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 302, state: 1
2019-10-09 18:12:50.96785 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 303, state: 2
2019-10-09 18:12:50.96785 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 304, state: 3
2019-10-09 18:12:50.96786 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 305, state: 4
2019-10-09 18:12:50.96787 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 306, state: 5
2019-10-09 18:12:50.96787 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96788 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96789 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 325, state: 0
2019-10-09 18:12:50.96789 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 326, state: 1
2019-10-09 18:12:50.96790 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 327, state: 2
2019-10-09 18:12:50.96790 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 328, state: 3
2019-10-09 18:12:50.96791 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 329, state: 4
2019-10-09 18:12:50.96792 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 330, state: 5
2019-10-09 18:12:50.96792 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got header command
2019-10-09 18:12:50.96793 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 386, state: 0
2019-10-09 18:12:50.96794 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 387, state: 1
2019-10-09 18:12:50.96795 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 388, state: 2
2019-10-09 18:12:50.96796 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 389, state: 3
2019-10-09 18:12:50.96796 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 390, state: 4
2019-10-09 18:12:50.96797 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 391, state: 5
2019-10-09 18:12:50.96797 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96798 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96799 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 410, state: 0
2019-10-09 18:12:50.96800 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 411, state: 1
2019-10-09 18:12:50.96800 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 412, state: 2
2019-10-09 18:12:50.96801 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 413, state: 3
2019-10-09 18:12:50.96801 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 414, state: 4
2019-10-09 18:12:50.96802 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 415, state: 5
2019-10-09 18:12:50.96803 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got eoh command
2019-10-09 18:12:50.96803 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 415, state: 0
2019-10-09 18:12:50.96804 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 416, state: 1
2019-10-09 18:12:50.96805 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 417, state: 2
2019-10-09 18:12:50.96805 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 418, state: 3
2019-10-09 18:12:50.96806 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 419, state: 4
2019-10-09 18:12:50.96806 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 420, state: 5
2019-10-09 18:12:50.96807 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96808 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96808 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 439, state: 0
2019-10-09 18:12:50.96809 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 440, state: 1
2019-10-09 18:12:50.96810 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 441, state: 2
2019-10-09 18:12:50.96810 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 442, state: 3
2019-10-09 18:12:50.96811 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 443, state: 4
2019-10-09 18:12:50.96811 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 444, state: 5
2019-10-09 18:12:50.96812 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got body chunk: 26 bytes
2019-10-09 18:12:50.96814 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 470, state: 0
2019-10-09 18:12:50.96815 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 471, state: 1
2019-10-09 18:12:50.96816 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 472, state: 2
2019-10-09 18:12:50.96816 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 473, state: 3
2019-10-09 18:12:50.96817 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 474, state: 4
2019-10-09 18:12:50.96818 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 475, state: 5
2019-10-09 18:12:50.96818 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro command
2019-10-09 18:12:50.96820 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got macro: i -> 46pK4f6Xx3zMktM
2019-10-09 18:12:50.96821 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 494, state: 0
2019-10-09 18:12:50.96822 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 495, state: 1
2019-10-09 18:12:50.96823 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 496, state: 2
2019-10-09 18:12:50.96823 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 497, state: 3
2019-10-09 18:12:50.96824 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 498, state: 4
2019-10-09 18:12:50.96824 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got eob command
2019-10-09 18:12:50.97277 #31819(rspamd_proxy) <59b7fa>; proxy; rspamd_mime_parse_message: cannot find content-type for a message, assume text/plain
2019-10-09 18:12:50.97281 #31819(rspamd_proxy) <59b7fa>; proxy; rspamd_mime_part_get_cte: detected missing CTE for part as: 7bit
2019-10-09 18:12:50.97292 #31819(rspamd_proxy) <59b7fa>; proxy; rspamd_message_parse: loaded message; id: <undef>; queue-id: <46pK4f6Xx3zMktM>; size: 252; checksum: <0324da7d47cf5c6f35e267c7d1b8dcdf>
2019-10-09 18:12:50.97374 #31819(rspamd_proxy) <59b7fa>; lua; settings.lua:333: <undef> apply settings according to rule RNSNETWORK (local matched)
2019-10-09 18:12:50.97434 #31819(rspamd_proxy) <59b7fa>; proxy; lua_task_set_settings: disabled action greylist due to settings
2019-10-09 18:12:50.97436 #31819(rspamd_proxy) <59b7fa>; proxy; lua_task_set_settings: disabled action reject due to settings
2019-10-09 18:12:51.02182 #31819(rspamd_proxy) <59b7fa>; proxy; spf_symbol_callback: skip SPF checks for local networks and authorized users
2019-10-09 18:12:51.02188 #31819(rspamd_proxy) <59b7fa>; proxy; dkim_symbol_callback: skip DKIM checks for local networks and authorized users
2019-10-09 18:12:51.02257 #31819(rspamd_proxy) <59b7fa>; lua; dmarc.lua:572: skip DMARC checks for local networks and authorized users
2019-10-09 18:12:51.02426 #31819(rspamd_proxy) <59b7fa>; lua; once_received.lua:95: Skipping once_received for authenticated user or local network
2019-10-09 18:12:55.30191 #31819(rspamd_proxy) <59b7fa>; lua; dcc.lua:255: dcc: clean, returned result A - info: body=0
2019-10-09 18:12:55.30449 #31819(rspamd_proxy) <59b7fa>; lua; spamtrap.lua:48: skip spamtrap checks for local networks or authenticated user
2019-10-09 18:12:55.30894 #31819(rspamd_proxy) <59b7fa>; lua; neural.lua:289: created new ANN profile for SHORT:default, data stored at prefix rn_SHORT_default_qqxwrdjp_0
2019-10-09 18:12:55.30961 #31819(rspamd_proxy) <59b7fa>; lua; neural.lua:289: created new ANN profile for LONG:default, data stored at prefix rn_LONG_default_qqxwrdjp_0
2019-10-09 18:12:55.31108 #31819(rspamd_proxy) <59b7fa>; proxy; rspamd_task_write_log: id: <undef>, qid: <46pK4f6Xx3zMktM>, ip: 127.0.1.1, from: <system.net>, (default: F (no action): [-6.14/7.60], user_settings: POLICY_RNSNETWORK, symbols: [ARC_NA(0.00),GENERIC_REPUTATION(0.00){-0.9588599225769},TO_MATCH_ENVRCPT_ALL(0.00),MIME_GOOD(-0.10){text/plain},ARC_SIGNED(0.00){i=1},TO_DN_NONE(0.00),RCPT_COUNT_ONE(0.00){1},MISSING_MID(2.50),FROM_NO_DN(0.00),RCVD_COUNT_ZERO(0.00){0},FROM_EQ_ENVFROM(0.00),MIME_TRACE(0.00){0:+},BAYES_HAM(-3.55){98.51%}], len: 252, time: 4341.644ms real, 4341.644ms virtual, dns req: 1 digest: <0324da7d47cf5c6f35e267c7d1b8dcdf>, rcpts: <christian.email>, mime_rcpts: <christian.email> subject: "test Wed, 09 Oct 2019 18:12:50 +0200"
2019-10-09 18:12:55.31110 #31819(rspamd_proxy) <59b7fa>; proxy; rspamd_protocol_http_reply: regexp statistics: 44 pcre regexps scanned, 0 regexps matched, 184 regexps total, 12 regexps cached, 1.49KiB scanned using pcre, 1.49KiB scanned total
2019-10-09 18:12:55.31123 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: add header command - "X-Rspamd-Policy"="Policy set to POLICY_RNSNETWORK"
2019-10-09 18:12:55.31127 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: add header command - "ARC-Message-Signature"="i=1; a=rsa-sha256; c=relaxed/relaxed; d=srvint.net;
2019-10-09 18:12:55.31128 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: add header command - "ARC-Seal"="i=1; s=BD87C431-DD13-4C61-99A3-446B863A64D6; d=srvint.net;
2019-10-09 18:12:55.31129 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: add header command - "ARC-Authentication-Results"="i=1;
2019-10-09 18:12:55.31130 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: add header command - "Authentication-Results"="TESTING;
2019-10-09 18:12:55.31131 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_send_action: send a command
2019-10-09 18:12:55.31131 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup common data on abort
2019-10-09 18:12:55.31133 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup 1 recipients on abort
2019-10-09 18:12:55.31134 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup from
2019-10-09 18:12:55.31135 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup headers
2019-10-09 18:12:55.32518 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_handle_session: read 15 bytes, 0 remain, 65541 allocated
2019-10-09 18:12:55.32522 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 0, state: 0
2019-10-09 18:12:55.32523 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 1, state: 1
2019-10-09 18:12:55.32524 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 2, state: 2
2019-10-09 18:12:55.32525 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 3, state: 3
2019-10-09 18:12:55.32525 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 4, state: 4
2019-10-09 18:12:55.32526 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 5, state: 5
2019-10-09 18:12:55.32527 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got abort command
2019-10-09 18:12:55.32528 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup common data on abort
2019-10-09 18:12:55.32529 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup headers
2019-10-09 18:12:55.32530 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 5, state: 0
2019-10-09 18:12:55.32531 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 6, state: 1
2019-10-09 18:12:55.32532 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 7, state: 2
2019-10-09 18:12:55.32532 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 8, state: 3
2019-10-09 18:12:55.32533 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 9, state: 4
2019-10-09 18:12:55.32534 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 10, state: 5
2019-10-09 18:12:55.32535 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: got abort command
2019-10-09 18:12:55.32537 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup common data on abort
2019-10-09 18:12:55.32538 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup headers
2019-10-09 18:12:55.32538 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 10, state: 0
2019-10-09 18:12:55.32539 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 11, state: 1
2019-10-09 18:12:55.32540 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 12, state: 2
2019-10-09 18:12:55.32540 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 13, state: 3
2019-10-09 18:12:55.32541 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_consume_input: offset: 14, state: 4
2019-10-09 18:12:55.32543 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_process_command: quit command, refcount: 1
2019-10-09 18:12:55.32552 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_dtor: destroying milter session
2019-10-09 18:12:55.32554 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup IO on abort
2019-10-09 18:12:55.32555 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup common data on abort
2019-10-09 18:12:55.32555 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup headers
2019-10-09 18:12:55.32556 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup addr
2019-10-09 18:12:55.32557 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup hostname
2019-10-09 18:12:55.32557 #31819(rspamd_proxy) <59b7fa>; milter; rspamd_milter_session_reset: cleanup macros
</christian.email></christian.email></system.net></undef></undef></undef></christian.email></system.net>

If you look closer to the logs, you can see that it chose POLICY_RNSNETWORK, which looks like this:


    RNSNETWORK {
        symbols = [ "POLICY_RNSNETWORK" ];
        priority = medium;
        local = yes;
        apply {
            POLICY_RNSNETWORK = -5.0;
            add_headers {
                X-Rspamd-Policy = "Policy set to POLICY_RNSNETWORK";
            }
            actions {
                reject = null;
                greylist = null;
            }
            symbols_disabled = [
                "GREYLIST_SAVE",
                "GREYLIST_CHECK"
            ]
        }
    }

That does have a much lower priority as TESTING and therefor must never win a match.

If I remove the request_header section from the TESTING rule and set ip = 127.0.1.1, the rule wins and matches as expected. So for some reason, the request_header is ignored or not processed.

A second strange thing is that "127.0.1.1" is not listed in local_addrs and should never match local=true in the RNSNETWORK rule!


rspamadm configdump options
*** Section options ***
...
local_addrs [
    "192.168.0.0/16",
    "10.0.0.0/8",
    "172.16.0.0/12",
    "fd00::/8",
    "169.254.0.0/16",
    "fe80::/10",
    "134.255.226.240/28",
    "2a05:bec0:28::/48",
    "109.230.236.221",
    "2a05:bec0:1:1:9cc1:28ff:fed1:9b34",
    "127.2.4.7",
]
...

该提问来源于开源项目:rspamd/rspamd

  • 点赞
  • 写回答
  • 关注问题
  • 收藏
  • 复制链接分享
  • 邀请回答

7条回答

  • weixin_39595008 weixin_39595008 4月前

    BTW: Yes I know that in this example both rules are eual, but that is not the point ;-)

    点赞 评论 复制链接分享
  • weixin_39595008 weixin_39595008 4月前

    Is more information required? Is the bug report clear enough?

    点赞 评论 复制链接分享
  • weixin_39942995 weixin_39942995 4月前

    request_header { "MTA-Name" = "TESTING"; }

    As I never figured out if Tag or Name is the correct value I always try both. Currently MTA-Name is matching in my settings (2.0)

    点赞 评论 复制链接分享
  • weixin_39595008 weixin_39595008 4月前

    Thanks -rosenberg , unfortunately even this does not work here. I just changed it to MTA-Name and ran a test, but it still matches the wrong policy with lower priority.

    点赞 评论 复制链接分享
  • weixin_39595008 weixin_39595008 4月前

    Update: When -rosenberg said I could try MTA-Name instead of MTA-Tag, I changes my settings.map in the evening hours (about 10:00pm), but did not reload anything. Simply saving. I have a override for settings=, to load settings.conf not from the local.d/settings.conf, instead loading it as a map file from maps.d/settings.map.

    I tested the changed tag this morning without success, as I reported before.

    5 minutes ago (before this comment), I had to restart rspamd on this server and now the map changes worked. I had changed debug_modules to show "protocol" details. To have this active, I restarted Rspamd. So now it looks like the following:

    1. It works with MTA-Name (so the docs should be adopted)
    2. Map-reloading is not working perfectly in current master branch and versions before.

    should I change the subject now to make this more clear

    点赞 评论 复制链接分享
  • weixin_39616367 weixin_39616367 4月前

    Is it still an issue?

    点赞 评论 复制链接分享
  • weixin_39875842 weixin_39875842 4月前

    This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

    点赞 评论 复制链接分享