test/stderr/0398

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=p1234
   4 configuration file is TESTSUITE/test-config
   5 admin user
   6 changed uid/gid: privilege not needed
   7   uid=EXIM_UID gid=EXIM_GID pid=p1234
   8 seeking password data for user "CALLER": cache not available
   9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  11 sender address = CALLER@test.ex
  12 set_process_info: pppp accepting a local SMTP message from <CALLER@test.ex>
  13  in hosts_connection_nolog? no (option unset)
  14 LOG: smtp_connection MAIN
  15   SMTP connection from CALLER
  16 SMTP>> 220 mail.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
  17 smtp_setup_msg entered
  18 SMTP<< helo test
  19 SMTP>> 250 mail.test.ex Hello CALLER at test
  20 SMTP<< mail from:<qq@remote>
  21 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  22 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  23 SMTP>> 250 OK
  24 SMTP<< rcpt to:<abc@local>
  25 using ACL "rcpt"
  26 processing "deny" (TESTSUITE/test-config 19)
  27 check senders = qq@remote
  28 qq@remote in "qq@remote"?
  29  list element: qq@remote
  30  address match test: subject=qq@remote pattern=qq@remote
  31  remote in "remote"?
  32   list element: remote
  33   remote in "remote"? yes (matched "remote")
  34  qq@remote in "qq@remote"? yes (matched "qq@remote")
  35 check !verify = sender
  36 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  37 Verifying qq@remote
  38 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  39 Considering qq@remote
  40 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  41 routing qq@remote
  42 --------> r1 router <--------
  43 local_part=qq domain=remote
  44 checking domains
  45 remote in "local"?
  46  list element: local
  47 remote in "local"? no (end of list)
  48 r1 router skipped: domains mismatch
  49 --------> r2 router <--------
  50 local_part=qq domain=remote
  51 checking domains
  52 remote in "remote"?
  53  list element: remote
  54  remote in "remote"? yes (matched "remote")
  55 calling r2 router
  56 r2 router called for qq@remote
  57   domain = remote
  58 route_item = * 127.0.0.1
  59 remote in "*"?
  60  list element: *
  61  remote in "*"? yes (matched "*")
  62 original list of hosts = '127.0.0.1' options = ''
  63 expanded list of hosts = '127.0.0.1' options = ''
  64 set transport t2
  65 finding IP address for 127.0.0.1
  66 calling host_find_byname
  67 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
  68 local host found for non-MX address
  69 fully qualified name = 127.0.0.1
  70 MUNGED: ::1 will be omitted in what follows
  71 get[host|ipnode]byname[2] looked up these IP addresses:
  72   name=127.0.0.1 address=127.0.0.1
  73 remote host address is the local host: remote: configured to try delivery anyway
  74 queued for t2 transport: local_part = qq
  75 domain = remote
  76   errors_to=NULL
  77   domain_data=remote local_part_data=NULL
  78 routed by r2 router
  79   envelope to: qq@remote
  80   transport: t2
  81   host 127.0.0.1 [127.0.0.1]
  82 ----------- end verify ------------
  83 sender qq@remote verified ok
  84 deny: condition test failed in ACL "rcpt"
  85 processing "warn" (TESTSUITE/test-config 21)
  86 check senders = qq@remote
  87 qq@remote in "qq@remote"?
  88  list element: qq@remote
  89  address match test: subject=qq@remote pattern=qq@remote
  90  remote in "remote"?
  91   list element: remote
  92   remote in "remote"? yes (matched "remote")
  93  qq@remote in "qq@remote"? yes (matched "qq@remote")
  94 check !verify = sender/callout
  95 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  96 Verifying qq@remote
  97 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  98 Considering qq@remote
  99 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 100 routing qq@remote
 101 --------> r1 router <--------
 102 local_part=qq domain=remote
 103 checking domains
 104 remote in "local"?
 105  list element: local
 106 remote in "local"? no (end of list)
 107 r1 router skipped: domains mismatch
 108 --------> r2 router <--------
 109 local_part=qq domain=remote
 110 checking domains
 111 remote in "remote"?
 112  list element: remote
 113  remote in "remote"? yes (matched "remote")
 114 calling r2 router
 115 r2 router called for qq@remote
 116   domain = remote
 117 route_item = * 127.0.0.1
 118 remote in "*"?
 119  list element: *
 120  remote in "*"? yes (matched "*")
 121 original list of hosts = '127.0.0.1' options = ''
 122 expanded list of hosts = '127.0.0.1' options = ''
 123 finding IP address for 127.0.0.1
 124 calling host_find_byname
 125 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
 126 local host found for non-MX address
 127 fully qualified name = 127.0.0.1
 128 MUNGED: ::1 will be omitted in what follows
 129 get[host|ipnode]byname[2] looked up these IP addresses:
 130   name=127.0.0.1 address=127.0.0.1
 131 remote host address is the local host: remote: configured to try delivery anyway
 132 queued for t2 transport: local_part = qq
 133 domain = remote
 134   errors_to=NULL
 135   domain_data=remote local_part_data=NULL
 136 routed by r2 router
 137   envelope to: qq@remote
 138   transport: t2
 139   host 127.0.0.1 [127.0.0.1]
 140 Attempting full verification using callout
 141  locking TESTSUITE/spool/db/callout.lockfile
 142  locked  TESTSUITE/spool/db/callout.lockfile
 143  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
 144  returned from EXIM_DBOPEN: 0xAAAAAAAA
 145  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
 146  dbfn_read: key=remote
 147  callout cache: found domain record for remote
 148  dbfn_read: key=qq@remote
 149  callout cache: no address record found for qq@remote
 150  EXIM_DBCLOSE(0xAAAAAAAA)
 151  closed hints database and lockfile
 152 interface=NULL port=PORT_S
 153 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ... 127.0.0.1 in hosts_try_fastopen?
 154  list element:
 155  connected
 156   SMTP<< 220 Server ready
 157 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
 158   SMTP>> EHLO mail.test.ex
 159 cmd buf flush ddd bytes
 160   SMTP<< 250 OK
 161  compiled RE '.outlook.com$' not found in local cache
 162  compiling RE '.outlook.com$'
 163  compiled RE '.outlook.com$' saved in local cache
 164 not using PIPELINING
 165 not using DSN
 166 127.0.0.1 in hosts_require_auth? no (option unset)
 167   SMTP>> MAIL FROM:<>
 168 cmd buf flush ddd bytes
 169   SMTP<< 250 OK
 170   SMTP>> RCPT TO:<qq@remote>
 171 cmd buf flush ddd bytes
 172 sync_responses expect rcpt for qq@remote
 173   SMTP<< 550 Unknown
 174   SMTP>> QUIT
 175 cmd buf flush ddd bytes
 176   SMTP<< 250 OK
 177   SMTP(close)>>
 178 cmdlog: '220:EHLO:250:MAIL:250:RCPT:550:QUIT:250'
 179  locking TESTSUITE/spool/db/callout.lockfile
 180  locked  TESTSUITE/spool/db/callout.lockfile
 181  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR|O_CREAT
 182  returned from EXIM_DBOPEN: 0xAAAAAAAA
 183  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
 184  dbfn_write: key=remote
 185  wrote callout cache domain record for remote:
 186    result=1 postmaster=0 random=0
 187  dbfn_write: key=qq@remote
 188  wrote negative callout cache address record for qq@remote
 189  EXIM_DBCLOSE(0xAAAAAAAA)
 190  closed hints database and lockfile
 191 ----------- end verify ------------
 192 l_message: $acl_verify_message
 193 warn: condition test succeeded in ACL "rcpt"
 194 LOG: MAIN
 195   U=CALLER Warning: Sender verify failed: 127.0.0.1 [127.0.0.1] : SMTP error from remote mail server after RCPT TO:<qq@remote>: 550 Unknown
 196 processing "accept" (TESTSUITE/test-config 24)
 197 check senders = qq@remote
 198 qq@remote in "qq@remote"?
 199  list element: qq@remote
 200  address match test: subject=qq@remote pattern=qq@remote
 201  remote in "remote"?
 202   list element: remote
 203   remote in "remote"? yes (matched "remote")
 204  qq@remote in "qq@remote"? yes (matched "qq@remote")
 205 accept: condition test succeeded in ACL "rcpt"
 206 end of ACL "rcpt": ACCEPT
 207 SMTP>> 250 Accepted
 208 SMTP<< rcpt to:<xyz@local>
 209 using ACL "rcpt"
 210 processing "deny" (TESTSUITE/test-config 19)
 211 check senders = qq@remote
 212 qq@remote in "qq@remote"?
 213  list element: qq@remote
 214  address match test: subject=qq@remote pattern=qq@remote
 215  remote in "remote"?
 216   list element: remote
 217   remote in "remote"? yes (matched "remote")
 218  qq@remote in "qq@remote"? yes (matched "qq@remote")
 219 check !verify = sender
 220 using cached sender verify result
 221 deny: condition test failed in ACL "rcpt"
 222 processing "warn" (TESTSUITE/test-config 21)
 223 check senders = qq@remote
 224 qq@remote in "qq@remote"?
 225  list element: qq@remote
 226  address match test: subject=qq@remote pattern=qq@remote
 227  remote in "remote"?
 228   list element: remote
 229   remote in "remote"? yes (matched "remote")
 230  qq@remote in "qq@remote"? yes (matched "qq@remote")
 231 check !verify = sender/callout
 232 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 233 Verifying qq@remote
 234 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 235 Considering qq@remote
 236 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 237 routing qq@remote
 238 --------> r1 router <--------
 239 local_part=qq domain=remote
 240 checking domains
 241 remote in "local"?
 242  list element: local
 243 remote in "local"? no (end of list)
 244 r1 router skipped: domains mismatch
 245 --------> r2 router <--------
 246 local_part=qq domain=remote
 247 checking domains
 248 remote in "remote"?
 249  list element: remote
 250  remote in "remote"? yes (matched "remote")
 251 calling r2 router
 252 r2 router called for qq@remote
 253   domain = remote
 254 route_item = * 127.0.0.1
 255 remote in "*"?
 256  list element: *
 257  remote in "*"? yes (matched "*")
 258 original list of hosts = '127.0.0.1' options = ''
 259 expanded list of hosts = '127.0.0.1' options = ''
 260 finding IP address for 127.0.0.1
 261 calling host_find_byname
 262 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
 263 local host found for non-MX address
 264 fully qualified name = 127.0.0.1
 265 MUNGED: ::1 will be omitted in what follows
 266 get[host|ipnode]byname[2] looked up these IP addresses:
 267   name=127.0.0.1 address=127.0.0.1
 268 remote host address is the local host: remote: configured to try delivery anyway
 269 queued for t2 transport: local_part = qq
 270 domain = remote
 271   errors_to=NULL
 272   domain_data=remote local_part_data=NULL
 273 routed by r2 router
 274   envelope to: qq@remote
 275   transport: t2
 276   host 127.0.0.1 [127.0.0.1]
 277 Attempting full verification using callout
 278  locking TESTSUITE/spool/db/callout.lockfile
 279  locked  TESTSUITE/spool/db/callout.lockfile
 280  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
 281  returned from EXIM_DBOPEN: 0xAAAAAAAA
 282  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
 283  dbfn_read: key=remote
 284  callout cache: found domain record for remote
 285  dbfn_read: key=qq@remote
 286  callout cache: found address record for qq@remote
 287  callout cache: address record is negative
 288  EXIM_DBCLOSE(0xAAAAAAAA)
 289  closed hints database and lockfile
 290 ----------- end verify ------------
 291 l_message: $acl_verify_message
 292 warn: condition test succeeded in ACL "rcpt"
 293 LOG: MAIN
 294   U=CALLER Warning: Sender verify failed
 295 processing "accept" (TESTSUITE/test-config 24)
 296 check senders = qq@remote
 297 qq@remote in "qq@remote"?
 298  list element: qq@remote
 299  address match test: subject=qq@remote pattern=qq@remote
 300  remote in "remote"?
 301   list element: remote
 302   remote in "remote"? yes (matched "remote")
 303  qq@remote in "qq@remote"? yes (matched "qq@remote")
 304 accept: condition test succeeded in ACL "rcpt"
 305 end of ACL "rcpt": ACCEPT
 306 SMTP>> 250 Accepted
 307 SMTP<< quit
 308 SMTP>> 221 mail.test.ex closing connection
 309 LOG: smtp_connection MAIN
 310   SMTP connection from CALLER closed by QUIT
 311 search_tidyup called
 312 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>