Hi,

The problem was reproduced and undertood today, it only affects the latest
snapshot and the fix will be committed in a minute by eric@

I'll regen snapshots tonight after a bit of code backporting



On Wed, May 21, 2014 at 10:28:09AM +0100, John Cox wrote:
> Hi
> 
> >User gilles has just rebuilt a master snapshot, available from:
> >
> >        http://www.OpenSMTPD.org/archives/opensmtpd-201405202103.tar.gz
> >
> >Checksum:
> >
> >  SHA256 (opensmtpd-201405202103.tar.gz) =
> >  39a4520515e36a3b44b4cbbd63211bddbc07db316d929c3b583b86f95e5558d1
> >
> >A summary of the content of this snapshot is available below.
> >
> >Please test and let us know if it breaks something!
> 
> Better than the last one but something is still confused. It ran for a
> bit and then stopped (didn't crash just stopped doing stuff) having
> delivered a very empty message:
> 
> $ cat '1400658290.911.yidhra.outer.uphall.net:2,S'
> Return-Path: [email protected]
> Delivered-To: [email protected]
> Received: from azathoth.uphall.net (azathoth.uphall.net
> [46.235.226.138]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id
> 0893cac8;
>         TLS version=TLSv1/SSLv3 cipher=ECDHE-RSA-AES256-GCM-SHA384
> bits=256 verify=YES;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:44:50 +0100 (BST)
> $
> 
> I'm unsure exactly how this message originated - I think it was a
> longer real message that has been flattened but I'm not sure.  I have
> logs from both azathoth (using sendmail) and yidhra (opensmtpd) but
> sendmail logs cause my head to hurt!  Logs attached
> 
> >If this snapshot doesn't work, please also test with a previous one,
> >to help us spot where the issue is coming from. You can access all
> >previous snapshots here:
> >
> >        http://www.opensmtpd.org/archives/
> >
> >The OpenSMTPD team ;-)
> >
> >
> >Summary of changes since last snapshot (opensmtpd-201405142324):
> >---------------------------------------------------------------
> >
> >    - change filter definition syntax making it possible to pass arguments 
> > to filter
> >    - fix the parse_attribute interface in table_ldap it was doing not 
> > really smart
> >    - while at it, fix user authentication which was not fetching the 
> > correct attribute
> >    - when dealing with an unknown lookup kind, do not succeed with random 
> > crap in the result
> >    - add missing header needed by str* and mem* functions, from OpenBSD cvs 
> > tree
> >    - code cleanup and fixes
> >    - per-listener filter
> >    - fix log strings
> >    - unset the wait flag when cancelling the timeout
> >    - fix a possible double-free in the mta when using pki in outgoing 
> > connections
> >    - generic ssl API improvements while there, by reyk@
> >    - sync manpages changes from OpenBSD
> >    - %i -> %d
> 


> ============================================
> Log from yidhra - mildly redacted in message body from ebay to remove refs
> 
> # smtpd -v -d
> debug: init ssl-tree
> info: loading pki information for yidhra.outer.uphall.net
> info: OpenSMTPD 201405202103 starting
> debug: bounce warning after 4h
> debug: using "fs" queue backend
> debug: using "ramqueue" scheduler backend
> debug: using "ram" stat backend
> info: startup [debug mode]
> debug: parent_send_config_ruleset: reloading
> filter: building simple chains...
> debug: init ssl-tree
> debug: parent_send_config: configuring pony process
> filter: building complex chains...
> info: loading pki keys for yidhra.outer.uphall.net
> debug: parent_send_config: configuring ca process
> filter: done building complex chains
> debug: init private ssl-tree
> debug: ca_engine_init: using RSAX engine support
> debug: smtp: listen on 127.0.0.1 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: smtp: listen on IPv6:::1 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: smtp: listen on IPv6:fe80::6a05:caff:fe08:e7b1%em2 port 25 flags 0x1 
> pki "yidhra.outer.uphall.net"
> debug: smtp: listen on 10.44.0.3 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: smtp: will accept at most 3503 clients
> debug: queue: done loading queue into scheduler
> debug: smtpd: scanning offline queue...
> debug: smtpd: offline scanning done
> debug: smtp: new client on listener: 0xef84a336000
> smtp-in: New session cd0f0efb1c7016a0 from host azathoth.uphall.net 
> [46.235.226.138]
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: session_start_ssl: switching to SSL
> debug: pony: rsae_priv_enc
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> smtp-in: Started TLS on session cd0f0efb1c7016a0: version=TLSv1/SSLv3, 
> cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256
> smtp-in: Client certificate verification succeeded on session cd0f0efb1c7016a0
> debug: smtp: SIZE in MAIL FROM command
> debug: aliases_virtual_get: 'jc' resolved to 1 nodes
> debug: aliases_get: returned 1 aliases
> smtp: 0xef8495fd000: fd 5 from queue
> smtp: 0xef8495fd000: fd 7 from filter
> filter: filter_tx_io(0xef8517e8400, IO_DATAIN)
> filter: filter_tx_io: datain (295) for req cd0f0efb1c7016a0: Received: from 
> azathoth.uphall.net (azathoth.uphall.net [46.235.226.138]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id 0c62dd75;
>         TLS version=TLSv1/SSLv3 cipher=ECDHE-RSA-AES256-GCM-SHA384 bits=256 
> verify=YES;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:40:58 +0100 (BST)
> 
> filter: filter_tx_io(0xef8517e8400, IO_DATAIN)
> filter: filter_tx_io: datain (803) for req cd0f0efb1c7016a0: Received: from 
> smtp1.cix.co.uk (smtp1.cix.co.uk [77.92.64.121])
>         by azathoth.uphall.net (8.14.8/8.14.6) with ESMTP id s4L7ewxI023688
>         (version=TLSv1/SSLv3 cipher=DHE-RSA-AES256-SHA bits=256 verify=NO)
>         for <[email protected]>; Wed, 21 May 2014 08:40:58 +0100 (BST)
> Received: (qmail 12334 invoked from network); 21 May 2014 07:40:57 -0000
> Received: from unknown (HELO Ithaqua.elder.uphall.net) (86.1.43.115)
>   by smtp1.cix.co.uk with ESMTPS (AES128-SHA encrypted); 21 May 2014 07:40:57 
> -0000
> From: John Cox <[email protected]>
> To: John home Cox <[email protected]>
> Subject: Test 6 -> uphall
> Date: Wed, 21 May 2014 08:40:58 +0100
> Message-ID: <[email protected]>
> User-Agent: ForteAgent/7.10.32.1212
> MIME-Version: 1.0
> Content-Type: text/plain; charset=us-ascii
> Content-Transfer-Encoding: 7bit
> 
> 
> 
> debug: smtp: 0xef8495fd000: data io done (1098 bytes)
> filter: deferring eom query...
> filter: filter_tx_io(0xef8517e8400, IO_DISCONNECTED)
> debug: filter: tx done (1098) for req cd0f0efb1c7016a0
> filter: running eom query...
> debug: filter: filter_end_query(-1, 1098, 1098)
> debug: 0xef8495fd000: end of message, msgflags=0x0000
> smtp-in: Accepted message 0c62dd75 on session cd0f0efb1c7016a0: 
> from=<[email protected]>, to=<[email protected]>, size=1098, ndest=1, 
> proto=ESMTP
> debug: scheduler: evp:0c62dd757698b451 scheduled (mda)
> mda: new user cd0f0f05be0e6c1a for "<getpwnam>:jc"
> debug: lka: userinfo <getpwnam>:jc
> debug: mda: new session cd0f0f066b6a8ef7 for user "<getpwnam>:jc" evpid 
> 0c62dd757698b451
> debug: mda: no more envelope for "<getpwnam>:jc"
> debug: mda: got message fd 5 for session cd0f0f066b6a8ef7 evpid 
> 0c62dd757698b451
> debug: mda: querying mda fd for session cd0f0f066b6a8ef7 evpid 
> 0c62dd757698b451
> debug: smtpd: forking mda for session cd0f0f066b6a8ef7: "/home/jc/Maildir" as 
> jc
> debug: mda: got mda fd 6 for session cd0f0f066b6a8ef7 evpid 0c62dd757698b451
> debug: mda: end-of-file for session cd0f0f066b6a8ef7 evpid 0c62dd757698b451
> debug: mda: all data sent for session cd0f0f066b6a8ef7 evpid 0c62dd757698b451
> debug: smtpd: mda process done for session cd0f0f066b6a8ef7: exited okay
> delivery: Ok for 0c62dd757698b451: from=<[email protected]>, 
> to=<[email protected]>, user=jc, method=maildir, delay=1s, 
> stat=Delivered
> debug: mda: session cd0f0f066b6a8ef7 done
> debug: mda: user "jc" becomes runnable
> debug: mda: all done for user "<getpwnam>:jc"
> smtp-in: Closing session cd0f0efb1c7016a0
> debug: smtp: 0xef8495fd000: deleting session: done
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: smtp: new client on listener: 0xef84a336000
> smtp-in: New session cd0f0f079537ecce from host 10.44.1.11 [10.44.1.11]
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: session_start_ssl: switching to SSL
> smtp-in: No PKI entry for requested SNI "smtp.outer.uphall.net"on session 
> cd0f0f079537ecce
> debug: pony: rsae_priv_dec
> smtp-in: Started TLS on session cd0f0f079537ecce: version=TLSv1/SSLv3, 
> cipher=AES128-SHA, bits=128
> smtp: 0xef8495fd000: fd 5 from queue
> smtp: 0xef8495fd000: fd 7 from filter
> filter: filter_tx_io(0xef8517e8600, IO_DATAIN)
> filter: filter_tx_io: datain (255) for req cd0f0f079537ecce: Received: from 
> Ithaqua.elder.uphall.net (10.44.1.11 [10.44.1.11]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id 66e88e27;
>         TLS version=TLSv1/SSLv3 cipher=AES128-SHA bits=128 verify=NO;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:41:31 +0100 (BST)
> 
> filter: filter_tx_io(0xef8517e8600, IO_DATAIN)
> filter: filter_tx_io: datain (314) for req cd0f0f079537ecce: From: John Cox 
> <[email protected]>
> To: John Cox <[email protected]>
> Subject: Test 6 -> cix
> Date: Wed, 21 May 2014 08:41:31 +0100
> Message-ID: <[email protected]>
> User-Agent: ForteAgent/7.10.32.1212
> MIME-Version: 1.0
> Content-Type: text/plain; charset=us-ascii
> Content-Transfer-Encoding: 7bit
> 
> To Cix
> 
> debug: smtp: 0xef8495fd000: data io done (569 bytes)
> filter: deferring eom query...
> filter: filter_tx_io(0xef8517e8600, IO_DISCONNECTED)
> debug: filter: tx done (569) for req cd0f0f079537ecce
> filter: running eom query...
> debug: filter: filter_end_query(-1, 569, 569)
> debug: 0xef8495fd000: end of message, msgflags=0x0000
> smtp-in: Accepted message 66e88e27 on session cd0f0f079537ecce: 
> from=<[email protected]>, to=<[email protected]>, size=569, ndest=1, proto=ESMTP
> debug: scheduler: evp:66e88e273476e4e8 scheduled (mta)
> debug: mta: received evp:66e88e273476e4e8 for <[email protected]>
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=1, ntask=1, nconnector=0, nconn=0
> debug: mta: querying MX for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]...
> debug: mta: 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  waiting for MX
> debug: MXs for domain azathoth.uphall.net:
>         46.235.226.138 preference -1
> debug: mta: ... got mx (0xef84e1f79a0, azathoth.uphall.net, 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>])
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=1, ntask=1, nconnector=0, nconn=0
> debug: mta: querying source for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]...
> debug: mta: ... got source for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]:
>  10.44.0.3
> debug: mta: new 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x10000]
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: mta-routing: searching new route for 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]...
> debug: mta-routing: selecting candidate route 10.44.0.3 <-> 46.235.226.138
> debug: mta-routing: spawning new connection on 10.44.0.3 <-> 46.235.226.138
> debug: mta: 0xef84d106400: spawned for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: mta: cannot use 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  before 2s
> debug: mta-routing: no route available for 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]:
>  must wait a bit
> debug: mta: retrying to connect on 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
>  in 2s...
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=2, ntask=1, nconnector=1, nconn=1
> debug: mta: scheduling relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  in 1s...
> smtp-out: Connecting to tls://46.235.226.138:25 (azathoth.uphall.net) on 
> session cd0f0f13cf9d48ab...
> smtp-out: Connected on session cd0f0f13cf9d48ab
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_priv_enc
> smtp-out: Started TLS on session cd0f0f13cf9d48ab: version=TLSv1/SSLv3, 
> cipher=DHE-RSA-AES256-GCM-SHA384, bits=256
> smtp-out: Server certificate verification succeeded on session 
> cd0f0f13cf9d48ab
> debug: mta-routing: route 10.44.0.3 <-> 46.235.226.138 (azathoth.uphall.net) 
> is now valid.
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x20000]
> debug: mta: cancelling connector timeout
> debug: mta: enough connections already
> debug: mta: 0xef84d106400: handling next task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> relay: Ok for 66e88e273476e4e8: session=cd0f0f13cf9d48ab, 
> from=<[email protected]>, to=<[email protected]>, rcpt=<->, source=10.44.0.3, 
> relay=46.235.226.138 (azathoth.uphall.net), delay=0s, stat=250 2.0.0 
> s4L7fViu021647 Message accepted for delivery
> debug: mta: waiting for 1s before next transaction
> debug: mta: flush for 66e88e273476e4e8 (-> [email protected])
> debug: mta: ... timeout for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=2, ntask=0, nconnector=1, nconn=1
> debug: mta: all done for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 9s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 8s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 7s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 6s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 5s
> smtp-in: Closing session cd0f0f079537ecce
> debug: smtp: 0xef8495fd000: deleting session: done
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 4s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 3s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 2s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 1s
> mta: timeout for session hangon
> debug: mta: 0xef84d106400: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> smtp-out: Closing session cd0f0f13cf9d48ab: 1 message sent.
> debug: mta: 0xef84d106400: session done
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: mta_route_collect(10.44.0.3 <-> 46.235.226.138 (azathoth.uphall.net))
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: mta: no task for connector
> debug: mta: mta_route_unref(): really discarding route 10.44.0.3 <-> 
> 46.235.226.138 (azathoth.uphall.net)
> debug: mta: freeing 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> debug: mta: freeing 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> ^Cinfo: ca agent exiting
> info: pony agent exiting
> info: scheduler handler exiting
> info: lookup agent exiting
> info: control process exiting
> info: queue handler exiting
> warn: parent terminating
> # smtpd -v -d
> debug: init ssl-tree
> info: loading pki information for yidhra.outer.uphall.net
> info: OpenSMTPD 201405202103 starting
> debug: bounce warning after 4h
> debug: using "fs" queue backend
> debug: using "ramqueue" scheduler backend
> debug: using "ram" stat backend
> info: startup [debug mode]
> debug: parent_send_config_ruleset: reloading
> filter: building simple chains...
> debug: init ssl-tree
> debug: parent_send_config: configuring pony process
> filter: building complex chains...
> info: loading pki keys for yidhra.outer.uphall.net
> debug: parent_send_config: configuring ca process
> filter: done building complex chains
> debug: ca_engine_init: using RSAX engine support
> debug: init private ssl-tree
> debug: smtp: listen on 127.0.0.1 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: smtp: listen on IPv6:fe80::1%lo0 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: smtp: listen on IPv6:::1 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: smtp: listen on IPv6:fe80::6a05:caff:fe08:e7b1%em2 port 25 flags 0x1 
> pki "yidhra.outer.uphall.net"
> debug: smtp: listen on 10.44.0.3 port 25 flags 0x1 pki 
> "yidhra.outer.uphall.net"
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: smtp: will accept at most 3503 clients
> debug: queue: done loading queue into scheduler
> debug: smtpd: scanning offline queue...
> debug: smtpd: offline scanning done
> debug: smtp: new client on listener: 0x95522968000
> smtp-in: New session 5fbaf86d1accfe42 from host 10.44.1.11 [10.44.1.11]
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: session_start_ssl: switching to SSL
> smtp-in: No PKI entry for requested SNI "smtp.outer.uphall.net"on session 
> 5fbaf86d1accfe42
> debug: pony: rsae_priv_dec
> smtp-in: Started TLS on session 5fbaf86d1accfe42: version=TLSv1/SSLv3, 
> cipher=AES128-SHA, bits=128
> smtp: 0x95521ea6000: fd 5 from queue
> smtp: 0x95521ea6000: fd 7 from filter
> filter: filter_tx_io(0x9551ecad000, IO_DATAIN)
> filter: filter_tx_io: datain (255) for req 5fbaf86d1accfe42: Received: from 
> Ithaqua.elder.uphall.net (10.44.1.11 [10.44.1.11]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id 9d909c59;
>         TLS version=TLSv1/SSLv3 cipher=AES128-SHA bits=128 verify=NO;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:42:50 +0100 (BST)
> 
> filter: filter_tx_io(0x9551ecad000, IO_DATAIN)
> filter: filter_tx_io: datain (314) for req 5fbaf86d1accfe42: From: John Cox 
> <[email protected]>
> To: John Cox <[email protected]>
> Subject: Test 7 -> cix
> Date: Wed, 21 May 2014 08:42:50 +0100
> Message-ID: <[email protected]>
> User-Agent: ForteAgent/7.10.32.1212
> MIME-Version: 1.0
> Content-Type: text/plain; charset=us-ascii
> Content-Transfer-Encoding: 7bit
> 
> To Cix
> 
> debug: smtp: 0x95521ea6000: data io done (569 bytes)
> filter: deferring eom query...
> filter: filter_tx_io(0x9551ecad000, IO_DISCONNECTED)
> debug: filter: tx done (569) for req 5fbaf86d1accfe42
> filter: running eom query...
> debug: filter: filter_end_query(-1, 569, 569)
> debug: 0x95521ea6000: end of message, msgflags=0x0000
> smtp-in: Accepted message 9d909c59 on session 5fbaf86d1accfe42: 
> from=<[email protected]>, to=<[email protected]>, size=569, ndest=1, proto=ESMTP
> debug: scheduler: evp:9d909c596955bb83 scheduled (mta)
> debug: mta: received evp:9d909c596955bb83 for <[email protected]>
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=1, ntask=1, nconnector=0, nconn=0
> debug: mta: querying MX for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]...
> debug: mta: 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  waiting for MX
> debug: MXs for domain azathoth.uphall.net:
>         46.235.226.138 preference -1
> debug: mta: ... got mx (0x9551bb2b2a0, azathoth.uphall.net, 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>])
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=1, ntask=1, nconnector=0, nconn=0
> debug: mta: querying source for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]...
> debug: mta: ... got source for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]:
>  10.44.0.3
> debug: mta: new 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x10000]
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: mta-routing: searching new route for 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]...
> debug: mta-routing: selecting candidate route 10.44.0.3 <-> 46.235.226.138
> debug: mta-routing: spawning new connection on 10.44.0.3 <-> 46.235.226.138
> debug: mta: 0x955207eee00: spawned for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: mta: cannot use 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  before 2s
> debug: mta-routing: no route available for 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]:
>  must wait a bit
> debug: mta: retrying to connect on 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
>  in 2s...
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=2, ntask=1, nconnector=1, nconn=1
> debug: mta: scheduling relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  in 1s...
> smtp-out: Connecting to tls://46.235.226.138:25 (azathoth.uphall.net) on 
> session 5fbaf8792ff102b1...
> smtp-out: Connected on session 5fbaf8792ff102b1
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_priv_enc
> smtp-out: Started TLS on session 5fbaf8792ff102b1: version=TLSv1/SSLv3, 
> cipher=DHE-RSA-AES256-GCM-SHA384, bits=256
> smtp-out: Server certificate verification succeeded on session 
> 5fbaf8792ff102b1
> debug: mta-routing: route 10.44.0.3 <-> 46.235.226.138 (azathoth.uphall.net) 
> is now valid.
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x20000]
> debug: mta: cancelling connector timeout
> debug: mta: enough connections already
> debug: mta: 0x955207eee00: handling next task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> relay: Ok for 9d909c596955bb83: session=5fbaf8792ff102b1, 
> from=<[email protected]>, to=<[email protected]>, rcpt=<->, source=10.44.0.3, 
> relay=46.235.226.138 (azathoth.uphall.net), delay=1s, stat=250 2.0.0 
> s4L7goU7025337 Message accepted for delivery
> debug: mta: waiting for 1s before next transaction
> debug: mta: flush for 9d909c596955bb83 (-> [email protected])
> debug: mta: ... timeout for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> debug: mta: draining 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
>  refcount=2, ntask=0, nconnector=1, nconn=1
> debug: mta: all done for 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 9s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 8s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 7s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 6s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 5s
> smtp-in: Closing session 5fbaf86d1accfe42
> debug: smtp: 0x95521ea6000: deleting session: done
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 4s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 3s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 2s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> mta: debug: last connection: hanging on for 1s
> mta: timeout for session hangon
> debug: mta: 0x955207eee00: no task for relay 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> smtp-out: Closing session 5fbaf8792ff102b1: 1 message sent.
> debug: mta: 0x955207eee00: session done
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: mta_route_collect(10.44.0.3 <-> 46.235.226.138 (azathoth.uphall.net))
> debug: mta: connecting with 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: mta: no task for connector
> debug: mta: mta_route_unref(): really discarding route 10.44.0.3 <-> 
> 46.235.226.138 (azathoth.uphall.net)
> debug: mta: freeing 
> [relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>]
> debug: mta: freeing 
> [connector:10.44.0.3->[relay:azathoth.uphall.net,starttls,pki_name=yidhra.outer.uphall.net,mx,sourcetable=<dynamic:0>],0x0]
> debug: smtp: new client on listener: 0x95522968000
> smtp-in: New session 5fbaf87a27322426 from host azathoth.uphall.net 
> [46.235.226.138]
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: session_start_ssl: switching to SSL
> debug: pony: rsae_priv_enc
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> smtp-in: Started TLS on session 5fbaf87a27322426: version=TLSv1/SSLv3, 
> cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256
> smtp-in: Client certificate verification succeeded on session 5fbaf87a27322426
> debug: smtp: SIZE in MAIL FROM command
> debug: aliases_virtual_get: 'jc' resolved to 1 nodes
> debug: aliases_get: returned 1 aliases
> smtp: 0x95526ca4000: fd 5 from queue
> smtp: 0x95526ca4000: fd 7 from filter
> filter: filter_tx_io(0x955207eea00, IO_DATAIN)
> filter: filter_tx_io: datain (295) for req 5fbaf87a27322426: Received: from 
> azathoth.uphall.net (azathoth.uphall.net [46.235.226.138]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id e300b6b3;
>         TLS version=TLSv1/SSLv3 cipher=ECDHE-RSA-AES256-GCM-SHA384 bits=256 
> verify=YES;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:44:50 +0100 (BST)
> 
> filter: filter_tx_io(0x955207eea00, IO_DATAIN)
> filter: filter_tx_io: datain (803) for req 5fbaf87a27322426: Received: from 
> smtp2.cix.co.uk (smtp2.cix.co.uk [77.92.64.123])
>         by azathoth.uphall.net (8.14.8/8.14.6) with ESMTP id s4L7gBkN018567
>         (version=TLSv1/SSLv3 cipher=DHE-RSA-AES256-SHA bits=256 verify=NO)
>         for <[email protected]>; Wed, 21 May 2014 08:42:11 +0100 (BST)
> Received: (qmail 32492 invoked from network); 21 May 2014 07:42:10 -0000
> Received: from unknown (HELO Ithaqua.elder.uphall.net) (86.1.43.115)
>   by smtp2.cix.co.uk with ESMTPS (AES128-SHA encrypted); 21 May 2014 07:42:10 
> -0000
> From: John Cox <[email protected]>
> To: John home Cox <[email protected]>
> Subject: Test 7 -> uphall
> Date: Wed, 21 May 2014 08:42:11 +0100
> Message-ID: <[email protected]>
> User-Agent: ForteAgent/7.10.32.1212
> MIME-Version: 1.0
> Content-Type: text/plain; charset=us-ascii
> Content-Transfer-Encoding: 7bit
> 
> 
> 
> debug: smtp: 0x95526ca4000: data io done (1098 bytes)
> filter: deferring eom query...
> filter: filter_tx_io(0x955207eea00, IO_DISCONNECTED)
> debug: filter: tx done (1098) for req 5fbaf87a27322426
> filter: running eom query...
> debug: filter: filter_end_query(-1, 1098, 1098)
> debug: 0x95526ca4000: end of message, msgflags=0x0000
> smtp-in: Accepted message e300b6b3 on session 5fbaf87a27322426: 
> from=<[email protected]>, to=<[email protected]>, size=1098, ndest=1, 
> proto=ESMTP
> debug: scheduler: evp:e300b6b3829fcecc scheduled (mda)
> mda: new user 5fbaf8843416d689 for "<getpwnam>:jc"
> debug: lka: userinfo <getpwnam>:jc
> debug: mda: new session 5fbaf885a9200c5d for user "<getpwnam>:jc" evpid 
> e300b6b3829fcecc
> debug: mda: no more envelope for "<getpwnam>:jc"
> debug: mda: got message fd 5 for session 5fbaf885a9200c5d evpid 
> e300b6b3829fcecc
> debug: mda: querying mda fd for session 5fbaf885a9200c5d evpid 
> e300b6b3829fcecc
> debug: smtpd: forking mda for session 5fbaf885a9200c5d: "/home/jc/Maildir" as 
> jc
> debug: mda: got mda fd 6 for session 5fbaf885a9200c5d evpid e300b6b3829fcecc
> debug: mda: end-of-file for session 5fbaf885a9200c5d evpid e300b6b3829fcecc
> debug: mda: all data sent for session 5fbaf885a9200c5d evpid e300b6b3829fcecc
> debug: smtpd: mda process done for session 5fbaf885a9200c5d: exited okay
> delivery: Ok for e300b6b3829fcecc: from=<[email protected]>, 
> to=<[email protected]>, user=jc, method=maildir, delay=0s, 
> stat=Delivered
> debug: mda: session 5fbaf885a9200c5d done
> debug: mda: user "jc" becomes runnable
> debug: mda: all done for user "<getpwnam>:jc"
> debug: smtp: SIZE in MAIL FROM command
> debug: aliases_virtual_get: 'jc' resolved to 1 nodes
> debug: aliases_get: returned 1 aliases
> smtp: 0x95526ca4000: fd 5 from queue
> smtp: 0x95526ca4000: fd 7 from filter
> debug: smtp: 0x95526ca4000: data io done (295 bytes)
> filter: deferring eom query...
> filter: filter_tx_io(0x955207eea00, IO_DATAIN)
> filter: filter_tx_io: datain (295) for req 5fbaf87a27322426: Received: from 
> azathoth.uphall.net (azathoth.uphall.net [46.235.226.138]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id 0893cac8;
>         TLS version=TLSv1/SSLv3 cipher=ECDHE-RSA-AES256-GCM-SHA384 bits=256 
> verify=YES;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:44:50 +0100 (BST)
> 
> filter: filter_tx_io(0x955207eea00, IO_DISCONNECTED)
> debug: filter: tx done (295) for req 5fbaf87a27322426
> filter: running eom query...
> debug: filter: filter_end_query(-1, 295, 295)
> debug: 0x95526ca4000: end of message, msgflags=0x0000
> smtp-in: Accepted message 0893cac8 on session 5fbaf87a27322426: 
> from=<[email protected]>, to=<[email protected]>, size=295, ndest=1, 
> proto=ESMTP
> debug: scheduler: evp:0893cac8789217c3 scheduled (mda)
> mda: new user 5fbaf88c328029ac for "<getpwnam>:jc"
> debug: lka: userinfo <getpwnam>:jc
> debug: mda: new session 5fbaf88d24969b68 for user "<getpwnam>:jc" evpid 
> 0893cac8789217c3
> debug: mda: no more envelope for "<getpwnam>:jc"
> debug: mda: got message fd 5 for session 5fbaf88d24969b68 evpid 
> 0893cac8789217c3
> debug: mda: querying mda fd for session 5fbaf88d24969b68 evpid 
> 0893cac8789217c3
> debug: smtpd: forking mda for session 5fbaf88d24969b68: "/home/jc/Maildir" as 
> jc
> debug: mda: got mda fd 6 for session 5fbaf88d24969b68 evpid 0893cac8789217c3
> debug: mda: end-of-file for session 5fbaf88d24969b68 evpid 0893cac8789217c3
> debug: mda: all data sent for session 5fbaf88d24969b68 evpid 0893cac8789217c3
> smtp-in: Bad input on session 5fbaf87a27322426: 500 5.5.1 Invalid command: 
> Pipelining not supported
> smtp-in: Closing session 5fbaf87a27322426
> debug: smtp: 0x95526ca4000: deleting session: done
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: pony: rsae_finish
> debug: smtp: new client on listener: 0x95522968000
> smtp-in: New session 5fbaf88e0445f91d from host azathoth.uphall.net 
> [46.235.226.138]
> debug: smtpd: mda process done for session 5fbaf88d24969b68: exited okay
> delivery: Ok for 0893cac8789217c3: from=<[email protected]>, 
> to=<[email protected]>, user=jc, method=maildir, delay=0s, 
> stat=Delivered
> debug: mda: session 5fbaf88d24969b68 done
> debug: mda: user "jc" becomes runnable
> debug: mda: all done for user "<getpwnam>:jc"
> debug: smtp: SIZE in MAIL FROM command
> debug: aliases_virtual_get: 'jc' resolved to 1 nodes
> debug: aliases_get: returned 1 aliases
> smtp: 0x9551f226000: fd 5 from queue
> smtp: 0x9551f226000: fd 7 from filter
> filter: filter_tx_io(0x955207eec00, IO_DATAIN)
> filter: filter_tx_io: datain (213) for req 5fbaf88e0445f91d: Received: from 
> azathoth.uphall.net (azathoth.uphall.net [46.235.226.138]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTP id b1036160;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:44:50 +0100 (BST)
> 
> filter: filter_tx_io(0x955207eec00, IO_DATAIN)
> filter: filter_tx_io: datain (1264) for req 5fbaf88e0445f91d: Received: from 
> mxphxpool28.ebay.com (mxphxpool28.ebay.com [66.211.161.28])
>         by azathoth.uphall.net (8.14.8/8.14.6) with ESMTP id s4L7gQ7A025951
>         for <[email protected]>; Wed, 21 May 2014 08:42:26 +0100 (BST)
> Received: from chd1b02c-12b1.stratus.phx.ebay.com ([10.98.78.60])
>         by mxphxpool28.ebay.com (8.13.8/8.13.8) with ESMTP id s4L7cmsT005422
>         for <[email protected]>; Wed, 21 May 2014 00:42:19 -0700 (GMT)
> X-DKIM: Sendmail DKIM Filter v2.8.3 mxphxpool28.ebay.com s4L7cmsT005422
> DKIM-Signature: v=1; a=rsa-sha1; c=relaxed/relaxed; d=ebay.co.uk; s=dkim1k;
>         t=1400658139; bh=cQ1TMGIr91XuAjjdw+1MvtUVcS8=;
>         h=From:Reply-To:To:Message-ID:Subject:MIME-Version:Content-Type;
>         b=oobS1r0a3NrFw/i1c2GqXEHmotCIucHMGDATomBo3U3YbNsKqbT/Mm8BeOnj1fZ8G
>          zTyw23mY4iW+CpHygDTJ+kLhjp+t4caK1bVEvqro2W3c84TiIxtfh+T8YKURAsTuSv
>          /8sd4m2iz41vko6xWMpv/a7lRfWBRLIfOIjqZfZ4=
> Date: Wed, 21 May 2014 00:42:19 -0700 (GMT)
> From: eBay <[email protected]>
> Reply-To: [email protected]
> To: [email protected]
> Message-ID: <fa30289a-c5df-4ab2-8a51-2035fbc17c78@chd1b02c-12b1>
> Subject: New items that match: dartington victoria wine glass
> MIME-Version: 1.0
> Content-Type: multipart/alternative;
>         boundary="----=_Part_1004737_338826290.1400658139463"
> ReplyTo: [email protected]
> X-eBay-MailVersionTracker: 873.16844669
> 
> filter: filter_tx_io(0x955207eec00, IO_DATAIN)
> filter: filter_tx_io: datain (1975) for req 5fbaf88e0445f91d: 
> X-eBay-MailTracker: 11021_601.873.3.0.71b898e7f47543ff88b040a93d4247be
> 
> ------=_Part_1004737_338826290.1400658139463
> Content-Type: text/plain; charset=UTF-8
> Content-Transfer-Encoding: quoted-printable
> 
> -----------------------------------------------------------------
> Still looking for dartington victoria wine glass? We've found 1 for you!
> -----------------------------------------------------------------
> 
> John, here are your saved search results. We hope this helps you find what =
> you're looking for.
> 
> 
> 
> =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
> =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
> =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
> View all results ###
> 
> =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
> =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
> =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
> 
> DARTINGTON VICTORIA CLARET/WINE GLASSES FT33/3 SET OF SIX http://rover.ebay=
> .com/rover/0/e11021.m43.l3160/7?euid=3D71b898e7f47543ff88b040a93d4247be&loc=
> =3Dhttp%3A%2F%2Fcgi.ebay.co.uk%2Fws%2FeBayISAPI.dll%3FViewItem%26item%3D310=
> 964245003%26ssPageName%3DADME%3AB%3ASS%3AGB%3A3160&exe=3D10018&ext=3D100037=
> &sojTags=3Dexe=3Dexe,ext=3Dext
> Current bid: =C2=A390.00
> Postage: =C2=A328.00
> 
> 
> --------------------------------------------------------------------
> View all results : ###
> 
> filter: filter_tx_io(0x955207eec00, IO_DATAIN)
> filter: filter_tx_io: datain (1988) for req 5fbaf88e0445f91d: 
> --------------------------------------------------------------------
> 
> --------------------------------------------------------------------
> 
> Your saved search details
> 
> *Search name: dartington victoria wine glass
> 
> *Additional search criteria: dartington victoria wine glass
> 
> *[ Refine this search ###
> 
> 
> 
> -----------------------------------------------------------------
> Email reference id: [###]
> -----------------------------------------------------------------
> 
> -----------------------------------------------------------------
> eBay sent this message to John Cox (###). Learn more http://pages.eba=
> y.co.uk/help/confidence/name-userid-emails.html
> 
> eBay is committed to your privacy. Learn more about our privacy policy http=
> ://pages.ebay.co.uk/help/policies/privacy-policy.html and user agreement ht=
> tp://pages.ebay.co.uk/help/policies/user-agreement.html
> This email was sent by eBay Europe S.=C3=A0 r.l., which may use affiliates =
> to provide eBay services. If you are a non-EU resident, please refer to the=
>  user agreement for the contact data of your contracting party.
> 
> 
> filter: filter_tx_io(0x955207eec00, IO_DATAIN)
> filter: filter_tx_io: datain (207) for req 5fbaf88e0445f91d: =C2=A9 2014 eBay 
> Inc., eBay Europe S.=C3=A0 r.l. 22-24 Boulevard Royal, L-2=
> 449, Luxembourg
> -----------------------------------------------------------------
> 
> ------=_Part_1004737_338826290.1400658139463--
> 
> debug: smtp: 0x9551f226000: data io done (5647 bytes)
> filter: deferring eom query...
> filter: filter_tx_io(0x955207eec00, IO_DISCONNECTED)
> debug: filter: tx done (5647) for req 5fbaf88e0445f91d
> filter: running eom query...
> debug: filter: filter_end_query(-1, 5647, 5647)
> debug: 0x9551f226000: end of message, msgflags=0x0000
> smtp-in: Accepted message b1036160 on session 5fbaf88e0445f91d: 
> from=<[email protected]>, to=<[email protected]>, size=5647, 
> ndest=1, proto=ESMTP
> debug: scheduler: evp:b1036160b48fa5b0 scheduled (mda)
> mda: new user 5fbaf897b003e721 for "<getpwnam>:jc"
> debug: lka: userinfo <getpwnam>:jc
> debug: mda: new session 5fbaf898ce9b3aab for user "<getpwnam>:jc" evpid 
> b1036160b48fa5b0
> debug: mda: no more envelope for "<getpwnam>:jc"
> debug: mda: got message fd 5 for session 5fbaf898ce9b3aab evpid 
> b1036160b48fa5b0
> debug: mda: querying mda fd for session 5fbaf898ce9b3aab evpid 
> b1036160b48fa5b0
> debug: smtpd: forking mda for session 5fbaf898ce9b3aab: "/home/jc/Maildir" as 
> jc
> debug: mda: got mda fd 6 for session 5fbaf898ce9b3aab evpid b1036160b48fa5b0
> debug: mda: end-of-file for session 5fbaf898ce9b3aab evpid b1036160b48fa5b0
> debug: mda: all data sent for session 5fbaf898ce9b3aab evpid b1036160b48fa5b0
> debug: smtpd: mda process done for session 5fbaf898ce9b3aab: exited okay
> delivery: Ok for b1036160b48fa5b0: from=<[email protected]>, 
> to=<[email protected]>, user=jc, method=maildir, delay=0s, 
> stat=Delivered
> debug: mda: session 5fbaf898ce9b3aab done
> debug: mda: user "jc" becomes runnable
> debug: mda: all done for user "<getpwnam>:jc"
> smtp-in: Closing session 5fbaf88e0445f91d
> debug: smtp: 0x9551f226000: deleting session: done
> debug: smtp: new client on listener: 0x95522968000
> smtp-in: New session 5fbaf899275ee7a6 from host azathoth.uphall.net 
> [46.235.226.138]
> debug: lka: looking up pki "yidhra.outer.uphall.net"
> debug: session_start_ssl: switching to SSL
> debug: pony: rsae_priv_enc
> debug: pony: rsae_init
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_init
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> debug: pony: rsae_pub_dec
> debug: pony: rsae_bn_mod_exp
> smtp-in: Started TLS on session 5fbaf899275ee7a6: version=TLSv1/SSLv3, 
> cipher=ECDHE-RSA-AES256-GCM-SHA384, bits=256
> smtp-in: Client certificate verification succeeded on session 5fbaf899275ee7a6
> debug: smtp: SIZE in MAIL FROM command
> debug: aliases_virtual_get: '[email protected]' resolved to 
> 1 nodes
> debug: aliases_get: returned 1 aliases
> smtp: 0x95526ca4000: fd 5 from queue
> smtp: 0x95526ca4000: fd 7 from filter
> filter: filter_tx_io(0x955207ee600, IO_DATAIN)
> filter: filter_tx_io: datain (303) for req 5fbaf899275ee7a6: Received: from 
> azathoth.uphall.net (azathoth.uphall.net [46.235.226.138]);
>         by yidhra.outer.uphall.net (OpenSMTPD) with ESMTPS id d4ad6730;
>         TLS version=TLSv1/SSLv3 cipher=ECDHE-RSA-AES256-GCM-SHA384 bits=256 
> verify=YES;
>         for <[email protected]>;
>         Wed, 21 May 2014 08:46:46 +0100 (BST)
> 
> 
> ----------------
> It appears from the logs on azathoth that stuff should have
> been going on here but wasn't
> 
> some time later (maybe a short hour) I hit Ctrl-C
> 
> 
> ^Cinfo: ca agent exiting
> filter: filter_tx_io(0x955207ee600, IO_DATAIN)
> filter: filter_tx_io: datain (4096) for req 5fbaf899275ee7a6: Received: from 
> shear.ucar.edu (lists.openbsd.org [192.43.244.163])
>         by azathoth.uphall.net (8.14.8/8.14.6) with ESMTP id s4L7kils016858
>         (version=TLSv1/SSLv3 cipher=DHE-RSA-AES256-SHA bits=256 verify=FAIL)
>         for <[email protected]>; Wed, 21 May 2014 08:46:45 +0100 (BST)
> Received: from openbsd.org (localhost [127.0.0.1])
>         by shear.ucar.edu (8.14.5/8.14.5) with ESMTP id s4L7tZ0I027039;
>         Wed, 21 May 2014 01:55:35 -0600 (MDT)
> Received: from plane.gmane.org (plane.gmane.org [80.91.229.3])
>         by shear.ucar.edu (8.14.5/8.14.5) with ESMTP id s4L7slvl029667
>         for <[email protected]>; Wed, 21 May 2014 01:54:48 -0600 (MDT)
> Received: from list
>         by plane.gmane.org with local (Exim 4.69) (envelope-from 
> <[email protected]>) id 1Wn1Cy-0004qE-5Y
>         for [email protected]; Wed, 21 May 2014 09:45:04 +0200
> Received: from naiad.spacehopper.org ([195.95.187.26])
>         by main.gmane.org with esmtp (Gmexim 0.1 (Debian)) id 1AlnuQ-0007hv-00
>         for <[email protected]>; Wed, 21 May 2014 09:45:04 +0200
> Received: from stu
>         by naiad.spacehopper.org with local (Gmexim 0.1 (Debian)) id 
> 1AlnuQ-0007hv-00
>         for <[email protected]>; Wed, 21 May 2014 09:45:04 +0200
> X-Injected-Via-Gmane: http://gmane.org/
> To: [email protected]
> From: Stuart Henderson <[email protected]>
> Subject: Re: iked connecting with sophos (pluto)
> Date: Wed, 21 May 2014 07:44:49 +0000 (UTC)
> Lines: 432
> Message-ID: <[email protected]>
> References: <[email protected]> 
> <[email protected]>
> X-Complaints-To: [email protected]
> X-Gmane-NNTP-Posting-Host: naiad.spacehopper.org
> User-Agent: slrn/1.0.1 (OpenBSD)
> List-Help: <mailto:[email protected]?body=help>
> List-ID: <misc.openbsd.org>
> List-Owner: <mailto:[email protected]>
> List-Post: <mailto:[email protected]>
> List-Subscribe: <mailto:[email protected]?body=sub%20misc>
> List-Unsubscribe: <mailto:[email protected]?body=unsub%20misc>
> X-Loop: [email protected]
> Precedence: list
> Sender: [email protected]
> 
> Are you sure that the server at work is running IKEv2?
> IKE (aka IKEv1, as supported by isakmpd) is far more common.
> 
> On 2014-05-20, Martijn van Duren <[email protected]> wrote:
> > ping?
> >
> > On 05/08/14 14:07, Martijn van Duren wrote:
> >> Hello misc,
> >>
> >> I'm currently trying to set up an ipsec connection from my laptop to the
> >> vpn at my work. I'm new to ipsec, so my apologies if I missed something
> >> obvious.
> >>
> >> When setting up the connection I do see my requests go to the server,
> >> but I never get a reply. My colleagues use an ipsec connection from the
> >> same network, so it should not be a network issue. I have pf on my
> >> laptop disabled for this test.
> >>
> >> Below are the system settings as used, both on the vpn-server as on my
> >> machine. I would like to know if someone could tell me what I'm doing
> >> wrong or whether this is a bug.
> >>
> >> server:
> >> - system: Sophos UTM 9
> >> - daemon: Pluto (strongSwan 4.4.1git20100610 THREADS VENDORID
> >> CISCO_QUIRKS)
> >> - ip: aa.bb.cc.dd
> >> - config (the required information as far as I can see):
> >> Compression off, not using strict policy.
> >> IKE Settings: AES 256 / SHA1 / Group 2: MODP 1024   Lifetime: 36000
> >> seconds
> >> IPsec Settings: AES 256 / SHA1 / Group 2: MODP 1024   Lifetime: 36000
> >> seconds
> >> VPN ID: vpn01.company.tld
> >>
> >> client:
> >> - system: see dmesg below
> >> - network: In a NAT network, with public ip ee.ff.gg.hh
> >> - config:
> >>          - initial:
> >> ikev2 "company" active esp \
> >>         from any to any \
> >>         peer vpn01.company.tld \
> >>         ikesa auth hmac-sha1 prf hmac-sha1 group modp1024 \
> >>         childsa auth hmac-sha1 enc aes-256 group modp1024 \
> >>         dstid vpn01.company.tld \
> >>         lifetime 36000 \
> >>         eap "mschap-v2"
> >>          - 2nd (more basic? config):
> >> ikev2 active esp from any to any peer vpn01.company.tld
> >>
> >> When starting the iked it sends its IKE_SA_INIT, but I never get a
> >> reply:
> >> ca_privkey_serialize: type RSA_KEY length 1192
> >> ca_pubkey_serialize: type RSA_KEY length 270
> >> ca_reload: local cert type RSA_KEY
> >> ikev2_dispatch_cert: updated local CERTREQ type RSA_K
> debug: smtp: new client on listener: 0x95522968000
> info: pony agent exiting
> info: scheduler handler exiting
> info: lookup agent exiting
> info: control process exiting
> info: queue handler exiting
> warn: parent terminating
> #
> 


-- 
Gilles Chehade

https://www.poolp.org                                          @poolpOrg

-- 
You received this mail because you are subscribed to [email protected]
To unsubscribe, send a mail to: [email protected]

Reply via email to