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