test/stderr/2201

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=p1236
   4 seeking password data for user "CALLER": cache not available
   5 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
   6 configuration file is TESTSUITE/test-config
   7 trusted user
   8 admin user
   9 dropping to exim gid; retaining priv uid
  10 seeking password data for user "CALLER": using cached result
  11 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  12 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  13 sender address = CALLER@myhost.test.ex
  14 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
  15 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  16 Testing test.ex@test.ex
  17 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  18 Considering test.ex@test.ex
  19 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  20 routing test.ex@test.ex
  21 --------> r0 router <--------
  22 local_part=test.ex domain=test.ex
  23 checking senders
  24 CALLER@myhost.test.ex in "a@shorthost.test.ex"?
  25  list element: a@shorthost.test.ex
  26  address match test: subject=CALLER@myhost.test.ex pattern=a@shorthost.test.ex
  27 CALLER@myhost.test.ex in "a@shorthost.test.ex"? no (end of list)
  28 r0 router skipped: senders mismatch
  29 --------> r1 router <--------
  30 local_part=test.ex domain=test.ex
  31 checking domains
  32 test.ex in "dnsdb;test.ex"?
  33  list element: dnsdb;test.ex
  34  search_open: dnsdb "NULL"
  35  search_find: file="NULL"
  36    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
  37  LRU list:
  38  internal_search_find: file="NULL"
  39    type=dnsdb key="test.ex" opts=NULL
  40  database lookup required for test.ex
  41                               (tainted)
  42  dnsdb key: test.ex
  43 DNS lookup of test.ex (TXT) using fakens
  44 DNS lookup of test.ex (TXT) succeeded
  45  creating new cache entry
  46  lookup yielded: A TXT record for test.ex.
  47  test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
  48 checking local_parts
  49 test.ex in "dnsdb;test.ex"?
  50  list element: dnsdb;test.ex
  51  search_open: dnsdb "NULL"
  52    cached open
  53  search_find: file="NULL"
  54    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
  55  LRU list:
  56  internal_search_find: file="NULL"
  57    type=dnsdb key="test.ex" opts=NULL
  58  cached data used for lookup of test.ex
  59  lookup yielded: A TXT record for test.ex.
  60  test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
  61 calling r1 router
  62 r1 router called for test.ex@test.ex
  63   domain = test.ex
  64 set transport local_delivery
  65 queued for local_delivery transport: local_part = test.ex
  66 domain = test.ex
  67   errors_to=NULL
  68   domain_data=A TXT record for test.ex. local_part_data=A TXT record for test.ex.
  69 routed by r1 router
  70   envelope to: test.ex@test.ex
  71   transport: local_delivery
  72 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  73 Testing unknown@test.ex
  74 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  75 Considering unknown@test.ex
  76 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  77 routing unknown@test.ex
  78 --------> r0 router <--------
  79 local_part=unknown domain=test.ex
  80 checking senders
  81 CALLER@myhost.test.ex in "a@shorthost.test.ex"?
  82  list element: a@shorthost.test.ex
  83  address match test: subject=CALLER@myhost.test.ex pattern=a@shorthost.test.ex
  84 CALLER@myhost.test.ex in "a@shorthost.test.ex"? no (end of list)
  85 r0 router skipped: senders mismatch
  86 --------> r1 router <--------
  87 local_part=unknown domain=test.ex
  88 checking domains
  89 test.ex in "dnsdb;test.ex"?
  90  list element: dnsdb;test.ex
  91  search_open: dnsdb "NULL"
  92    cached open
  93  search_find: file="NULL"
  94    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
  95  LRU list:
  96  internal_search_find: file="NULL"
  97    type=dnsdb key="test.ex" opts=NULL
  98  cached data used for lookup of test.ex
  99  lookup yielded: A TXT record for test.ex.
 100  test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
 101 checking local_parts
 102 unknown in "dnsdb;unknown"?
 103  list element: dnsdb;unknown
 104  search_open: dnsdb "NULL"
 105    cached open
 106  search_find: file="NULL"
 107    key="unknown" partial=-1 affix=NULL starflags=0 opts=NULL
 108  LRU list:
 109  internal_search_find: file="NULL"
 110    type=dnsdb key="unknown" opts=NULL
 111  database lookup required for unknown
 112                               (tainted)
 113  dnsdb key: unknown
 114 DNS lookup of unknown (TXT) using fakens
 115 DNS lookup of unknown (TXT) gave HOST_NOT_FOUND
 116 returning DNS_NOMATCH
 117 faking res_search(TXT) response length as 65535
 118  writing neg-cache entry for unknown-TXT-xxxx, ttl 3000
 119  creating new cache entry
 120  lookup failed
 121 unknown in "dnsdb;unknown"? no (end of list)
 122 r1 router skipped: local_parts mismatch
 123 --------> r2 router <--------
 124 local_part=unknown domain=test.ex
 125 checking domains
 126 test.ex in "dnsdb;test.ex"?
 127  list element: dnsdb;test.ex
 128  search_open: dnsdb "NULL"
 129    cached open
 130  search_find: file="NULL"
 131    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
 132  LRU list:
 133  internal_search_find: file="NULL"
 134    type=dnsdb key="test.ex" opts=NULL
 135  cached data used for lookup of test.ex
 136  lookup yielded: A TXT record for test.ex.
 137  test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
 138 checking senders
 139 CALLER@myhost.test.ex in "dnsdb;A=myhost.test.ex"?
 140  list element: dnsdb;A=myhost.test.ex
 141  address match test: subject=CALLER@myhost.test.ex pattern=dnsdb;A=myhost.test.ex
 142  search_open: dnsdb "NULL"
 143    cached open
 144  search_find: file="NULL"
 145    key="A=myhost.test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
 146  LRU list:
 147  internal_search_find: file="NULL"
 148    type=dnsdb key="A=myhost.test.ex" opts=NULL
 149  database lookup required for A=myhost.test.ex
 150  dnsdb key: myhost.test.ex
 151 DNS lookup of myhost.test.ex (A) using fakens
 152 DNS lookup of myhost.test.ex (A) succeeded
 153  creating new cache entry
 154  lookup yielded: V4NET.10.10.10
 155  CALLER@myhost.test.ex in "dnsdb;A=myhost.test.ex"? yes (matched "dnsdb;A=myhost.test.ex")
 156 calling r2 router
 157 r2 router called for unknown@test.ex
 158   domain = test.ex
 159 set transport local_delivery
 160 queued for local_delivery transport: local_part = unknown
 161 domain = test.ex
 162   errors_to=NULL
 163   domain_data=A TXT record for test.ex. local_part_data=NULL
 164 routed by r2 router
 165   envelope to: unknown@test.ex
 166   transport: local_delivery
 167 search_tidyup called
 168 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 169 Exim version x.yz ....
 170 configuration file is TESTSUITE/test-config
 171 trusted user
 172 admin user
 173 search_tidyup called
 174 search_tidyup called
 175 search_open: dnsdb "NULL"
 176 search_find: file="NULL"
 177   key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
 178 LRU list:
 179 internal_search_find: file="NULL"
 180   type=dnsdb key="a=shorthost.test.ex" opts=NULL
 181 database lookup required for a=shorthost.test.ex
 182                              (tainted)
 183 dnsdb key: shorthost.test.ex
 184 creating new cache entry
 185 lookup yielded: 127.0.0.1
 186 search_open: dnsdb "NULL"
 187   cached open
 188 search_find: file="NULL"
 189   key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
 190 LRU list:
 191 internal_search_find: file="NULL"
 192   type=dnsdb key="a=shorthost.test.ex" opts=NULL
 193 cached data used for lookup of a=shorthost.test.ex
 194 lookup yielded: 127.0.0.1
 195 search_open: dnsdb "NULL"
 196   cached open
 197 search_find: file="NULL"
 198   key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
 199 LRU list:
 200 internal_search_find: file="NULL"
 201   type=dnsdb key="a=shorthost.test.ex" opts=NULL
 202 cached data found but out-of-date; database lookup required for a=shorthost.test.ex
 203                              (tainted)
 204 dnsdb key: shorthost.test.ex
 205 replacing old cache entry
 206 lookup yielded: 127.0.0.1
 207 LOG: MAIN
 208   <= a@shorthost.test.ex U=CALLER P=local S=sss
 209 created log directory TESTSUITE/spool/log
 210 search_tidyup called
 211 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 212
 213 ******** SERVER ********
 214 Exim version x.yz ....
 215 configuration file is TESTSUITE/test-config
 216 trusted user
 217 admin user
 218 dropping to exim gid; retaining priv uid
 219 daemon_smtp_port overridden by -oX:
 220   <: 1225
 221 creating notifier socket
 222  TESTSUITE/spool/exim_daemon_notify
 223 listening on all interfaces (IPv4) port PORT_D
 224 pid written to TESTSUITE/spool/exim-daemon.pid
 225 LOG: MAIN
 226   exim x.yz daemon started: pid=p1235, no queue runs, listening for SMTP on port PORT_D
 227 daemon running with uid=EXIM_UID gid=EXIM_GID euid=EXIM_UID egid=EXIM_GID
 228 Listening...
 229 Connection request from 127.0.0.1 port sssss
 230 p1238 Process p1238 is handling incoming connection from [127.0.0.1]
 231 p1238 Process p1238 is ready for new message
 232 1 SMTP accept process running
 233 Listening...
 234 p1238 dnslists check: rbl.test.ex/V4NET.11.12.14
 235 p1238 new DNS lookup for 14.12.11.V4NET.rbl.test.ex
 236 p1238 dnslists: wrote cache entry, ttl=2
 237 p1238 DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
 238 p1238 => that means V4NET.11.12.14 is listed at rbl.test.ex
 239 p1238 dnslists check: rbl.test.ex/V4NET.11.12.14
 240 p1238 dnslists: using result of previous lookup
 241 p1238 DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
 242 p1238 => that means V4NET.11.12.14 is listed at rbl.test.ex
 243 p1238 dnslists check: rbl.test.ex/V4NET.11.12.14
 244 p1238 cached data found but past valid time; new DNS lookup for 14.12.11.V4NET.rbl.test.ex
 245 p1238 dnslists: wrote cache entry, ttl=2
 246 p1238 DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
 247 p1238 => that means V4NET.11.12.14 is listed at rbl.test.ex
 248 LOG: MAIN
 249   <= a@shorthost.test.ex H=localhost (myhost.test.ex) [127.0.0.1] P=esmtp S=sss id=E10HmaX-000000005vi-0000@myhost.test.ex
 250 Process p1238 is ready for new message
 251 LOG: smtp_connection MAIN
 252   SMTP connection from localhost (myhost.test.ex) [127.0.0.1] D=qqs closed by QUIT
 253 >>>>>>>>>>>>>>>> Exim pid=p1238 (daemon-accept) terminating with rc=0 >>>>>>>>>>>>>>>>
 254 p1235 child p1238 ended: status=0x0
 255 p1235   normal exit, 0
 256 p1235 0 SMTP accept processes now running
 257 p1235 Listening...
 258 p1235 SIGTERM/SIGINT seen
 259 p1235 >>>>>>>>>>>>>>>> Exim pid=p1235 (daemon) terminating with rc=0 >>>>>>>>>>>>>>>>