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 qualify & rewrite recipients list
 451 global rewrite rules
 452 rewrite headers
 453  rewrite_one_header: type=F:
 454    From: CALLER_NAME <CALLER@myhost.test.ex>
 455 search_tidyup called
 456 >>Headers after rewriting and local additions:
 457 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 458 F From: CALLER_NAME <CALLER@myhost.test.ex>
 459   Date: Tue, 2 Mar 1999 09:44:33 +0000
 460
 461 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 462 Data file written for message 10HmaX-0005vi-00
 463 >>Generated Received: header line
 464 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 465         (envelope-from <CALLER@myhost.test.ex>)
 466         id 10HmaX-0005vi-00
 467         for userx@myhost.test.ex;
 468         Tue, 2 Mar 1999 09:44:33 +0000
 469 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 470 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 471 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 472 Size of headers = sss
 473 LOG: MAIN
 474   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 475 created log directory TESTSUITE/spool/log
 476 search_tidyup called
 477 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
 478 Exim version x.yz ....
 479 changed uid/gid: forcing real = effective
 480   uid=uuuu gid=EXIM_GID pid=pppp
 481 configuration file is TESTSUITE/test-config
 482 trusted user
 483 admin user
 484 dropping to exim gid; retaining priv uid
 485 seeking password data for user "CALLER": cache not available
 486 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 487 set_process_info: pppp delivering specified messages
 488 set_process_info: pppp delivering 10HmaX-0005vi-00
 489 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 490 reading spool file 10HmaX-0005vi-00-H
 491 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 492 sender_local=1 ident=CALLER
 493 Non-recipients:
 494  Empty Tree
 495 ---- End of tree ----
 496 recipients_count=1
 497 **** SPOOL_IN - No additional fields
 498 body_linecount=1 message_linecount=8
 499 DSN: set orcpt:   flags: 0x0
 500 Delivery address list:
 501   userx@myhost.test.ex
 502  locking TESTSUITE/spool/db/retry.lockfile
 503  locked  TESTSUITE/spool/db/retry.lockfile
 504  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 505  returned from EXIM_DBOPEN: (nil)
 506  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 507 no retry data available
 508 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 509 Considering: userx@myhost.test.ex
 510 unique = userx@myhost.test.ex
 511 no   domain  retry record
 512 no   address retry record
 513 userx@myhost.test.ex: queued for routing
 514 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 515 routing userx@myhost.test.ex
 516 --------> r1 router <--------
 517 local_part=userx domain=myhost.test.ex
 518 processing address_data
 519  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 520  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 521    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 522  LRU list:
 523  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 524    type=sqlite key="select name from them where id='userx'" opts=NULL
 525  file lookup required for select name from them where id='userx'
 526    in TESTSUITE/aux-fixed/sqlitedb
 527  creating new cache entry
 528  lookup yielded: Ayen Other
 529 calling r1 router
 530 r1 router called for userx@myhost.test.ex
 531   domain = myhost.test.ex
 532 set transport t1
 533 queued for t1 transport: local_part = userx
 534 domain = myhost.test.ex
 535   errors_to=NULL
 536   domain_data=NULL local_part_data=NULL
 537 routed by r1 router
 538   envelope to: userx@myhost.test.ex
 539   transport: t1
 540 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 541 After routing:
 542   Local deliveries:
 543     userx@myhost.test.ex
 544   Remote deliveries:
 545   Failed addresses:
 546   Deferred addresses:
 547 search_tidyup called
 548 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 549 --------> userx@myhost.test.ex <--------
 550  locking TESTSUITE/spool/db/retry.lockfile
 551  locked  TESTSUITE/spool/db/retry.lockfile
 552  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 553  returned from EXIM_DBOPEN: (nil)
 554  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 555 no retry data available
 556 search_tidyup called
 557 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
 558   uid=CALLER_UID gid=CALLER_GID pid=pppp
 559   home=NULL current=/
 560 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
 561 appendfile transport entered
 562  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 563  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 564    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 565  LRU list:
 566  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 567    type=sqlite key="select id from them where id='userx'" opts=NULL
 568  file lookup required for select id from them where id='userx'
 569    in TESTSUITE/aux-fixed/sqlitedb
 570  creating new cache entry
 571  lookup yielded: userx
 572 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 573   file=TESTSUITE/test-mail/userx format=unix
 574   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 575   message_suffix=\n
 576   maildir_use_size_file=no
 577   locking by lockfile fcntl
 578 lock name: TESTSUITE/test-mail/userx.lock
 579 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
 580 lock file created
 581 mailbox TESTSUITE/test-mail/userx is locked
 582 writing to file TESTSUITE/test-mail/userx
 583 writing data block fd=dddd size=sss timeout=0
 584 cannot use sendfile for body: spoolfile not wireformat
 585 writing data block fd=dddd size=sss timeout=0
 586 writing data block fd=dddd size=sss timeout=0
 587 appendfile yields 0 with errno=dd more_errno=dd
 588 search_tidyup called
 589 journalling userx@myhost.test.ex
 590 t1 transport returned OK for userx@myhost.test.ex
 591 post-process userx@myhost.test.ex (0)
 592 userx@myhost.test.ex delivered
 593 LOG: MAIN
 594   => userx <userx@myhost.test.ex> R=r1 T=t1
 595 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 596 changed uid/gid: post-delivery tidying
 597   uid=EXIM_UID gid=EXIM_GID pid=pppp
 598 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 599 Processing retry items
 600 Succeeded addresses:
 601  userx@myhost.test.ex: no retry items
 602 Failed addresses:
 603 Deferred addresses:
 604 end of retry processing
 605 DSN: processing router : r1
 606 DSN: processing successful delivery address: userx@myhost.test.ex
 607 DSN: Sender_address: CALLER@myhost.test.ex
 608 DSN: orcpt: NULL  flags: 0x0
 609 DSN: envid: NULL  ret: 0
 610 DSN: Final recipient: userx@myhost.test.ex
 611 DSN: Remote SMTP server supports DSN: 0
 612 DSN: not sending DSN success message
 613 LOG: MAIN
 614   Completed
 615 end delivery of 10HmaX-0005vi-00
 616 search_tidyup called
 617 search_tidyup called
 618 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 619 search_tidyup called
 620 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 621 Exim version x.yz ....
 622 configuration file is TESTSUITE/test-config
 623 admin user
 624 dropping to exim gid; retaining priv uid
 625  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 626  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 627    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 628  LRU list:
 629  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 630    type=sqlite key="select name from them where id='userx';" opts=NULL
 631  file lookup required for select name from them where id='userx';
 632    in TESTSUITE/aux-fixed/sqlitedb
 633  creating new cache entry
 634  lookup yielded: Ayen Other
 635 search_tidyup called
 636 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 637 Exim version x.yz ....
 638 configuration file is TESTSUITE/test-config
 639 admin user
 640 dropping to exim gid; retaining priv uid
 641  search_open: sqlite "NULL"
 642  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
 643  search_find: file="NULL"
 644    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 645  LRU list:
 646  internal_search_find: file="NULL"
 647    type=sqlite key="select name from them where id='userx';" opts=NULL
 648  database lookup required for select name from them where id='userx';
 649  creating new cache entry
 650  lookup yielded: Ayen Other
 651 search_tidyup called
 652 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 653 Exim version x.yz ....
 654 configuration file is TESTSUITE/test-config
 655 admin user
 656 dropping to exim gid; retaining priv uid
 657  search_open: sqlite "NULL"
 658  Using sqlite_dbfile: nonabsolute_filename
 659 search_tidyup called
 660 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 661 Exim version x.yz ....
 662 configuration file is TESTSUITE/test-config
 663 admin user
 664 dropping to exim gid; retaining priv uid
 665  search_open: sqlite "NULL"
 666  Using sqlite_dbfile: TESTSUITE/missingfile
 667  search_find: file="NULL"
 668    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 669  LRU list:
 670  internal_search_find: file="NULL"
 671    type=sqlite key="select name from them where id='userx';" opts=NULL
 672  database lookup required for select name from them where id='userx';
 673  sqlite3_exec failed: no such table: them
 674  creating new cache entry
 675  lookup failed
 676 search_tidyup called
 677 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>