test/stderr/2600

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