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 not using PIPELINING
 162 not using DSN
 163 127.0.0.1 in hosts_require_auth? no (option unset)
 164   SMTP>> MAIL FROM:<>
 165 cmd buf flush ddd bytes
 166   SMTP<< 250 OK
 167   SMTP>> RCPT TO:<qq@remote>
 168 cmd buf flush ddd bytes
 169 sync_responses expect rcpt for qq@remote
 170   SMTP<< 550 Unknown
 171   SMTP>> QUIT
 172 cmd buf flush ddd bytes
 173   SMTP<< 250 OK
 174   SMTP(close)>>
 175 cmdlog: '220:EHLO:250:MAIL:250:RCPT:550:QUIT:250'
 176  locking TESTSUITE/spool/db/callout.lockfile
 177  locked  TESTSUITE/spool/db/callout.lockfile
 178  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR|O_CREAT
 179  returned from EXIM_DBOPEN: 0xAAAAAAAA
 180  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
 181  dbfn_write: key=remote
 182  wrote callout cache domain record for remote:
 183    result=1 postmaster=0 random=0
 184  dbfn_write: key=qq@remote
 185  wrote negative callout cache address record for qq@remote
 186  EXIM_DBCLOSE(0xAAAAAAAA)
 187  closed hints database and lockfile
 188 ----------- end verify ------------
 189 l_message: $acl_verify_message
 190 warn: condition test succeeded in ACL "rcpt"
 191 LOG: MAIN
 192   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
 193 processing "accept" (TESTSUITE/test-config 24)
 194 check senders = qq@remote
 195 qq@remote in "qq@remote"?
 196  list element: qq@remote
 197  address match test: subject=qq@remote pattern=qq@remote
 198  remote in "remote"?
 199   list element: remote
 200   remote in "remote"? yes (matched "remote")
 201  qq@remote in "qq@remote"? yes (matched "qq@remote")
 202 accept: condition test succeeded in ACL "rcpt"
 203 end of ACL "rcpt": ACCEPT
 204 SMTP>> 250 Accepted
 205 SMTP<< rcpt to:<xyz@local>
 206 using ACL "rcpt"
 207 processing "deny" (TESTSUITE/test-config 19)
 208 check senders = qq@remote
 209 qq@remote in "qq@remote"?
 210  list element: qq@remote
 211  address match test: subject=qq@remote pattern=qq@remote
 212  remote in "remote"?
 213   list element: remote
 214   remote in "remote"? yes (matched "remote")
 215  qq@remote in "qq@remote"? yes (matched "qq@remote")
 216 check !verify = sender
 217 using cached sender verify result
 218 deny: condition test failed in ACL "rcpt"
 219 processing "warn" (TESTSUITE/test-config 21)
 220 check senders = qq@remote
 221 qq@remote in "qq@remote"?
 222  list element: qq@remote
 223  address match test: subject=qq@remote pattern=qq@remote
 224  remote in "remote"?
 225   list element: remote
 226   remote in "remote"? yes (matched "remote")
 227  qq@remote in "qq@remote"? yes (matched "qq@remote")
 228 check !verify = sender/callout
 229 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 230 Verifying qq@remote
 231 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 232 Considering qq@remote
 233 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 234 routing qq@remote
 235 --------> r1 router <--------
 236 local_part=qq domain=remote
 237 checking domains
 238 remote in "local"?
 239  list element: local
 240 remote in "local"? no (end of list)
 241 r1 router skipped: domains mismatch
 242 --------> r2 router <--------
 243 local_part=qq domain=remote
 244 checking domains
 245 remote in "remote"?
 246  list element: remote
 247  remote in "remote"? yes (matched "remote")
 248 calling r2 router
 249 r2 router called for qq@remote
 250   domain = remote
 251 route_item = * 127.0.0.1
 252 remote in "*"?
 253  list element: *
 254  remote in "*"? yes (matched "*")
 255 original list of hosts = '127.0.0.1' options = ''
 256 expanded list of hosts = '127.0.0.1' options = ''
 257 finding IP address for 127.0.0.1
 258 calling host_find_byname
 259 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
 260 local host found for non-MX address
 261 fully qualified name = 127.0.0.1
 262 MUNGED: ::1 will be omitted in what follows
 263 get[host|ipnode]byname[2] looked up these IP addresses:
 264   name=127.0.0.1 address=127.0.0.1
 265 remote host address is the local host: remote: configured to try delivery anyway
 266 queued for t2 transport: local_part = qq
 267 domain = remote
 268   errors_to=NULL
 269   domain_data=remote local_part_data=NULL
 270 routed by r2 router
 271   envelope to: qq@remote
 272   transport: t2
 273   host 127.0.0.1 [127.0.0.1]
 274 Attempting full verification using callout
 275  locking TESTSUITE/spool/db/callout.lockfile
 276  locked  TESTSUITE/spool/db/callout.lockfile
 277  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
 278  returned from EXIM_DBOPEN: 0xAAAAAAAA
 279  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
 280  dbfn_read: key=remote
 281  callout cache: found domain record for remote
 282  dbfn_read: key=qq@remote
 283  callout cache: found address record for qq@remote
 284  callout cache: address record is negative
 285  EXIM_DBCLOSE(0xAAAAAAAA)
 286  closed hints database and lockfile
 287 ----------- end verify ------------
 288 l_message: $acl_verify_message
 289 warn: condition test succeeded in ACL "rcpt"
 290 LOG: MAIN
 291   U=CALLER Warning: Sender verify failed
 292 processing "accept" (TESTSUITE/test-config 24)
 293 check senders = qq@remote
 294 qq@remote in "qq@remote"?
 295  list element: qq@remote
 296  address match test: subject=qq@remote pattern=qq@remote
 297  remote in "remote"?
 298   list element: remote
 299   remote in "remote"? yes (matched "remote")
 300  qq@remote in "qq@remote"? yes (matched "qq@remote")
 301 accept: condition test succeeded in ACL "rcpt"
 302 end of ACL "rcpt": ACCEPT
 303 SMTP>> 250 Accepted
 304 SMTP<< quit
 305 SMTP>> 221 mail.test.ex closing connection
 306 LOG: smtp_connection MAIN
 307   SMTP connection from CALLER closed by QUIT
 308 search_tidyup called
 309 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>