In my milter logs I see success for every request postfix makes. I sent only two emails and this is what my milter gets:
recipients: [email protected] [email protected] logs: [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest START: [get *] [2025-10-17 10:31:42] [INFO] [PID:188081] Parts: ["get","*"] [2025-10-17 10:31:42] [INFO] [PID:188081] Command: [get], Key: [*] [2025-10-17 10:31:42] [INFO] [PID:188081] Response sent, awaiting next request... [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest START: [get *] [2025-10-17 10:31:42] [INFO] [PID:188081] Parts: ["get","*"] [2025-10-17 10:31:42] [INFO] [PID:188081] Command: [get], Key: [*] [2025-10-17 10:31:42] [INFO] [PID:188081] Response sent, awaiting next request... [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest START: [get [email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Parts: ["get","[email protected]"] [2025-10-17 10:31:42] [INFO] [PID:188081] Command: [get], Key: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Extracted email: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Calling routeEmail([email protected])... [2025-10-17 10:31:42] [INFO] [PID:188081] Route result: {"success":true,"message":"Routed to 1.2.3.244 via other","smtp_host":"1.2.3.244","timestamp":"2025-10-17 10:31:42","data":{"smtp_id":4,"transport":"other","remaining_capacity":118,"messages_today":12}} [2025-10-17 10:31:42] [INFO] [PID:188081] Routed: [email protected] -> 1.2.3.244 (transport: other) [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest END: returning [smtp:[1.2.3.244]:25] [2025-10-17 10:31:42] [INFO] [PID:188081] Response sent, awaiting next request... [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest START: [get [email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Parts: ["get","[email protected]"] [2025-10-17 10:31:42] [INFO] [PID:188081] Command: [get], Key: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Extracted email: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Calling routeEmail([email protected])... [2025-10-17 10:31:42] [INFO] [PID:188081] Route result: {"success":true,"message":"Routed to 1.2.3.241 via other","smtp_host":"1.2.3.241","timestamp":"2025-10-17 10:31:42","data":{"smtp_id":1,"transport":"other","remaining_capacity":118,"messages_today":12}} [2025-10-17 10:31:42] [INFO] [PID:188081] Routed: [email protected] -> 1.2.3.241 (transport: other) [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest END: returning [smtp:[1.2.3.241]:25] [2025-10-17 10:31:42] [INFO] [PID:188081] Response sent, awaiting next request... [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest START: [get [email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Parts: ["get","[email protected]"] [2025-10-17 10:31:42] [INFO] [PID:188081] Command: [get], Key: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Extracted email: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Calling routeEmail([email protected])... [2025-10-17 10:31:42] [INFO] [PID:188081] Route result: {"success":true,"message":"Routed to 1.2.3.243 via other","smtp_host":"1.2.3.243","timestamp":"2025-10-17 10:31:42","data":{"smtp_id":3,"transport":"other","remaining_capacity":117,"messages_today":13}} [2025-10-17 10:31:42] [INFO] [PID:188081] Routed: [email protected] -> 1.2.3.243 (transport: other) [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest END: returning [smtp:[1.2.3.243]:25] [2025-10-17 10:31:42] [INFO] [PID:188081] Response sent, awaiting next request... [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest START: [get [email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Parts: ["get","[email protected]"] [2025-10-17 10:31:42] [INFO] [PID:188081] Command: [get], Key: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Extracted email: [[email protected]] [2025-10-17 10:31:42] [INFO] [PID:188081] Calling routeEmail([email protected])... [2025-10-17 10:31:42] [INFO] [PID:188081] Route result: {"success":true,"message":"Routed to 1.2.3.242 via other","smtp_host":"1.2.3.242","timestamp":"2025-10-17 10:31:42","data":{"smtp_id":2,"transport":"other","remaining_capacity":117,"messages_today":13}} [2025-10-17 10:31:42] [INFO] [PID:188081] Routed: [email protected] -> 1.2.3.242 (transport: other) [2025-10-17 10:31:42] [INFO] [PID:188081] >>> processRequest END: returning [smtp:[1.2.3.242]:25] [2025-10-17 10:31:42] [INFO] [PID:188081] Response sent, awaiting next request... [2025-10-17 10:31:43] [INFO] [PID:188081] >>> processRequest START: [get [email protected]] [2025-10-17 10:31:43] [INFO] [PID:188081] Parts: ["get","[email protected]"] [2025-10-17 10:31:43] [INFO] [PID:188081] Command: [get], Key: [[email protected]] [2025-10-17 10:31:43] [INFO] [PID:188081] Extracted email: [[email protected]] [2025-10-17 10:31:43] [INFO] [PID:188081] Calling routeEmail([email protected])... [2025-10-17 10:31:43] [INFO] [PID:188081] Route result: {"success":true,"message":"Routed to 177.85.215.244 via others","smtp_host":"177.85.215.244","timestamp":"2025-10-17 10:31:43","data":{"smtp_id":4,"transport":"others","remaining_capacity":117,"messages_today":13}} [2025-10-17 10:31:43] [INFO] [PID:188081] Routed: [email protected] -> 177.85.215.244 (transport: other) [2025-10-17 10:31:43] [INFO] [PID:188081] >>> processRequest END: returning [smtp:[177.85.215.244]:25] [2025-10-17 10:31:43] [INFO] [PID:188081] Response sent, awaiting next request... Em sex., 17 de out. de 2025 às 10:25, Wietse Venema via Postfix-users <[email protected]> escreveu: > > Rafael Azevedo via Postfix-users: > > After fixing that issue, I noticed that when postfix first connect to > > my milter, it starts with 2 requests containing ` * `. > > When a Postfix process 'opens' the transport table, it will do one > "*" lookup and remember the result. If the result was not an error, > then **that Postfix process** will not make a "*" query again. If > the result was an error, then **that Postfix process** may do another > "*" lookup after 30s. > > Transport lookups with the sender address are needed for sender > addess verification, and also happen as a side effect of > reject_unlisted_sender and smtpd_reject_unlisted_sender (that side > efferct could be optimized away but if this query is killing your > performance, then you have a bigger problem). > > Wietse > _______________________________________________ > Postfix-users mailing list -- [email protected] > To unsubscribe send an email to [email protected] _______________________________________________ Postfix-users mailing list -- [email protected] To unsubscribe send an email to [email protected]
