test/stderr/2600

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