On Tue, 16 Aug 2016 23:17:18 -0500
Edgar Pettijohn <ed...@pettijohn-web.com> wrote:
So now cleaner after i kick out the filter's.
smtpd -dv
debug: init ssl-tree
info: loading pki information for fr-sb.silviosiefke.com
debug: init ca-tree
debug: init ssl-tree
info: loading pki keys for fr-sb.silviosiefke.com
info: OpenSMTPD 5.9.2p1 starting
debug: bounce warning after 4h
debug: using "fs" queue backend
debug: using "ramqueue" scheduler backend
debug: using "ram" stat backend
debug: parent_send_config_ruleset: reloading
debug: parent_send_config: configuring pony process
debug: parent_send_config: configuring ca process
filter: building simple chains...
filter: building complex chains...
filter: done building complex chains
debug: ca_engine_init: using RSA privsep engine
debug: smtp: listen on 127.0.0.1 port 10025 flags 0x400 pki "" ca ""
debug: smtp: listen on IPv6:fe80::f816:3eff:fec5:7c12 port 587 flags 0x469 pki
"fr-sb.silviosiefke.com" ca ""
debug: smtp: listen on 164.132.55.246 port 587 flags 0x469 pki
"fr-sb.silviosiefke.com" ca ""
debug: smtp: listen on IPv6:fe80::f816:3eff:fec5:7c12 port 25 flags 0x401 pki
"fr-sb.silviosiefke.com" ca ""
debug: smtp: listen on 164.132.55.246 port 25 flags 0x401 pki
"fr-sb.silviosiefke.com" ca ""
debug: smtp: listen on 127.0.0.1 port 25 flags 0x400 pki "" ca ""
debug: init private ssl-tree
debug: pony: rsae_init
debug: pony: rsae_init
debug: smtp: will accept at most 2043 clients
debug: queue: done loading queue into scheduler
debug: smtpd: scanning offline queue...
debug: smtpd: offline scanning done
debug: smtp: new client on listener: 0x23fc1a0
smtp-in: New session 7ceea047b02403f2 from host mail-wm0-f65.google.com
[74.125.82.65]
debug: lka: looking up pki "fr-sb.silviosiefke.com"
debug: session_start_ssl: switching to SSL
debug: pony: rsae_priv_enc
smtp-in: Started TLS on session 7ceea047b02403f2: version=TLSv1.2,
cipher=ECDHE-RSA-AES128-GCM-SHA256, bits=128
debug: smtp: SIZE in MAIL FROM command
smtp: 0x24727f0: fd 5 from queue
smtp: 0x24727f0: fd 7 from filter
debug: smtp: 0x24727f0: data io done (2778 bytes)
filter: deferring eom query...
filter: running eom query...
debug: 0x24727f0: end of message, msgflags=0x0000
debug: scheduler: evp:9362c104cd76a1cf scheduled (mta)
smtp-in: Accepted message 9362c104 on session 7ceea047b02403f2:
from=<silviosie...@gmail.com>, to=<mlmmj-test+subscr...@silviosiefke.com>,
size=2778, ndest=1, proto=ESMTP
debug: mta: received evp:9362c104cd76a1cf for
<mlmmj-test+subscr...@silviosiefke.com>
debug: mta: draining [relay:127.0.0.1,port=10024,mx] refcount=1, ntask=1,
nconnector=0, nconn=0
debug: mta: querying MX for [relay:127.0.0.1,port=10024,mx]...
debug: mta: [relay:127.0.0.1,port=10024,mx] waiting for MX
debug: MXs for domain 127.0.0.1:
127.0.0.1 preference -1
debug: mta: ... got mx (0x24315a0, 127.0.0.1, [relay:127.0.0.1,port=10024,mx])
debug: mta: draining [relay:127.0.0.1,port=10024,mx] refcount=1, ntask=1,
nconnector=0, nconn=0
debug: mta: querying source for [relay:127.0.0.1,port=10024,mx]...
debug: mta: ... got source for [relay:127.0.0.1,port=10024,mx]: []
debug: mta: new [connector:[]->[relay:127.0.0.1,port=10024,mx],0x10000]
debug: mta: connecting with [connector:[]->[relay:127.0.0.1,port=10024,mx],0x0]
debug: mta-routing: searching new route for
[connector:[]->[relay:127.0.0.1,port=10024,mx],0x0]...
debug: mta-routing: selecting candidate route [] <-> 127.0.0.1
debug: mta-routing: spawning new connection on [] <-> 127.0.0.1
debug: mta: 0x23e8dc0: spawned for relay [relay:127.0.0.1,port=10024,mx]
debug: mta: connecting with [connector:[]->[relay:127.0.0.1,port=10024,mx],0x0]
debug: mta: cannot use [relay:127.0.0.1,port=10024,mx] before 2s
debug: mta-routing: no route available for
[connector:[]->[relay:127.0.0.1,port=10024,mx],0x0]: must wait a bit
debug: mta: retrying to connect on
[connector:[]->[relay:127.0.0.1,port=10024,mx],0x0] in 2s...
debug: mta: draining [relay:127.0.0.1,port=10024,mx] refcount=3, ntask=1,
nconnector=1, nconn=1
debug: mta: scheduling relay [relay:127.0.0.1,port=10024,mx] in 1s...
smtp-out: Connecting to smtp://127.0.0.1:10024 (localhost) on session
7ceea051873a4c65...
smtp-out: Connected on session 7ceea051873a4c65
debug: mta-routing: route [] <-> 127.0.0.1 (localhost) is now valid.
debug: mta: connecting with
[connector:[]->[relay:127.0.0.1,port=10024,mx],0x20000]
debug: mta: cancelling connector timeout
debug: mta: enough connections already
debug: mta: 0x23e8dc0: handling next task for relay
[relay:127.0.0.1,port=10024,mx]
smtp-in: Closing session 7ceea047b02403f2
debug: smtp: 0x24727f0: deleting session: done
debug: mta: ... timeout for [relay:127.0.0.1,port=10024,mx]
debug: mta: draining [relay:127.0.0.1,port=10024,mx] refcount=2, ntask=0,
nconnector=1, nconn=1
debug: mta: all done for [relay:127.0.0.1,port=10024,mx]
debug: smtp: new client on listener: 0x2412d80
smtp-in: New session 7ceea052ae493484 from host localhost [127.0.0.1]
smtp-in: Failed command on session 7ceea052ae493484: "RCPT
TO:<mlmmj-test+subscr...@silviosiefke.com>" => 550 Invalid recipient
relay: PermFail for 9362c104cd76a1cf: session=7ceea051873a4c65,
from=<silviosie...@gmail.com>, to=<mlmmj-test+subscr...@silviosiefke.com>,
rcpt=<->, source=127.0.0.1, relay=127.0.0.1 (localhost), delay=1s, stat=550
5.1.0 id=00362-12 - Rejected by next-hop MTA on relaying, from
MTA(smtp:[127.0.0.1]:10025): 550 Invalid recipient
debug: mta: waiting for 1s before next transaction
debug: mta: flush for 9362c104cd76a1cf (->
mlmmj-test+subscr...@silviosiefke.com)
debug: queue: bouncing evp:9362c104cd76a1cf as evp:9362c10490fb9401
debug: scheduler: evp:9362c10490fb9401 scheduled (bounce)
debug: bounce: new message 9362c104
debug: bounce: adding report 9362c10490fb9401:
mlmmj-test+subscr...@silviosiefke.com: 550 5.1.0 id=00362-12 - Rejected by
next-hop MTA on relaying, from MTA(smtp:[127.0.0.1]:10025): 550 Invalid
recipient
debug: bounce: drain: nmessage=1 running=0
debug: bounce: next message not ready yet
debug: bounce: setting timer
debug: bounce: timeout
debug: bounce: drain: nmessage=1 running=0
debug: bounce: requesting new enqueue socket...
debug: bounce: enough sessions running
debug: smtp: new client on listener: 0x242a8e0
smtp-in: New session 7ceea0570cad2872 from host vps296466.ovh.net [local]
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 9s
debug: bounce: got enqueue socket 4
debug: bounce: new session 0x23f0180
debug: bounce: 0x23f0180: getting next message...
debug: bounce: 0x23f0180: found message 9362c104
smtp: 0x2496ae0: fd 7 from queue
smtp: 0x2496ae0: fd 9 from filter
debug: smtp: 0x2496ae0: data io done (4229 bytes)
filter: deferring eom query...
filter: running eom query...
debug: 0x2496ae0: end of message, msgflags=0x0000
debug: scheduler: evp:9c77a83a8609787a scheduled (mta)
smtp-in: Accepted message 9c77a83a on session 7ceea0570cad2872: from=<>,
to=<silviosie...@gmail.com>, size=4229, ndest=1, proto=ESMTP
debug: mta: received evp:9c77a83a8609787a for <silviosie...@gmail.com>
debug: mta: draining [relay:gmail.com] refcount=1, ntask=1, nconnector=0,
nconn=0
debug: mta: querying MX for [relay:gmail.com]...
debug: mta: [relay:gmail.com] waiting for MX
debug: bounce: 0x23f0180: getting next message...
debug: bounce: 0x23f0180: no more messages
debug: bounce: 0x23f0180: deleting session
debug: bounce: drain: nmessage=0 running=0
debug: bounce: no more messages
smtp-in: Closing session 7ceea0570cad2872
debug: smtp: 0x2496ae0: deleting session: done
debug: MXs for domain gmail.com:
74.125.133.26 preference 5
64.233.163.26 preference 10
74.125.68.26 preference 20
64.233.189.26 preference 30
173.194.72.26 preference 40
debug: mta: ... got mx (0x24293c0, gmail.com, [relay:gmail.com])
debug: mta: draining [relay:gmail.com] refcount=1, ntask=1, nconnector=0,
nconn=0
debug: mta: querying source for [relay:gmail.com]...
debug: mta: ... got source for [relay:gmail.com]: []
debug: mta: new [connector:[]->[relay:gmail.com],0x10000]
debug: mta: connecting with [connector:[]->[relay:gmail.com],0x0]
debug: mta-routing: searching new route for
[connector:[]->[relay:gmail.com],0x0]...
debug: mta-routing: selecting candidate route [] <-> 74.125.133.26
debug: mta-routing: spawning new connection on [] <-> 74.125.133.26
debug: mta: 0x23f17a0: spawned for relay [relay:gmail.com]
debug: mta: connecting with [connector:[]->[relay:gmail.com],0x0]
debug: mta: cannot use [relay:gmail.com] before 2s
debug: mta-routing: no route available for
[connector:[]->[relay:gmail.com],0x0]: must wait a bit
debug: mta: retrying to connect on [connector:[]->[relay:gmail.com],0x0] in
2s...
debug: mta: draining [relay:gmail.com] refcount=3, ntask=1, nconnector=1,
nconn=1
debug: mta: scheduling relay [relay:gmail.com] in 1s...
smtp-out: Connecting to smtp+tls://74.125.133.26:25 (wo-in-f26.1e100.net) on
session 7ceea061e03e971d...
smtp-out: Connected on session 7ceea061e03e971d
debug: lka: looking up pki "vps296466.ovh.net"
debug: pony: rsae_init
debug: pony: rsae_pub_dec
debug: pony: rsae_bn_mod_exp
smtp-out: Started TLS on session 7ceea061e03e971d: version=TLSv1.2,
cipher=ECDHE-RSA-AES128-GCM-SHA256, bits=128
debug: certificate 0: len=1701
debug: certificate chain len: 3
debug: certificate 1: len=1701
debug: certificate 2: len=1012
debug: certificate 3: len=897
smtp-out: Server certificate verification succeeded on session 7ceea061e03e971d
debug: mta-routing: route [] <-> 74.125.133.26 (wo-in-f26.1e100.net) is now
valid.
debug: mta: connecting with [connector:[]->[relay:gmail.com],0x20000]
debug: mta: cancelling connector timeout
debug: mta: enough connections already
debug: mta: 0x23f17a0: handling next task for relay [relay:gmail.com]
relay: Ok for 9c77a83a8609787a: session=7ceea061e03e971d, from=<>,
to=<silviosie...@gmail.com>, rcpt=<->, source=164.132.55.246,
relay=74.125.133.26 (wo-in-f26.1e100.net), delay=0s, stat=250 2.0.0 OK
1471432150 by1si29728611wjb.33 - gsmtp
debug: mta: waiting for 1s before next transaction
debug: mta: flush for 9c77a83a8609787a (-> silviosie...@gmail.com)
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 8s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 9s
debug: mta: ... timeout for [relay:gmail.com]
debug: mta: draining [relay:gmail.com] refcount=2, ntask=0, nconnector=1,
nconn=1
debug: mta: all done for [relay:gmail.com]
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 7s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 8s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 6s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 7s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 5s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 6s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 4s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 5s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 3s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 4s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 2s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 3s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: debug: last connection: hanging on for 1s
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 2s
mta: timeout for session hangon
debug: mta: 0x23e8dc0: no task for relay [relay:127.0.0.1,port=10024,mx]
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
mta: debug: last connection: hanging on for 1s
smtp-out: Closing session 7ceea051873a4c65: 0 message sent.
debug: mta: 0x23e8dc0: session done
debug: mta_route_collect([] <-> 127.0.0.1 (localhost))
debug: mta: connecting with [connector:[]->[relay:127.0.0.1,port=10024,mx],0x0]
debug: mta: no task for connector
debug: mta: mta_route_unref(): really discarding route [] <-> 127.0.0.1
(localhost)
debug: mta: freeing [relay:127.0.0.1,port=10024,mx]
debug: mta: freeing [connector:[]->[relay:127.0.0.1,port=10024,mx],0x0]
smtp-in: Closing session 7ceea052ae493484
debug: smtp: 0x24727f0: deleting session: done
mta: timeout for session hangon
debug: mta: 0x23f17a0: no task for relay [relay:gmail.com]
smtp-out: Closing session 7ceea061e03e971d: 1 message sent.
debug: mta: 0x23f17a0: session done
debug: pony: rsae_finish
debug: mta_route_collect([] <-> 74.125.133.26 (wo-in-f26.1e100.net))
debug: mta: connecting with [connector:[]->[relay:gmail.com],0x0]
debug: mta: no task for connector
debug: mta: mta_route_unref(): really discarding route [] <-> 74.125.133.26
(wo-in-f26.1e100.net)
debug: mta: freeing [relay:gmail.com]
debug: mta: freeing [connector:[]->[relay:gmail.com],0x0]
Silvio
--
You received this mail because you are subscribed to misc@opensmtpd.org
To unsubscribe, send a mail to: misc+unsubscr...@opensmtpd.org
