test/stderr/2600

   1 Exim version x.yz ....
   2 configuration file is TESTSUITE/test-config
   3 admin user
   4 dropping to exim gid; retaining priv uid
   5  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
   6  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
   7    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
   8  LRU list:
   9  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  10    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  11  file lookup required for select name from them where id='userx';
  12    in TESTSUITE/aux-fixed/sqlitedb
  13  creating new cache entry
  14  lookup yielded: Ayen Other
  15  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  16    cached open
  17  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  18    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
  19  LRU list:
  20  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  21    type=sqlite key="select name from them where id='userx';" opts=NULL
  22  cached data found but wrong opts;  file lookup required for select name from them where id='userx';
  23  c  in TESTSUITE/aux-fixed/sqlitedb
  24  replacing old cache entry
  25  lookup yielded: Ayen Other
  26  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  27    cached open
  28  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  29    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  30  LRU list:
  31  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  32    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  33  cached data found but wrong opts;  file lookup required for select name from them where id='userx';
  34  c  in TESTSUITE/aux-fixed/sqlitedb
  35  replacing old cache entry
  36  lookup yielded: Ayen Other
  37  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  38    cached open
  39  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  40    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  41  LRU list:
  42  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  43    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  44  cached data used for lookup of select name from them where id='userx';
  45    in TESTSUITE/aux-fixed/sqlitedb
  46  lookup yielded: Ayen Other
  47  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  48    cached open
  49  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  50    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  51  LRU list:
  52  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  53    type=sqlite key="select name from them where id='xxxx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  54  file lookup required for select name from them where id='xxxx';
  55    in TESTSUITE/aux-fixed/sqlitedb
  56  lookup forced cache cleanup
  57  lookup failed
  58  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  59    cached open
  60  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  61    key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  62  LRU list:
  63  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  64    type=sqlite key="select name from them where id='nothing';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  65  file lookup required for select name from them where id='nothing';
  66    in TESTSUITE/aux-fixed/sqlitedb
  67  creating new cache entry
  68  lookup yielded:
  69  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  70    cached open
  71  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  72    key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  73  LRU list:
  74  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  75    type=sqlite key="select id,name from them where id='nothing';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  76  file lookup required for select id,name from them where id='nothing';
  77    in TESTSUITE/aux-fixed/sqlitedb
  78  creating new cache entry
  79  lookup yielded: id=nothing name=""
  80  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  81    cached open
  82  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  83    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  84  LRU list:
  85  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  86    type=sqlite key="select * from them where id='quote2';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  87  file lookup required for select * from them where id='quote2';
  88    in TESTSUITE/aux-fixed/sqlitedb
  89  creating new cache entry
  90  lookup yielded: name="\"stquot" id=quote2
  91  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  92    cached open
  93  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  94    key="select * from them where id='newline';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
  95  LRU list:
  96  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  97    type=sqlite key="select * from them where id='newline';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
  98  file lookup required for select * from them where id='newline';
  99    in TESTSUITE/aux-fixed/sqlitedb
 100  creating new cache entry
 101  lookup yielded: name="before
 102  after" id=newline
 103  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 104    cached open
 105  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 106    key="select * from them where id='tab';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 107  LRU list:
 108  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 109    type=sqlite key="select * from them where id='tab';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 110  file lookup required for select * from them where id='tab';
 111    in TESTSUITE/aux-fixed/sqlitedb
 112  creating new cache entry
 113  lookup yielded: name="x        x" id=tab
 114  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 115    cached open
 116  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 117    key="select * from them where id='its';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 118  LRU list:
 119  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 120    type=sqlite key="select * from them where id='its';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 121  file lookup required for select * from them where id='its';
 122    in TESTSUITE/aux-fixed/sqlitedb
 123  creating new cache entry
 124  lookup yielded: name=it's id=its
 125  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 126    cached open
 127  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 128    key="select * from them where name='it''s';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 129  LRU list:
 130  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 131    type=sqlite key="select * from them where name='it''s';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 132  file lookup required for select * from them where name='it''s';
 133    in TESTSUITE/aux-fixed/sqlitedb
 134  creating new cache entry
 135  lookup yielded: name=it's id=its
 136 search_tidyup called
 137 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 138 Exim version x.yz ....
 139 changed uid/gid: forcing real = effective
 140   uid=uuuu gid=CALLER_GID pid=p1235
 141 configuration file is TESTSUITE/test-config
 142 admin user
 143 changed uid/gid: privilege not needed
 144   uid=EXIM_UID gid=EXIM_GID pid=p1235
 145 seeking password data for user "CALLER": cache not available
 146 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 147 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 148 sender address = CALLER@myhost.test.ex
 149 sender_fullhost = [10.0.0.0]
 150 sender_rcvhost = [10.0.0.0]
 151 host in hosts_connection_nolog? no (option unset)
 152 LOG: smtp_connection MAIN
 153   SMTP connection from [10.0.0.0]
 154 host in host_lookup? no (option unset)
 155 set_process_info: pppp handling incoming connection from [10.0.0.0]
 156 host in host_reject_connection? no (option unset)
 157 host in sender_unqualified_hosts? no (option unset)
 158 host in recipient_unqualified_hosts? no (option unset)
 159 host in helo_verify_hosts? no (option unset)
 160 host in helo_try_verify_hosts? no (option unset)
 161 host in helo_accept_junk_hosts? no (option unset)
 162 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 163 smtp_setup_msg entered
 164 SMTP<< helo test
 165 test in helo_lookup_domains?
 166  list element: @
 167  list element: @[]
 168 test in helo_lookup_domains? no (end of list)
 169 sender_fullhost = (test) [10.0.0.0]
 170 sender_rcvhost = [10.0.0.0] (helo=test)
 171 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
 172 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
 173 SMTP<< mail from:<a@b>
 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 SMTP>> 250 OK
 177 SMTP<< rcpt to:<c@d>
 178 using ACL "check_recipient"
 179 processing "accept" (TESTSUITE/test-config 32)
 180 check domains = +local_domains
 181 d in "+local_domains"?
 182  list element: +local_domains
 183   start sublist local_domains
 184    d in "@"?
 185    ╎list element: @
 186    d in "@"? no (end of list)
 187   end sublist local_domains
 188 d in "+local_domains"? no (end of list)
 189 accept: condition test failed in ACL "check_recipient"
 190 processing "accept" (TESTSUITE/test-config 33)
 191 check hosts = +relay_hosts
 192 host in "+relay_hosts"?
 193  list element: +relay_hosts
 194   start sublist relay_hosts
 195   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"?
 196    ╎list element: sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'
 197    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 198    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 199    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 200    ╎LRU list:
 201    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 202    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 203    ╎file lookup required for select * from them where id='10.0.0.0'
 204    ╎  in TESTSUITE/aux-fixed/sqlitedb
 205    ╎lookup forced cache cleanup
 206    ╎lookup failed
 207   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
 208   end sublist relay_hosts
 209 host in "+relay_hosts"? no (end of list)
 210 accept: condition test failed in ACL "check_recipient"
 211 processing "deny" (TESTSUITE/test-config 34)
 212   message: relay not permitted
 213 deny: condition test succeeded in ACL "check_recipient"
 214 end of ACL "check_recipient": DENY
 215 SMTP>> 550 relay not permitted
 216 LOG: MAIN REJECT
 217   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 218 SMTP<< rcpt to:<c@d>
 219 using ACL "check_recipient"
 220 processing "accept" (TESTSUITE/test-config 32)
 221 check domains = +local_domains
 222 d in "+local_domains"?
 223  list element: +local_domains
 224   start sublist local_domains
 225    d in "@"?
 226    ╎list element: @
 227    d in "@"? no (end of list)
 228   end sublist local_domains
 229 d in "+local_domains"? no (end of list)
 230 accept: condition test failed in ACL "check_recipient"
 231 processing "accept" (TESTSUITE/test-config 33)
 232 check hosts = +relay_hosts
 233 host in "+relay_hosts"?
 234  list element: +relay_hosts
 235   start sublist relay_hosts
 236   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"?
 237    ╎list element: sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'
 238    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 239    ╎  cached open
 240    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 241    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 242    ╎LRU list:
 243    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 244    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 245    ╎file lookup required for select * from them where id='10.0.0.0'
 246    ╎  in TESTSUITE/aux-fixed/sqlitedb
 247    ╎lookup forced cache cleanup
 248    ╎lookup failed
 249   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
 250   end sublist relay_hosts
 251 host in "+relay_hosts"? no (end of list)
 252 accept: condition test failed in ACL "check_recipient"
 253 processing "deny" (TESTSUITE/test-config 34)
 254   message: relay not permitted
 255 deny: condition test succeeded in ACL "check_recipient"
 256 end of ACL "check_recipient": DENY
 257 SMTP>> 550 relay not permitted
 258 LOG: MAIN REJECT
 259   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 260 SMTP<< quit
 261 SMTP>> 221 myhost.test.ex closing connection
 262 LOG: smtp_connection MAIN
 263   SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
 264 search_tidyup called
 265 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 266 Exim version x.yz ....
 267 changed uid/gid: forcing real = effective
 268   uid=uuuu gid=CALLER_GID pid=p1236
 269 configuration file is TESTSUITE/test-config
 270 admin user
 271 changed uid/gid: privilege not needed
 272   uid=EXIM_UID gid=EXIM_GID pid=p1236
 273 seeking password data for user "CALLER": cache not available
 274 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 275 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 276 sender address = CALLER@myhost.test.ex
 277 sender_fullhost = [10.0.0.0]
 278 sender_rcvhost = [10.0.0.0]
 279 host in hosts_connection_nolog? no (option unset)
 280 LOG: smtp_connection MAIN
 281   SMTP connection from [10.0.0.0]
 282 host in host_lookup? no (option unset)
 283 set_process_info: pppp handling incoming connection from [10.0.0.0]
 284 host in host_reject_connection? no (option unset)
 285 host in sender_unqualified_hosts? no (option unset)
 286 host in recipient_unqualified_hosts? no (option unset)
 287 host in helo_verify_hosts? no (option unset)
 288 host in helo_try_verify_hosts? no (option unset)
 289 host in helo_accept_junk_hosts? no (option unset)
 290 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 291 smtp_setup_msg entered
 292 SMTP<< helo test
 293 test in helo_lookup_domains?
 294  list element: @
 295  list element: @[]
 296 test in helo_lookup_domains? no (end of list)
 297 sender_fullhost = (test) [10.0.0.0]
 298 sender_rcvhost = [10.0.0.0] (helo=test)
 299 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
 300 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
 301 SMTP<< mail from:<a@b>
 302 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 303 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 304 SMTP>> 250 OK
 305 SMTP<< rcpt to:<c@d>
 306 using ACL "check_recipient"
 307 processing "accept" (TESTSUITE/test-config 32)
 308 check domains = +local_domains
 309 d in "+local_domains"?
 310  list element: +local_domains
 311   start sublist local_domains
 312    d in "@"?
 313    ╎list element: @
 314    d in "@"? no (end of list)
 315   end sublist local_domains
 316 d in "+local_domains"? no (end of list)
 317 accept: condition test failed in ACL "check_recipient"
 318 processing "accept" (TESTSUITE/test-config 33)
 319 check hosts = +relay_hosts
 320 host in "+relay_hosts"?
 321  list element: +relay_hosts
 322   start sublist relay_hosts
 323   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"?
 324    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'
 325    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 326    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 327    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 328    ╎LRU list:
 329    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 330    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
 331    ╎file lookup required for select * from them where id='10.0.0.0'
 332    ╎  in TESTSUITE/aux-fixed/sqlitedb
 333    ╎lookup forced cache cleanup
 334    ╎lookup failed
 335   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
 336   end sublist relay_hosts
 337 host in "+relay_hosts"? no (end of list)
 338 accept: condition test failed in ACL "check_recipient"
 339 processing "deny" (TESTSUITE/test-config 34)
 340   message: relay not permitted
 341 deny: condition test succeeded in ACL "check_recipient"
 342 end of ACL "check_recipient": DENY
 343 SMTP>> 550 relay not permitted
 344 LOG: MAIN REJECT
 345   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 346 SMTP<< rcpt to:<c@d>
 347 using ACL "check_recipient"
 348 processing "accept" (TESTSUITE/test-config 32)
 349 check domains = +local_domains
 350 d in "+local_domains"?
 351  list element: +local_domains
 352   start sublist local_domains
 353    d in "@"?
 354    ╎list element: @
 355    d in "@"? no (end of list)
 356   end sublist local_domains
 357 d in "+local_domains"? no (end of list)
 358 accept: condition test failed in ACL "check_recipient"
 359 processing "accept" (TESTSUITE/test-config 33)
 360 check hosts = +relay_hosts
 361 host in "+relay_hosts"?
 362  list element: +relay_hosts
 363   start sublist relay_hosts
 364   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"?
 365    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'
 366    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 367    ╎  cached open
 368    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 369    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 370    ╎LRU list:
 371    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 372    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
 373    ╎file lookup required for select * from them where id='10.0.0.0'
 374    ╎  in TESTSUITE/aux-fixed/sqlitedb
 375    ╎lookup forced cache cleanup
 376    ╎lookup failed
 377   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
 378   end sublist relay_hosts
 379 host in "+relay_hosts"? no (end of list)
 380 accept: condition test failed in ACL "check_recipient"
 381 processing "deny" (TESTSUITE/test-config 34)
 382   message: relay not permitted
 383 deny: condition test succeeded in ACL "check_recipient"
 384 end of ACL "check_recipient": DENY
 385 SMTP>> 550 relay not permitted
 386 LOG: MAIN REJECT
 387   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 388 SMTP<< quit
 389 SMTP>> 221 myhost.test.ex closing connection
 390 LOG: smtp_connection MAIN
 391   SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
 392 search_tidyup called
 393 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 394 Exim version x.yz ....
 395 changed uid/gid: forcing real = effective
 396   uid=uuuu gid=CALLER_GID pid=p1237
 397 configuration file is TESTSUITE/test-config
 398 admin user
 399 changed uid/gid: privilege not needed
 400   uid=EXIM_UID gid=EXIM_GID pid=p1237
 401 seeking password data for user "CALLER": cache not available
 402 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 403 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 404 sender address = CALLER@myhost.test.ex
 405 sender_fullhost = [10.10.10.10]
 406 sender_rcvhost = [10.10.10.10]
 407 host in hosts_connection_nolog? no (option unset)
 408 LOG: smtp_connection MAIN
 409   SMTP connection from [10.10.10.10]
 410 host in host_lookup? no (option unset)
 411 set_process_info: pppp handling incoming connection from [10.10.10.10]
 412 host in host_reject_connection? no (option unset)
 413 host in sender_unqualified_hosts? no (option unset)
 414 host in recipient_unqualified_hosts? no (option unset)
 415 host in helo_verify_hosts? no (option unset)
 416 host in helo_try_verify_hosts? no (option unset)
 417 host in helo_accept_junk_hosts? no (option unset)
 418 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 419 smtp_setup_msg entered
 420 SMTP<< helo test
 421 test in helo_lookup_domains?
 422  list element: @
 423  list element: @[]
 424 test in helo_lookup_domains? no (end of list)
 425 sender_fullhost = (test) [10.10.10.10]
 426 sender_rcvhost = [10.10.10.10] (helo=test)
 427 set_process_info: pppp handling incoming connection from (test) [10.10.10.10]
 428 SMTP>> 250 myhost.test.ex Hello test [10.10.10.10]
 429 SMTP<< mail from:<a@b>
 430 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 431 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 432 SMTP>> 250 OK
 433 SMTP<< rcpt to:<c@d>
 434 using ACL "check_recipient"
 435 processing "accept" (TESTSUITE/test-config 32)
 436 check domains = +local_domains
 437 d in "+local_domains"?
 438  list element: +local_domains
 439   start sublist local_domains
 440    d in "@"?
 441    ╎list element: @
 442    d in "@"? no (end of list)
 443   end sublist local_domains
 444 d in "+local_domains"? no (end of list)
 445 accept: condition test failed in ACL "check_recipient"
 446 processing "accept" (TESTSUITE/test-config 33)
 447 check hosts = +relay_hosts
 448 host in "+relay_hosts"?
 449  list element: +relay_hosts
 450   start sublist relay_hosts
 451   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"?
 452    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'
 453    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 454    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 455    ╎  key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
 456    ╎LRU list:
 457    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 458    ╎  type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
 459    ╎file lookup required for select * from them where id='10.10.10.10'
 460    ╎  in TESTSUITE/aux-fixed/sqlitedb
 461    ╎creating new cache entry
 462    ╎lookup yielded: name=ok id=10.10.10.10
 463    ╎host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"? yes (matched "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'")
 464   end sublist relay_hosts
 465   host in "+relay_hosts"? yes (matched "+relay_hosts")
 466 accept: condition test succeeded in ACL "check_recipient"
 467 end of ACL "check_recipient": ACCEPT
 468 SMTP>> 250 Accepted
 469 SMTP<< rcpt to:<c@d>
 470 using ACL "check_recipient"
 471 processing "accept" (TESTSUITE/test-config 32)
 472 check domains = +local_domains
 473 d in "+local_domains"?
 474  list element: +local_domains
 475   start sublist local_domains
 476    d in "@"?
 477    ╎list element: @
 478    d in "@"? no (end of list)
 479   end sublist local_domains
 480 d in "+local_domains"? no (end of list)
 481 accept: condition test failed in ACL "check_recipient"
 482 processing "accept" (TESTSUITE/test-config 33)
 483 check hosts = +relay_hosts
 484 host in "+relay_hosts"?
 485  list element: +relay_hosts
 486   start sublist relay_hosts
 487   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"?
 488    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'
 489    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 490    ╎  cached open
 491    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 492    ╎  key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
 493    ╎LRU list:
 494    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 495    ╎  type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
 496    ╎cached data used for lookup of select * from them where id='10.10.10.10'
 497    ╎  in TESTSUITE/aux-fixed/sqlitedb
 498    ╎lookup yielded: name=ok id=10.10.10.10
 499    ╎host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"? yes (matched "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'")
 500   end sublist relay_hosts
 501   host in "+relay_hosts"? yes (matched "+relay_hosts")
 502 accept: condition test succeeded in ACL "check_recipient"
 503 end of ACL "check_recipient": ACCEPT
 504 SMTP>> 250 Accepted
 505 SMTP<< quit
 506 SMTP>> 221 myhost.test.ex closing connection
 507 LOG: smtp_connection MAIN
 508   SMTP connection from (test) [10.10.10.10] D=qqs closed by QUIT
 509 search_tidyup called
 510 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 511 Exim version x.yz ....
 512 changed uid/gid: forcing real = effective
 513   uid=uuuu gid=CALLER_GID pid=p1238
 514 configuration file is TESTSUITE/test-config
 515 admin user
 516 changed uid/gid: privilege not needed
 517   uid=EXIM_UID gid=EXIM_GID pid=p1238
 518 seeking password data for user "CALLER": cache not available
 519 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 520 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 521 sender address = CALLER@myhost.test.ex
 522 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 523 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 524 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 525 Sender: CALLER@myhost.test.ex
 526 Recipients:
 527   userx
 528 search_tidyup called
 529 >>Headers received:
 530
 531 qualify & rewrite recipients list
 532 rewrite rules on sender address
 533 qualify and rewrite headers
 534  rewrite_one_header: type=F:
 535    From: CALLER_NAME <CALLER@myhost.test.ex>
 536 search_tidyup called
 537 >>Headers after rewriting and local additions:
 538  I Message-Id: <E10HmaX-000000005vi-0000@myhost.test.ex>
 539  F From: CALLER_NAME <CALLER@myhost.test.ex>
 540    Date: Tue, 2 Mar 1999 09:44:33 +0000
 541
 542 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 543 Data file written for message 10HmaX-000000005vi-0000
 544 >>Generated Received: header line
 545 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 546         (envelope-from <CALLER@myhost.test.ex>)
 547         id 10HmaX-000000005vi-0000
 548         for userx@myhost.test.ex;
 549         Tue, 2 Mar 1999 09:44:33 +0000
 550 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
 551 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 552 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
 553 Size of headers = sss
 554 LOG: MAIN
 555   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 556 created log directory TESTSUITE/spool/log
 557 search_tidyup called
 558 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -DOPT=y -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
 559 Exim version x.yz ....
 560 changed uid/gid: forcing real = effective
 561   uid=uuuu gid=EXIM_GID pid=p1239
 562 configuration file is TESTSUITE/test-config
 563 trusted user
 564 admin user
 565 dropping to exim gid; retaining priv uid
 566 seeking password data for user "CALLER": cache not available
 567 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 568 set_process_info: pppp delivering specified messages
 569 set_process_info: pppp delivering 10HmaX-000000005vi-0000
 570 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 571 reading spool file 10HmaX-000000005vi-0000-H
 572 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 573 sender_local=1 ident=CALLER
 574 Non-recipients:
 575  Empty Tree
 576 ---- End of tree ----
 577 recipients_count=1
 578 **** SPOOL_IN - No additional fields
 579 body_linecount=1 message_linecount=8
 580 DSN: set orcpt:   flags: 0x0
 581 Delivery address list:
 582   userx@myhost.test.ex
 583  locking TESTSUITE/spool/db/retry.lockfile
 584  locked  TESTSUITE/spool/db/retry.lockfile
 585  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 586  returned from EXIM_DBOPEN: (nil)
 587  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 588 no retry data available
 589 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 590 Considering: userx@myhost.test.ex
 591 unique = userx@myhost.test.ex
 592 no   domain  retry record
 593 no   address retry record
 594 userx@myhost.test.ex: queued for routing
 595 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 596 routing userx@myhost.test.ex
 597 --------> r1 router <--------
 598 local_part=userx domain=myhost.test.ex
 599 processing address_data
 600  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 601  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 602    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 603  LRU list:
 604  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 605    type=sqlite key="select name from them where id='userx'" opts=NULL
 606  file lookup required for select name from them where id='userx'
 607    in TESTSUITE/aux-fixed/sqlitedb
 608  creating new cache entry
 609  lookup yielded: Ayen Other
 610 calling r1 router
 611 r1 router called for userx@myhost.test.ex
 612   domain = myhost.test.ex
 613 set transport t1
 614 queued for t1 transport: local_part = userx
 615 domain = myhost.test.ex
 616   errors_to=NULL
 617   domain_data=NULL local_part_data=NULL
 618 routed by r1 router
 619   envelope to: userx@myhost.test.ex
 620   transport: t1
 621 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 622 After routing:
 623   Local deliveries:
 624     userx@myhost.test.ex
 625   Remote deliveries:
 626   Failed addresses:
 627   Deferred addresses:
 628 search_tidyup called
 629 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 630 --------> userx@myhost.test.ex <--------
 631  locking TESTSUITE/spool/db/retry.lockfile
 632  locked  TESTSUITE/spool/db/retry.lockfile
 633  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 634  returned from EXIM_DBOPEN: (nil)
 635  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 636 no retry data available
 637 search_tidyup called
 638 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
 639   uid=CALLER_UID gid=CALLER_GID pid=p1240
 640   home=NULL current=/
 641 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to userx using t1
 642 appendfile transport entered
 643  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 644  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 645    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 646  LRU list:
 647  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 648    type=sqlite key="select id from them where id='userx'" opts=NULL
 649  file lookup required for select id from them where id='userx'
 650    in TESTSUITE/aux-fixed/sqlitedb
 651  creating new cache entry
 652  lookup yielded: userx
 653 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 654   file=TESTSUITE/test-mail/userx format=unix
 655   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 656   message_suffix=\n
 657   maildir_use_size_file=no
 658   locking by lockfile fcntl
 659 lock name: TESTSUITE/test-mail/userx.lock
 660 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
 661 lock file created
 662 mailbox TESTSUITE/test-mail/userx is locked
 663 writing to file TESTSUITE/test-mail/userx
 664 writing data block fd=dddd size=sss timeout=0
 665 cannot use sendfile for body: spoolfile not wireformat
 666 writing data block fd=dddd size=sss timeout=0
 667 writing data block fd=dddd size=sss timeout=0
 668 appendfile yields 0 with errno=dd more_errno=dd
 669 search_tidyup called
 670 journalling userx@myhost.test.ex
 671 t1 transport returned OK for userx@myhost.test.ex
 672 post-process userx@myhost.test.ex (0)
 673 userx@myhost.test.ex delivered
 674 LOG: MAIN
 675   => userx <userx@myhost.test.ex> R=r1 T=t1
 676 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 677 changed uid/gid: post-delivery tidying
 678   uid=EXIM_UID gid=EXIM_GID pid=p1239
 679 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
 680 Processing retry items
 681 Succeeded addresses:
 682  userx@myhost.test.ex: no retry items
 683 Failed addresses:
 684 Deferred addresses:
 685 end of retry processing
 686 DSN: processing router : r1
 687 DSN: processing successful delivery address: userx@myhost.test.ex
 688 DSN: Sender_address: CALLER@myhost.test.ex
 689 DSN: orcpt: NULL  flags: 0x0
 690 DSN: envid: NULL  ret: 0
 691 DSN: Final recipient: userx@myhost.test.ex
 692 DSN: Remote SMTP server supports DSN: 0
 693 DSN: not sending DSN success message
 694 LOG: MAIN
 695   Completed
 696 end delivery of 10HmaX-000000005vi-0000
 697 search_tidyup called
 698 search_tidyup called
 699 >>>>>>>>>>>>>>>> Exim pid=p1239 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 700 search_tidyup called
 701 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 702 Exim version x.yz ....
 703 configuration file is TESTSUITE/test-config
 704 admin user
 705 dropping to exim gid; retaining priv uid
 706  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 707  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 708    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 709  LRU list:
 710  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 711    type=sqlite key="select name from them where id='userx';" opts=NULL
 712  file lookup required for select name from them where id='userx';
 713    in TESTSUITE/aux-fixed/sqlitedb
 714  creating new cache entry
 715  lookup yielded: Ayen Other
 716 search_tidyup called
 717 >>>>>>>>>>>>>>>> Exim pid=p1241 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 718 Exim version x.yz ....
 719 configuration file is TESTSUITE/test-config
 720 admin user
 721 dropping to exim gid; retaining priv uid
 722  search_open: sqlite "NULL"
 723  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
 724  search_find: file="NULL"
 725    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 726  LRU list:
 727  internal_search_find: file="NULL"
 728    type=sqlite key="select name from them where id='userx';" opts=NULL
 729  database lookup required for select name from them where id='userx';
 730  creating new cache entry
 731  lookup yielded: Ayen Other
 732 search_tidyup called
 733 >>>>>>>>>>>>>>>> Exim pid=p1242 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 734 Exim version x.yz ....
 735 configuration file is TESTSUITE/test-config
 736 admin user
 737 dropping to exim gid; retaining priv uid
 738  search_open: sqlite "NULL"
 739  Using sqlite_dbfile: nonabsolute_filename
 740 search_tidyup called
 741 >>>>>>>>>>>>>>>> Exim pid=p1243 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 742 Exim version x.yz ....
 743 configuration file is TESTSUITE/test-config
 744 admin user
 745 dropping to exim gid; retaining priv uid
 746  search_open: sqlite "NULL"
 747  Using sqlite_dbfile: TESTSUITE/missingfile
 748  search_find: file="NULL"
 749    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 750  LRU list:
 751  internal_search_find: file="NULL"
 752    type=sqlite key="select name from them where id='userx';" opts=NULL
 753  database lookup required for select name from them where id='userx';
 754  sqlite3_exec failed: no such table: them
 755  creating new cache entry
 756  lookup failed
 757 search_tidyup called
 758 >>>>>>>>>>>>>>>> Exim pid=p1244 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>