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