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=pppp (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=pppp
 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=pppp
 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<< mail from:<a@b>
 165 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 166 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 167 SMTP>> 250 OK
 168 SMTP<< rcpt to:<c@d>
 169 using ACL "check_recipient"
 170 processing "accept" (TESTSUITE/test-config 32)
 171 check domains = +local_domains
 172 d in "@"? no (end of list)
 173 d in "+local_domains"? no (end of list)
 174 accept: condition test failed in ACL "check_recipient"
 175 processing "accept" (TESTSUITE/test-config 33)
 176 check hosts = +relay_hosts
 177 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 178 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 179   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 180 LRU list:
 181 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 182   type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 183 file lookup required for select * from them where id='10.0.0.0'
 184   in TESTSUITE/aux-fixed/sqlitedb
 185 lookup forced cache cleanup
 186 lookup failed
 187 host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
 188 host in "+relay_hosts"? no (end of list)
 189 accept: condition test failed in ACL "check_recipient"
 190 processing "deny" (TESTSUITE/test-config 34)
 191   message: relay not permitted
 192 deny: condition test succeeded in ACL "check_recipient"
 193 end of ACL "check_recipient": DENY
 194 SMTP>> 550 relay not permitted
 195 LOG: MAIN REJECT
 196   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 197 SMTP<< rcpt to:<c@d>
 198 using ACL "check_recipient"
 199 processing "accept" (TESTSUITE/test-config 32)
 200 check domains = +local_domains
 201 d in "@"? no (end of list)
 202 d in "+local_domains"? no (end of list)
 203 accept: condition test failed in ACL "check_recipient"
 204 processing "accept" (TESTSUITE/test-config 33)
 205 check hosts = +relay_hosts
 206 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 207   cached open
 208 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 209   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
 210 LRU list:
 211 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 212   type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
 213 file lookup required for select * from them where id='10.0.0.0'
 214   in TESTSUITE/aux-fixed/sqlitedb
 215 lookup forced cache cleanup
 216 lookup failed
 217 host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
 218 host in "+relay_hosts"? no (end of list)
 219 accept: condition test failed in ACL "check_recipient"
 220 processing "deny" (TESTSUITE/test-config 34)
 221   message: relay not permitted
 222 deny: condition test succeeded in ACL "check_recipient"
 223 end of ACL "check_recipient": DENY
 224 SMTP>> 550 relay not permitted
 225 LOG: MAIN REJECT
 226   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 227 SMTP<< quit
 228 SMTP>> 221 myhost.test.ex closing connection
 229 LOG: smtp_connection MAIN
 230   SMTP connection from [10.0.0.0] closed by QUIT
 231 search_tidyup called
 232 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 233 Exim version x.yz ....
 234 changed uid/gid: forcing real = effective
 235   uid=uuuu gid=CALLER_GID pid=pppp
 236 configuration file is TESTSUITE/test-config
 237 admin user
 238 changed uid/gid: privilege not needed
 239   uid=EXIM_UID gid=EXIM_GID pid=pppp
 240 seeking password data for user "CALLER": cache not available
 241 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 242 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 243 sender address = CALLER@myhost.test.ex
 244 sender_fullhost = [10.0.0.0]
 245 sender_rcvhost = [10.0.0.0]
 246 host in hosts_connection_nolog? no (option unset)
 247 LOG: smtp_connection MAIN
 248   SMTP connection from [10.0.0.0]
 249 host in host_lookup? no (option unset)
 250 set_process_info: pppp handling incoming connection from [10.0.0.0]
 251 host in host_reject_connection? no (option unset)
 252 host in sender_unqualified_hosts? no (option unset)
 253 host in recipient_unqualified_hosts? no (option unset)
 254 host in helo_verify_hosts? no (option unset)
 255 host in helo_try_verify_hosts? no (option unset)
 256 host in helo_accept_junk_hosts? no (option unset)
 257 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 258 smtp_setup_msg entered
 259 SMTP<< mail from:<a@b>
 260 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 261 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 262 SMTP>> 250 OK
 263 SMTP<< rcpt to:<c@d>
 264 using ACL "check_recipient"
 265 processing "accept" (TESTSUITE/test-config 32)
 266 check domains = +local_domains
 267 d in "@"? no (end of list)
 268 d in "+local_domains"? no (end of list)
 269 accept: condition test failed in ACL "check_recipient"
 270 processing "accept" (TESTSUITE/test-config 33)
 271 check hosts = +relay_hosts
 272 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 273 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 274   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 275 LRU list:
 276 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 277   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
 278 file lookup required for select * from them where id='10.0.0.0'
 279   in TESTSUITE/aux-fixed/sqlitedb
 280 lookup forced cache cleanup
 281 lookup failed
 282 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
 283 host in "+relay_hosts"? no (end of list)
 284 accept: condition test failed in ACL "check_recipient"
 285 processing "deny" (TESTSUITE/test-config 34)
 286   message: relay not permitted
 287 deny: condition test succeeded in ACL "check_recipient"
 288 end of ACL "check_recipient": DENY
 289 SMTP>> 550 relay not permitted
 290 LOG: MAIN REJECT
 291   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 292 SMTP<< rcpt to:<c@d>
 293 using ACL "check_recipient"
 294 processing "accept" (TESTSUITE/test-config 32)
 295 check domains = +local_domains
 296 d in "@"? no (end of list)
 297 d in "+local_domains"? no (end of list)
 298 accept: condition test failed in ACL "check_recipient"
 299 processing "accept" (TESTSUITE/test-config 33)
 300 check hosts = +relay_hosts
 301 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 302   cached open
 303 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 304   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 305 LRU list:
 306 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 307   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
 308 file lookup required for select * from them where id='10.0.0.0'
 309   in TESTSUITE/aux-fixed/sqlitedb
 310 lookup forced cache cleanup
 311 lookup failed
 312 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
 313 host in "+relay_hosts"? no (end of list)
 314 accept: condition test failed in ACL "check_recipient"
 315 processing "deny" (TESTSUITE/test-config 34)
 316   message: relay not permitted
 317 deny: condition test succeeded in ACL "check_recipient"
 318 end of ACL "check_recipient": DENY
 319 SMTP>> 550 relay not permitted
 320 LOG: MAIN REJECT
 321   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 322 SMTP<< quit
 323 SMTP>> 221 myhost.test.ex closing connection
 324 LOG: smtp_connection MAIN
 325   SMTP connection from [10.0.0.0] closed by QUIT
 326 search_tidyup called
 327 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 328 Exim version x.yz ....
 329 changed uid/gid: forcing real = effective
 330   uid=uuuu gid=CALLER_GID pid=pppp
 331 configuration file is TESTSUITE/test-config
 332 admin user
 333 changed uid/gid: privilege not needed
 334   uid=EXIM_UID gid=EXIM_GID pid=pppp
 335 seeking password data for user "CALLER": cache not available
 336 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 337 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 338 sender address = CALLER@myhost.test.ex
 339 sender_fullhost = [10.10.10.10]
 340 sender_rcvhost = [10.10.10.10]
 341 host in hosts_connection_nolog? no (option unset)
 342 LOG: smtp_connection MAIN
 343   SMTP connection from [10.10.10.10]
 344 host in host_lookup? no (option unset)
 345 set_process_info: pppp handling incoming connection from [10.10.10.10]
 346 host in host_reject_connection? no (option unset)
 347 host in sender_unqualified_hosts? no (option unset)
 348 host in recipient_unqualified_hosts? no (option unset)
 349 host in helo_verify_hosts? no (option unset)
 350 host in helo_try_verify_hosts? no (option unset)
 351 host in helo_accept_junk_hosts? no (option unset)
 352 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 353 smtp_setup_msg entered
 354 SMTP<< mail from:<a@b>
 355 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 356 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 357 SMTP>> 250 OK
 358 SMTP<< rcpt to:<c@d>
 359 using ACL "check_recipient"
 360 processing "accept" (TESTSUITE/test-config 32)
 361 check domains = +local_domains
 362 d in "@"? no (end of list)
 363 d in "+local_domains"? no (end of list)
 364 accept: condition test failed in ACL "check_recipient"
 365 processing "accept" (TESTSUITE/test-config 33)
 366 check hosts = +relay_hosts
 367 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 368 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 369   key="select * from them where id='10.10.10.10'" 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.10.10.10'" opts=NULL
 373 file lookup required for select * from them where id='10.10.10.10'
 374   in TESTSUITE/aux-fixed/sqlitedb
 375 creating new cache entry
 376 lookup yielded: name=ok id=10.10.10.10
 377 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'")
 378 host in "+relay_hosts"? yes (matched "+relay_hosts")
 379 accept: condition test succeeded in ACL "check_recipient"
 380 end of ACL "check_recipient": ACCEPT
 381 SMTP>> 250 Accepted
 382 SMTP<< rcpt to:<c@d>
 383 using ACL "check_recipient"
 384 processing "accept" (TESTSUITE/test-config 32)
 385 check domains = +local_domains
 386 d in "@"? no (end of list)
 387 d in "+local_domains"? no (end of list)
 388 accept: condition test failed in ACL "check_recipient"
 389 processing "accept" (TESTSUITE/test-config 33)
 390 check hosts = +relay_hosts
 391 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 392   cached open
 393 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 394   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
 395 LRU list:
 396 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 397   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
 398 cached data used for lookup of select * from them where id='10.10.10.10'
 399   in TESTSUITE/aux-fixed/sqlitedb
 400 lookup yielded: name=ok id=10.10.10.10
 401 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'")
 402 host in "+relay_hosts"? yes (matched "+relay_hosts")
 403 accept: condition test succeeded in ACL "check_recipient"
 404 end of ACL "check_recipient": ACCEPT
 405 SMTP>> 250 Accepted
 406 SMTP<< quit
 407 SMTP>> 221 myhost.test.ex closing connection
 408 LOG: smtp_connection MAIN
 409   SMTP connection from [10.10.10.10] closed by QUIT
 410 search_tidyup called
 411 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 412 Exim version x.yz ....
 413 changed uid/gid: forcing real = effective
 414   uid=uuuu gid=CALLER_GID pid=pppp
 415 configuration file is TESTSUITE/test-config
 416 admin user
 417 changed uid/gid: privilege not needed
 418   uid=EXIM_UID gid=EXIM_GID pid=pppp
 419 seeking password data for user "CALLER": cache not available
 420 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 421 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 422 sender address = CALLER@myhost.test.ex
 423 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 424 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 425 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 426 Sender: CALLER@myhost.test.ex
 427 Recipients:
 428   userx
 429 search_tidyup called
 430 >>Headers received:
 431
 432 rewrite_one_header: type=F:
 433   From: CALLER_NAME <CALLER@myhost.test.ex>
 434 search_tidyup called
 435 >>Headers after rewriting and local additions:
 436 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 437 F From: CALLER_NAME <CALLER@myhost.test.ex>
 438   Date: Tue, 2 Mar 1999 09:44:33 +0000
 439
 440 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 441 Data file written for message 10HmaX-0005vi-00
 442 >>Generated Received: header line
 443 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 444         (envelope-from <CALLER@myhost.test.ex>)
 445         id 10HmaX-0005vi-00
 446         for userx@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 447 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 448 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 449 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 450 Size of headers = sss
 451 LOG: MAIN
 452   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 453 created log directory TESTSUITE/spool/log
 454 search_tidyup called
 455 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -DOPT=y -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 456 Exim version x.yz ....
 457 changed uid/gid: forcing real = effective
 458   uid=uuuu gid=EXIM_GID pid=pppp
 459 configuration file is TESTSUITE/test-config
 460 trusted user
 461 admin user
 462 dropping to exim gid; retaining priv uid
 463 seeking password data for user "CALLER": cache not available
 464 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 465 set_process_info: pppp delivering specified messages
 466 set_process_info: pppp delivering 10HmaX-0005vi-00
 467 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 468 reading spool file 10HmaX-0005vi-00-H
 469 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 470 sender_local=1 ident=CALLER
 471 Non-recipients:
 472 Empty Tree
 473 ---- End of tree ----
 474 recipients_count=1
 475 **** SPOOL_IN - No additional fields
 476 body_linecount=1 message_linecount=7
 477 DSN: set orcpt:   flags: 0x0
 478 Delivery address list:
 479   userx@myhost.test.ex
 480  locking TESTSUITE/spool/db/retry.lockfile
 481  locked  TESTSUITE/spool/db/retry.lockfile
 482  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 483  returned from EXIM_DBOPEN: (nil)
 484  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 485  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
 486 no retry data available
 487 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 488 Considering: userx@myhost.test.ex
 489 unique = userx@myhost.test.ex
 490 no   domain  retry record
 491 no   address retry record
 492 userx@myhost.test.ex: queued for routing
 493 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 494 routing userx@myhost.test.ex
 495 --------> r1 router <--------
 496 local_part=userx domain=myhost.test.ex
 497 processing address_data
 498  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 499  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 500    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 501  LRU list:
 502  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 503    type=sqlite key="select name from them where id='userx'" opts=NULL
 504  file lookup required for select name from them where id='userx'
 505    in TESTSUITE/aux-fixed/sqlitedb
 506  creating new cache entry
 507  lookup yielded: Ayen Other
 508 calling r1 router
 509 r1 router called for userx@myhost.test.ex
 510   domain = myhost.test.ex
 511 set transport t1
 512 queued for t1 transport: local_part = userx
 513 domain = myhost.test.ex
 514   errors_to=NULL
 515   domain_data=NULL localpart_data=NULL
 516 routed by r1 router
 517   envelope to: userx@myhost.test.ex
 518   transport: t1
 519 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 520 After routing:
 521   Local deliveries:
 522     userx@myhost.test.ex
 523   Remote deliveries:
 524   Failed addresses:
 525   Deferred addresses:
 526 search_tidyup called
 527 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 528 --------> userx@myhost.test.ex <--------
 529  locking TESTSUITE/spool/db/retry.lockfile
 530  locked  TESTSUITE/spool/db/retry.lockfile
 531  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 532  returned from EXIM_DBOPEN: (nil)
 533  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 534 no retry data available
 535 search_tidyup called
 536 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
 537   uid=CALLER_UID gid=CALLER_GID pid=pppp
 538   home=NULL current=/
 539 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
 540 appendfile transport entered
 541  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 542  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 543    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 544  LRU list:
 545  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 546    type=sqlite key="select id from them where id='userx'" opts=NULL
 547  file lookup required for select id from them where id='userx'
 548    in TESTSUITE/aux-fixed/sqlitedb
 549  creating new cache entry
 550  lookup yielded: userx
 551 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 552   file=TESTSUITE/test-mail/userx format=unix
 553   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 554   message_suffix=\n
 555   maildir_use_size_file=no
 556   locking by lockfile fcntl
 557 lock name: TESTSUITE/test-mail/userx.lock
 558 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
 559 lock file created
 560 mailbox TESTSUITE/test-mail/userx is locked
 561 writing to file TESTSUITE/test-mail/userx
 562 writing data block fd=dddd size=sss timeout=0
 563 cannot use sendfile for body: spoolfile not wireformat
 564 writing data block fd=dddd size=sss timeout=0
 565 writing data block fd=dddd size=sss timeout=0
 566 appendfile yields 0 with errno=dd more_errno=dd
 567 search_tidyup called
 568 journalling userx@myhost.test.ex
 569 t1 transport returned OK for userx@myhost.test.ex
 570 post-process userx@myhost.test.ex (0)
 571 userx@myhost.test.ex delivered
 572 LOG: MAIN
 573   => userx <userx@myhost.test.ex> R=r1 T=t1
 574 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 575 changed uid/gid: post-delivery tidying
 576   uid=EXIM_UID gid=EXIM_GID pid=pppp
 577 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 578 Processing retry items
 579 Succeeded addresses:
 580  userx@myhost.test.ex: no retry items
 581 Failed addresses:
 582 Deferred addresses:
 583 end of retry processing
 584 DSN: processing router : r1
 585 DSN: processing successful delivery address: userx@myhost.test.ex
 586 DSN: Sender_address: CALLER@myhost.test.ex
 587 DSN: orcpt: NULL  flags: 0x0
 588 DSN: envid: NULL  ret: 0
 589 DSN: Final recipient: userx@myhost.test.ex
 590 DSN: Remote SMTP server supports DSN: 0
 591 DSN: not sending DSN success message
 592 LOG: MAIN
 593   Completed
 594 end delivery of 10HmaX-0005vi-00
 595 search_tidyup called
 596 search_tidyup called
 597 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 598 search_tidyup called
 599 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 600 Exim version x.yz ....
 601 configuration file is TESTSUITE/test-config
 602 admin user
 603 dropping to exim gid; retaining priv uid
 604  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 605  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 606    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 607  LRU list:
 608  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 609    type=sqlite key="select name from them where id='userx';" opts=NULL
 610  file lookup required for select name from them where id='userx';
 611    in TESTSUITE/aux-fixed/sqlitedb
 612  creating new cache entry
 613  lookup yielded: Ayen Other
 614 search_tidyup called
 615 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 616 Exim version x.yz ....
 617 configuration file is TESTSUITE/test-config
 618 admin user
 619 dropping to exim gid; retaining priv uid
 620  search_open: sqlite "NULL"
 621  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
 622  search_find: file="NULL"
 623    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 624  LRU list:
 625  internal_search_find: file="NULL"
 626    type=sqlite key="select name from them where id='userx';" opts=NULL
 627  database lookup required for select name from them where id='userx';
 628  creating new cache entry
 629  lookup yielded: Ayen Other
 630 search_tidyup called
 631 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 632 Exim version x.yz ....
 633 configuration file is TESTSUITE/test-config
 634 admin user
 635 dropping to exim gid; retaining priv uid
 636  search_open: sqlite "NULL"
 637  Using sqlite_dbfile: nonabsolute_filename
 638 search_tidyup called
 639 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 640 Exim version x.yz ....
 641 configuration file is TESTSUITE/test-config
 642 admin user
 643 dropping to exim gid; retaining priv uid
 644  search_open: sqlite "NULL"
 645  Using sqlite_dbfile: TESTSUITE/missingfile
 646  search_find: file="NULL"
 647    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 648  LRU list:
 649  internal_search_find: file="NULL"
 650    type=sqlite key="select name from them where id='userx';" opts=NULL
 651  database lookup required for select name from them where id='userx';
 652  sqlite3_exec failed: no such table: them
 653  creating new cache entry
 654  lookup failed
 655 search_tidyup called
 656 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>