Loading ...
Sorry, an error occurred while loading the content.

understanding address_verify_poll_delay

Expand Messages
  • btb
    with respect to my previous question about address verification, i think i m not understanding address_verify_poll_delay correctly. while working on
    Message 1 of 6 , Jul 9, 2014
    • 0 Attachment
      with respect to my previous question about address verification, i think
      i'm not understanding address_verify_poll_delay correctly. while
      working on troubleshooting the 6.2 second delay during the smtp
      handshake, i'd set address_verify_poll_delay to 15 seconds, expecting
      that postfix would then wait up to that long for verification of an
      address to occur. after doing this, i noticed 15 second delays in
      between each recipient verification, which i wasn't expecting [see below
      log excerpt]. is this expected? i interpreted an address verification
      request to be each of the individual probes shown in the log excerpt,
      and so expected postfix to wait up to that long for each to complete,
      but not wait that long in between each probe once successful.

      thanks
      -ben

      Jul 9 16:48:16 msa01 postfix/smtpd[31514]: connect from
      clientl.example.com[10.68.15.100]
      Jul 9 16:48:16 msa01 postfix/smtpd[31514]: Anonymous TLS connection
      established from clientl.example.com[10.68.15.100]: TLSv1 with cipher
      ECDHE-RSA-AES256-SHA (256/256 bits)
      Jul 9 16:48:16 msa01 postfix/cleanup[31493]: 3h7szh5t3yzJnMV:
      message-id=<3h7szh5t3yzJnMV@...>
      Jul 9 16:48:16 msa01 postfix/qmgr[24500]: 3h7szh5t3yzJnMV:
      from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
      Jul 9 16:48:16 msa01 postfix/lmtp[31505]: 3h7szh5t3yzJnMV:
      to=<testuser-3000@...>, relay=mda.example.com[10.59.1.57]:7025,
      delay=0.04, delays=0.03/0/0.01/0.01, dsn=2.1.5, status=deliverable (250
      2.1.5 Recipient OK)
      Jul 9 16:48:16 msa01 postfix/qmgr[24500]: 3h7szh5t3yzJnMV: removed
      Jul 9 16:48:31 msa01 postfix/smtpd[31514]: 3h7szz6skPzJnMV:
      client=clientl.example.com[10.68.15.100], sasl_method=PLAIN,
      sasl_username=user01
      Jul 9 16:48:32 msa01 postfix/cleanup[31520]: 3h7szz74sKzJnP1:
      message-id=<3h7szz74sKzJnP1@...>
      Jul 9 16:48:32 msa01 postfix/qmgr[24500]: 3h7szz74sKzJnP1:
      from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
      Jul 9 16:48:32 msa01 postfix/lmtp[31512]: 3h7szz74sKzJnP1:
      to=<testuser-3001@...>, relay=mda.example.com[10.59.1.57]:7025,
      delay=0.25, delays=0.24/0/0.01/0.01, dsn=2.1.5, status=deliverable (250
      2.1.5 Recipient OK)
      Jul 9 16:48:32 msa01 postfix/qmgr[24500]: 3h7szz74sKzJnP1: removed
      Jul 9 16:48:47 msa01 postfix/cleanup[31520]: 3h7t0H0BFrzJnP1:
      message-id=<3h7t0H0BFrzJnP1@...>
      Jul 9 16:48:47 msa01 postfix/qmgr[24500]: 3h7t0H0BFrzJnP1:
      from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
      Jul 9 16:48:47 msa01 postfix/lmtp[31505]: 3h7t0H0BFrzJnP1:
      to=<testuser-3002@...>, relay=mda.example.com[10.59.1.57]:7025,
      delay=0.04, delays=0.01/0/0.01/0.01, dsn=2.1.5, status=deliverable (250
      2.1.5 Recipient OK)
      Jul 9 16:48:47 msa01 postfix/qmgr[24500]: 3h7t0H0BFrzJnP1: removed
      Jul 9 16:49:02 msa01 postfix/cleanup[31520]: 3h7t0Z0VHwzJnP1:
      message-id=<3h7t0Z0VHwzJnP1@...>
      Jul 9 16:49:02 msa01 postfix/qmgr[24500]: 3h7t0Z0VHwzJnP1:
      from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
      Jul 9 16:49:02 msa01 postfix/lmtp[31512]: 3h7t0Z0VHwzJnP1:
      to=<testuser-3003@...>, relay=mda.example.com[10.59.1.57]:7025,
      delay=0.02, delays=0.01/0/0/0.01, dsn=2.1.5, status=deliverable (250
      2.1.5 Recipient OK)
      Jul 9 16:49:02 msa01 postfix/qmgr[24500]: 3h7t0Z0VHwzJnP1: removed
      Jul 9 16:49:17 msa01 postfix/cleanup[31520]: 3h7t0s0qsyzJnP1:
      message-id=<3h7t0s0qsyzJnP1@...>
      Jul 9 16:49:17 msa01 postfix/qmgr[24500]: 3h7t0s0qsyzJnP1:
      from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
      Jul 9 16:49:17 msa01 postfix/lmtp[31505]: 3h7t0s0qsyzJnP1:
      to=<testuser-3004@...>, relay=mda.example.com[10.59.1.57]:7025,
      delay=0.03, delays=0.01/0/0.01/0, dsn=2.1.5, status=deliverable (250
      2.1.5 Recipient OK)
      Jul 9 16:49:17 msa01 postfix/qmgr[24500]: 3h7t0s0qsyzJnP1: removed
    • Wietse Venema
      ... address_verify_poll_delay (default: 3s) The DELAY BETWEEN QUERIES for the completion of an address verification request in progress.
      Message 2 of 6 , Jul 9, 2014
      • 0 Attachment
        btb:
        > with respect to my previous question about address verification, i think
        > i'm not understanding address_verify_poll_delay correctly. while
        > working on troubleshooting the 6.2 second delay during the smtp
        > handshake, i'd set address_verify_poll_delay to 15 seconds, expecting
        > that postfix would then wait up to that long for verification of an
        > address to occur.

        address_verify_poll_delay (default: 3s)
        The DELAY BETWEEN QUERIES for the completion of an address verification
        request in progress.

        address_verify_poll_count (default: normal: 3, overload: 1)
        HOW MANY TIMES TO QUERY the verify(8) service for the completion of an
        address verification request in progress.

        Wietse
      • btb@...
        ... it is this exact wording which left me confused. is each queue id a single address verification request? or is the entire session while the client waits
        Message 3 of 6 , Jul 9, 2014
        • 0 Attachment
          On Jul 9, 2014, at 18.48, Wietse Venema <wietse@...> wrote:

          > btb:
          >> with respect to my previous question about address verification, i think
          >> i'm not understanding address_verify_poll_delay correctly. while
          >> working on troubleshooting the 6.2 second delay during the smtp
          >> handshake, i'd set address_verify_poll_delay to 15 seconds, expecting
          >> that postfix would then wait up to that long for verification of an
          >> address to occur.
          >
          > address_verify_poll_delay (default: 3s)
          > The DELAY BETWEEN QUERIES for the completion of an address verification
          > request in progress.

          it is this exact wording which left me confused. is each queue id a single address verification request? or is the entire session while the client waits for all addresses to be verified a single address verification request? logic would seem to indicate the former. in any case, i want postfix to wait up to 15 seconds for each address before deciding the status isn’t deliverable, not wait for 15 seconds idling after a verification request takes a fraction of a second to complete. how can these two elements of time be set independent of each other?

          -ben
        • Wietse Venema
          btb@bitrate.net: [ Charset windows-1252 converted... ] ... As documented: address_verify_poll_count (default: normal: 3, overload: 1) HOW MANY TIMES TO QUERY
          Message 4 of 6 , Jul 9, 2014
          • 0 Attachment
            btb@...:
            [ Charset windows-1252 converted... ]
            >
            > On Jul 9, 2014, at 18.48, Wietse Venema <wietse@...> wrote:
            >
            > > btb:
            > >> with respect to my previous question about address verification, i think
            > >> i'm not understanding address_verify_poll_delay correctly. while
            > >> working on troubleshooting the 6.2 second delay during the smtp
            > >> handshake, i'd set address_verify_poll_delay to 15 seconds, expecting
            > >> that postfix would then wait up to that long for verification of an
            > >> address to occur.
            > >
            > > address_verify_poll_delay (default: 3s)
            > > The DELAY BETWEEN QUERIES for the completion of an address verification
            > > request in progress.
            >
            > it is this exact wording which left me confused. is each queue
            > id a single address verification request? or is the entire session
            > while the client waits for all addresses to be verified a single
            > address verification request? logic would seem to indicate the
            > former. in any case, i want postfix to wait up to 15 seconds for
            > each address before deciding the status isn?t deliverable, not
            > wait for 15 seconds idling after a verification request takes a
            > fraction of a second to complete. how can these two elements of
            > time be set independent of each other?

            As documented:

            address_verify_poll_count (default: normal: 3, overload: 1)
            HOW MANY TIMES TO QUERY the verify(8) service for the completion of an
            address verification request in progress.

            This specifies how many times a query will be made for one address
            verification request in progress.

            As documented:

            address_verify_poll_delay (default: 3s)
            The DELAY BETWEEN QUERIES for the completion of an address verification
            request in progress.

            This specifies the delay betweem the $address_verify_poll_count
            queries for one address verification request in progress.

            Wietse
          • btb@...
            ... in the logs i shared, postfix completes verification of one address: Jul 9 16:49:02 msa01 postfix/cleanup[31520]: 3h7t0Z0VHwzJnP1:
            Message 5 of 6 , Jul 9, 2014
            • 0 Attachment
              On Jul 9, 2014, at 19.35, Wietse Venema <wietse@...> wrote:

              > address_verify_poll_delay (default: 3s)
              > The DELAY BETWEEN QUERIES for the completion of an address verification
              > request in progress.
              >
              > This specifies the delay betweem the $address_verify_poll_count
              > queries for one address verification request in progress.

              in the logs i shared, postfix completes verification of one address:

              Jul 9 16:49:02 msa01 postfix/cleanup[31520]: 3h7t0Z0VHwzJnP1: message-id=<3h7t0Z0VHwzJnP1@...>
              Jul 9 16:49:02 msa01 postfix/qmgr[24500]: 3h7t0Z0VHwzJnP1: from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
              Jul 9 16:49:02 msa01 postfix/lmtp[31512]: 3h7t0Z0VHwzJnP1: to=<testuser-3003@...>, relay=mda.example.com[10.59.1.57]:7025, delay=0.02, delays=0.01/0/0/0.01, dsn=2.1.5, status=deliverable (250 2.1.5 Recipient OK)
              Jul 9 16:49:02 msa01 postfix/qmgr[24500]: 3h7t0Z0VHwzJnP1: removed

              then waits 15 seconds before starting verification of the next address:

              Jul 9 16:49:17 msa01 postfix/cleanup[31520]: 3h7t0s0qsyzJnP1: message-id=<3h7t0s0qsyzJnP1@...>
              Jul 9 16:49:17 msa01 postfix/qmgr[24500]: 3h7t0s0qsyzJnP1: from=<double-bounce@...>, size=216, nrcpt=1 (queue active)
              Jul 9 16:49:17 msa01 postfix/lmtp[31505]: 3h7t0s0qsyzJnP1: to=<testuser-3004@...>, relay=mda.example.com[10.59.1.57]:7025, delay=0.03, delays=0.01/0/0.01/0, dsn=2.1.5, status=deliverable (250 2.1.5 Recipient OK)
              Jul 9 16:49:17 msa01 postfix/qmgr[24500]: 3h7t0s0qsyzJnP1: removed

              this is not a delay between the $address_verify_poll_count queries for one address verification request in progress. this is a delay between two different address verifications requests, for two different addresses. one completes, and is no longer in progress. then the next one begins.

              it seems to me that address_verify_poll_delay is not only affecting the delay between queries for one request, but also the delay between one request and the next. given the documentation, i don’t think this should be happening.

              -ben
            • Wietse Venema
              The address_verification_poll_count is the answer, but for some reason you insist on ignoring all my pointers to it. I will no longer contribute to this
              Message 6 of 6 , Jul 9, 2014
              • 0 Attachment
                The address_verification_poll_count is the answer, but for some
                reason you insist on ignoring all my pointers to it.

                I will no longer contribute to this thread.

                Wietse
              Your message has been successfully submitted and would be delivered to recipients shortly.