test/stderr/0432

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