On Wed, 17 Jun 2020 22:51:41 +0200 Frank Elsner via Exim-users wrote:
> On Wed, 17 Jun 2020 11:08:58 +0100 Jeremy Harris via Exim-users wrote:
> > On 17/06/2020 10:56, Frank Elsner via Exim-users wrote:
> > >>> 264381 exec /usr/exim/bin/exim -d=0xf7795cfd -MCd daemon-accept-delivery -Mc 1jiZUo-0016mC-CU
> >
> > >> Is there no other mention of process 264381 (or whatever the process
> > >> doing the exec is)?
> > >
> > > Coming back to the still unsolved problem:
> > >
> > > No other mention.
> >
> > Then you have a big problem. Most Unixen don't disappear a process due
> > to an exec, and most Exim testing I do quite reliably shows a further
> > debug output line from a newly-exec'd exim.
>
> I will repeat the test if time permits.
>
> > Apart from suggesting you double-check... you could involve your
> > kernel support to trace where the exec went to.
>
> Unfortunatly this exceeds my linux skills.
>
> I don't unterstand why exim-4.93 doesn't show this problem with the same kernel.
I could reproduce the problem with exim-4.94 which occures after the system came up after suspend:
22937 Process 22937 is handling incoming connection from [127.0.0.1]
22937 host in host_lookup? yes (matched "0.0.0.0/0")
22937 looking up host name for 127.0.0.1
22937 DNS lookup of 1.0.0.127.in-addr.arpa (PTR) gave TRY_AGAIN
22937 1.0.0.127.in-addr.arpa in dns_again_means_nonexist? no (option unset)
22937 returning DNS_AGAIN
22937 writing neg-cache entry for 1.0.0.127.in-addr.arpa-PTR-41, ttl -1
22937 IP address PTR lookup gave temporary error
22937 sender_fullhost = [127.0.0.1]
22937 sender_rcvhost = [127.0.0.1]
22937 set_process_info: 22937 handling incoming connection from [127.0.0.1]
22937 host in host_reject_connection? no (option unset)
22937 localhost in dns_ipv4_lookup? no (end of list)
22937 duplicate IP address 127.0.0.1 (MX=-1) removed
22937 gethostbyname2 looked up these IP addresses:
22937 name=localhost address=::1
22937 name=localhost address=127.0.0.1
22937 host in sender_unqualified_hosts? yes (matched "localhost")
22937 localhost in dns_ipv4_lookup? no (end of list)
22937 duplicate IP address 127.0.0.1 (MX=-1) removed
22937 gethostbyname2 looked up these IP addresses:
22937 name=localhost address=::1
22937 name=localhost address=127.0.0.1
22937 host in recipient_unqualified_hosts? yes (matched "localhost")
22937 host in helo_verify_hosts? no (option unset)
22937 host in helo_try_verify_hosts? no (option unset)
22937 host in helo_accept_junk_hosts? no (option unset)
22937 host in pipelining_connect_advertise_hosts? yes (matched "*")
22937 SMTP>> 220 siffux.fritz.box - ESMTP (exim-4.94) ready at Thu, 18 Jun 2020 16:51:55 +0200
22937 TCP_INFO getsockopt: Success
22937 Process 22937 is ready for new message
22937 smtp_setup_msg entered
22937 SMTP<< EHLO siffux
22937 siffux in helo_lookup_domains? no (end of list)
22937 sender_fullhost = (siffux) [127.0.0.1]
22937 sender_rcvhost = [127.0.0.1] (helo=siffux)
22937 set_process_info: 22937 handling incoming connection from (siffux) [127.0.0.1]
22937 host in dsn_advertise_hosts? no (option unset)
22937 host in pipelining_advertise_hosts? yes (matched "*")
22937 host in auth_advertise_hosts? yes (matched "*")
22937 host in chunking_advertise_hosts? yes (matched "*")
22937 host in tls_advertise_hosts? no (end of list)
22937 SMTP>> 250-siffux.fritz.box Hello siffux [127.0.0.1]
22937 250-SIZE 209715200
22937 250-8BITMIME
22937 250-PIPELINING
22937 250-X_PIPE_CONNECT
22937 250-CHUNKING
22937 250 HELP
22937 SMTP<< MAIL FROM:<frank.elsner@???>
22937 spool directory space = 20158348K inodes = 1597432 check_space = 10240K inodes = 1000 msg_size = 0
22937 log directory space = 20158348K inodes = 1597432 check_space = 10240K inodes = 100
22937 SMTP>> 250 OK
22937 SMTP<< RCPT TO:<echo@???>
22937 univie.ac.at in percent_hack_domains? no (end of list)
22937 using ACL "check_smtp_rcpt"
22937 processing "accept" (/etc/exim.conf 109)
22937 check hosts = +relay_hosts
22937 host in "<; 127.0.0.0/8 ; ::1 ; siffux.fritz.box"? yes (matched "127.0.0.0/8")
22937 host in "+relay_hosts"? yes (matched "+relay_hosts")
22937 accept: condition test succeeded in ACL "check_smtp_rcpt"
22937 end of ACL "check_smtp_rcpt": ACCEPT
22937 SMTP>> 250 Accepted
22937 SMTP<< DATA
22937 SMTP>> 354 Enter message, ending with "." on a line by itself
22937 search_tidyup called
22937 host in ignore_fromline_hosts? no (option unset)
22937 >>Headers received:
22937 Date: Thu, 18 Jun 2020 16:51:55 +0200
22937 To: echo@???
22937 From: frank.elsner@???
22937 Subject: test Thu, 18 Jun 2020 16:51:55 +0200
22937 Message-Id: <20200618165155.022936@siffux>
22937 X-Mailer: swaks v20190914.0 jetmore.org/john/code/swaks/
22937
22937 address match test: subject=echo@??? pattern=*@localhost
22937 univie.ac.at in "localhost"? no (end of list)
22937 echo@??? in "*@localhost"? no (end of list)
22937 address match test: subject=echo@??? pattern=*@local
22937 univie.ac.at in "local"? no (end of list)
22937 echo@??? in "*@local"? no (end of list)
22937 address match test: subject=echo@??? pattern=*@moltke28
22937 univie.ac.at in "moltke28"? no (end of list)
22937 echo@??? in "*@moltke28"? no (end of list)
22937 address match test: subject=echo@??? pattern=root@???
22937 echo@??? in "root@???"? no (end of list)
22937 address match test: subject=echo@??? pattern=logwatch@???
22937 echo@??? in "logwatch@???"? no (end of list)
22937 address match test: subject=echo@??? pattern=*@*
22937 univie.ac.at in "*"? yes (matched "*")
22937 echo@??? in "*@*"? yes (matched "*@*")
22937 search_open: lsearch "/usr/exim/tbl/synonyms"
22937 search_find: file="/usr/exim/tbl/synonyms"
22937 key="echo@???" partial=-1 affix=NULL starflags=0 opts=NULL
22937 LRU list:
22937 1/usr/exim/tbl/synonyms
22937 End
22937 internal_search_find: file="/usr/exim/tbl/synonyms"
22937 type=lsearch key="echo@???" opts=NULL
22937 file lookup required for echo@???
22937 in /usr/exim/tbl/synonyms
22937 lookup failed
22937 address match test: subject=frank.elsner@??? pattern=root@???
22937 frank.elsner@??? in "root@???"? no (end of list)
22937 address match test: subject=frank.elsner@??? pattern=logwatch@???
22937 frank.elsner@??? in "logwatch@???"? no (end of list)
22937 address match test: subject=frank.elsner@??? pattern=*@*
22937 mailbox.org in "*"? yes (matched "*")
22937 frank.elsner@??? in "*@*"? yes (matched "*@*")
22937 search_open: lsearch "/usr/exim/tbl/synonyms"
22937 cached open
22937 search_find: file="/usr/exim/tbl/synonyms"
22937 key="frank.elsner@???" partial=-1 affix=NULL starflags=0 opts=NULL
22937 LRU list:
22937 1/usr/exim/tbl/synonyms
22937 End
22937 internal_search_find: file="/usr/exim/tbl/synonyms"
22937 type=lsearch key="frank.elsner@???" opts=NULL
22937 file lookup required for frank.elsner@???
22937 in /usr/exim/tbl/synonyms
22937 lookup failed
22937 rewritten sender = frank.elsner@???
22937 rewrite_one_header: type=T:
22937 To: echo@???
22937 address match test: subject=echo@??? pattern=root@???
22937 echo@??? in "root@???"? no (end of list)
22937 address match test: subject=echo@??? pattern=logwatch@???
22937 echo@??? in "logwatch@???"? no (end of list)
22937 address match test: subject=echo@??? pattern=*@*
22937 univie.ac.at in "*"? yes (matched "*")
22937 echo@??? in "*@*"? yes (matched "*@*")
22937 search_open: lsearch "/usr/exim/tbl/synonyms"
22937 cached open
22937 search_find: file="/usr/exim/tbl/synonyms"
22937 key="echo@???" partial=-1 affix=NULL starflags=0 opts=NULL
22937 LRU list:
22937 1/usr/exim/tbl/synonyms
22937 End
22937 internal_search_find: file="/usr/exim/tbl/synonyms"
22937 type=lsearch key="echo@???" opts=NULL
22937 cached data used for lookup of echo@???
22937 in /usr/exim/tbl/synonyms
22937 lookup failed
22937 rewrite_one_header: type=F:
22937 From: frank.elsner@???
22937 address match test: subject=frank.elsner@??? pattern=root@???
22937 frank.elsner@??? in "root@???"? no (end of list)
22937 address match test: subject=frank.elsner@??? pattern=logwatch@???
22937 frank.elsner@??? in "logwatch@???"? no (end of list)
22937 address match test: subject=frank.elsner@??? pattern=*@*
22937 mailbox.org in "*"? yes (matched "*")
22937 frank.elsner@??? in "*@*"? yes (matched "*@*")
22937 search_open: lsearch "/usr/exim/tbl/synonyms"
22937 cached open
22937 search_find: file="/usr/exim/tbl/synonyms"
22937 key="frank.elsner@???" partial=-1 affix=NULL starflags=0 opts=NULL
22937 LRU list:
22937 1/usr/exim/tbl/synonyms
22937 End
22937 internal_search_find: file="/usr/exim/tbl/synonyms"
22937 type=lsearch key="frank.elsner@???" opts=NULL
22937 cached data used for lookup of frank.elsner@???
22937 in /usr/exim/tbl/synonyms
22937 lookup failed
22937 search_tidyup called
22937 >>Headers after rewriting and local additions:
22937 Date: Thu, 18 Jun 2020 16:51:55 +0200
22937 T To: echo@???
22937 F From: frank.elsner@???
22937 Subject: test Thu, 18 Jun 2020 16:51:55 +0200
22937 I Message-Id: <20200618165155.022936@siffux>
22937 X-Mailer: swaks v20190914.0 jetmore.org/john/code/swaks/
22937
22937 Data file name: /var/spool/exim//input//1jlvtT-0005xx-53-D
22937 Data file written for message 1jlvtT-0005xx-53
22937 >>Generated Received: header line
22937 P Received: from [127.0.0.1] (helo=siffux)
22937 by siffux.fritz.box (exim-4.94) with esmtp
22937 for <echo@???>
22937 id 1jlvtT-0005xx-53; Thu, 18 Jun 2020 16:51:55 +0200
22937 using ACL "check_smtp_data"
22937 processing "warn" (/etc/exim.conf 113)
22937 check condition = ${if !def:h_Message-Id: {1}}
22937 =
22937 warn: condition test failed in ACL "check_smtp_data"
22937 processing "accept" (/etc/exim.conf 116)
22937 accept: condition test succeeded in ACL "check_smtp_data"
22937 end of ACL "check_smtp_data": ACCEPT
22937 Writing spool header file: /var/spool/exim//input//hdr.1jlvtT-0005xx-53
22937 DSN: **** SPOOL_OUT - address: <echo@???> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
22937 Renaming spool header file: /var/spool/exim//input//1jlvtT-0005xx-53-H
22937 Size of headers = 408
22937 LOG: MAIN
22937 <= frank.elsner@??? H=(siffux) [127.0.0.1] P=esmtp S=434 id=20200618165155.022936@siffux T="test Thu, 18 Jun 2020 16:51:55 +0200"
22937 tick check: 1592491915.156500 1592491548.933500
22937 waiting 366.223500 sec
--Frank
