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=NULL
   8  LRU list:
   9  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  10    type=sqlite key="select name from them where id='userx';" opts=NULL
  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 used for lookup of select name from them where id='userx';
  23    in TESTSUITE/aux-fixed/sqlitedb
  24  lookup yielded: Ayen Other
  25  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  26    cached open
  27  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  28    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts=NULL
  29  LRU list:
  30  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  31    type=sqlite key="select name from them where id='xxxx';" opts=NULL
  32  file lookup required for select name from them where id='xxxx';
  33    in TESTSUITE/aux-fixed/sqlitedb
  34  lookup forced cache cleanup
  35  lookup failed
  36  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  37    cached open
  38  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  39    key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts=NULL
  40  LRU list:
  41  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  42    type=sqlite key="select name from them where id='nothing';" opts=NULL
  43  file lookup required for select name from them where id='nothing';
  44    in TESTSUITE/aux-fixed/sqlitedb
  45  creating new cache entry
  46  lookup yielded:
  47  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  48    cached open
  49  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  50    key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts=NULL
  51  LRU list:
  52  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  53    type=sqlite key="select id,name from them where id='nothing';" opts=NULL
  54  file lookup required for select id,name from them where id='nothing';
  55    in TESTSUITE/aux-fixed/sqlitedb
  56  creating new cache entry
  57  lookup yielded: id=nothing name=""
  58  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  59    cached open
  60  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  61    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts=NULL
  62  LRU list:
  63  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  64    type=sqlite key="select * from them where id='quote2';" opts=NULL
  65  file lookup required for select * from them where id='quote2';
  66    in TESTSUITE/aux-fixed/sqlitedb
  67  creating new cache entry
  68  lookup yielded: name="\"stquot" id=quote2
  69  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
  70    cached open
  71  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  72    key="select * from them where id='newline';" partial=-1 affix=NULL starflags=0 opts=NULL
  73  LRU list:
  74  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  75    type=sqlite key="select * from them where id='newline';" opts=NULL
  76  file lookup required for select * from them where id='newline';
  77    in TESTSUITE/aux-fixed/sqlitedb
  78  creating new cache entry
  79  lookup yielded: name="before
  80  after" id=newline
  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='tab';" partial=-1 affix=NULL starflags=0 opts=NULL
  85  LRU list:
  86  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  87    type=sqlite key="select * from them where id='tab';" opts=NULL
  88  file lookup required for select * from them where id='tab';
  89    in TESTSUITE/aux-fixed/sqlitedb
  90  creating new cache entry
  91  lookup yielded: name="x        x" id=tab
  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='its';" partial=-1 affix=NULL starflags=0 opts=NULL
  96  LRU list:
  97  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
  98    type=sqlite key="select * from them where id='its';" opts=NULL
  99  file lookup required for select * from them where id='its';
 100    in TESTSUITE/aux-fixed/sqlitedb
 101  creating new cache entry
 102  lookup yielded: name=it's id=its
 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 name='it''s';" partial=-1 affix=NULL starflags=0 opts=NULL
 107  LRU list:
 108  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 109    type=sqlite key="select * from them where name='it''s';" opts=NULL
 110  file lookup required for select * from them where name='it''s';
 111    in TESTSUITE/aux-fixed/sqlitedb
 112  creating new cache entry
 113  lookup yielded: name=it's id=its
 114 search_tidyup called
 115 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 116 Exim version x.yz ....
 117 changed uid/gid: forcing real = effective
 118   uid=uuuu gid=CALLER_GID pid=pppp
 119 configuration file is TESTSUITE/test-config
 120 admin user
 121 changed uid/gid: privilege not needed
 122   uid=EXIM_UID gid=EXIM_GID pid=pppp
 123 seeking password data for user "CALLER": cache not available
 124 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 125 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 126 sender address = CALLER@myhost.test.ex
 127 sender_fullhost = [10.0.0.0]
 128 sender_rcvhost = [10.0.0.0]
 129 host in hosts_connection_nolog? no (option unset)
 130 LOG: smtp_connection MAIN
 131   SMTP connection from [10.0.0.0]
 132 host in host_lookup? no (option unset)
 133 set_process_info: pppp handling incoming connection from [10.0.0.0]
 134 host in host_reject_connection? no (option unset)
 135 host in sender_unqualified_hosts? no (option unset)
 136 host in recipient_unqualified_hosts? no (option unset)
 137 host in helo_verify_hosts? no (option unset)
 138 host in helo_try_verify_hosts? no (option unset)
 139 host in helo_accept_junk_hosts? no (option unset)
 140 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 141 smtp_setup_msg entered
 142 SMTP<< mail from:<a@b>
 143 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 144 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 145 SMTP>> 250 OK
 146 SMTP<< rcpt to:<c@d>
 147 using ACL "check_recipient"
 148 processing "accept" (TESTSUITE/test-config 26)
 149 check domains = +local_domains
 150 d in "@"? no (end of list)
 151 d in "+local_domains"? no (end of list)
 152 accept: condition test failed in ACL "check_recipient"
 153 processing "accept" (TESTSUITE/test-config 27)
 154 check hosts = +relay_hosts
 155 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 156 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 157   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 158 LRU list:
 159 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 160   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
 161 file lookup required for select * from them where id='10.0.0.0'
 162   in TESTSUITE/aux-fixed/sqlitedb
 163 lookup forced cache cleanup
 164 lookup failed
 165 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
 166 host in "+relay_hosts"? no (end of list)
 167 accept: condition test failed in ACL "check_recipient"
 168 processing "deny" (TESTSUITE/test-config 28)
 169   message: relay not permitted
 170 deny: condition test succeeded in ACL "check_recipient"
 171 end of ACL "check_recipient": DENY
 172 SMTP>> 550 relay not permitted
 173 LOG: MAIN REJECT
 174   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 175 SMTP<< rcpt to:<c@d>
 176 using ACL "check_recipient"
 177 processing "accept" (TESTSUITE/test-config 26)
 178 check domains = +local_domains
 179 d in "@"? no (end of list)
 180 d in "+local_domains"? no (end of list)
 181 accept: condition test failed in ACL "check_recipient"
 182 processing "accept" (TESTSUITE/test-config 27)
 183 check hosts = +relay_hosts
 184 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 185   cached open
 186 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 187   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 188 LRU list:
 189 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 190   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
 191 file lookup required for select * from them where id='10.0.0.0'
 192   in TESTSUITE/aux-fixed/sqlitedb
 193 lookup forced cache cleanup
 194 lookup failed
 195 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
 196 host in "+relay_hosts"? no (end of list)
 197 accept: condition test failed in ACL "check_recipient"
 198 processing "deny" (TESTSUITE/test-config 28)
 199   message: relay not permitted
 200 deny: condition test succeeded in ACL "check_recipient"
 201 end of ACL "check_recipient": DENY
 202 SMTP>> 550 relay not permitted
 203 LOG: MAIN REJECT
 204   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 205 SMTP<< quit
 206 SMTP>> 221 myhost.test.ex closing connection
 207 LOG: smtp_connection MAIN
 208   SMTP connection from [10.0.0.0] closed by QUIT
 209 search_tidyup called
 210 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 211 Exim version x.yz ....
 212 changed uid/gid: forcing real = effective
 213   uid=uuuu gid=CALLER_GID pid=pppp
 214 configuration file is TESTSUITE/test-config
 215 admin user
 216 changed uid/gid: privilege not needed
 217   uid=EXIM_UID gid=EXIM_GID pid=pppp
 218 seeking password data for user "CALLER": cache not available
 219 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 220 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 221 sender address = CALLER@myhost.test.ex
 222 sender_fullhost = [10.10.10.10]
 223 sender_rcvhost = [10.10.10.10]
 224 host in hosts_connection_nolog? no (option unset)
 225 LOG: smtp_connection MAIN
 226   SMTP connection from [10.10.10.10]
 227 host in host_lookup? no (option unset)
 228 set_process_info: pppp handling incoming connection from [10.10.10.10]
 229 host in host_reject_connection? no (option unset)
 230 host in sender_unqualified_hosts? no (option unset)
 231 host in recipient_unqualified_hosts? no (option unset)
 232 host in helo_verify_hosts? no (option unset)
 233 host in helo_try_verify_hosts? no (option unset)
 234 host in helo_accept_junk_hosts? no (option unset)
 235 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 236 smtp_setup_msg entered
 237 SMTP<< mail from:<a@b>
 238 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 239 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 240 SMTP>> 250 OK
 241 SMTP<< rcpt to:<c@d>
 242 using ACL "check_recipient"
 243 processing "accept" (TESTSUITE/test-config 26)
 244 check domains = +local_domains
 245 d in "@"? no (end of list)
 246 d in "+local_domains"? no (end of list)
 247 accept: condition test failed in ACL "check_recipient"
 248 processing "accept" (TESTSUITE/test-config 27)
 249 check hosts = +relay_hosts
 250 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 251 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 252   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
 253 LRU list:
 254 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 255   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
 256 file lookup required for select * from them where id='10.10.10.10'
 257   in TESTSUITE/aux-fixed/sqlitedb
 258 creating new cache entry
 259 lookup yielded: name=ok id=10.10.10.10
 260 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'")
 261 host in "+relay_hosts"? yes (matched "+relay_hosts")
 262 accept: condition test succeeded in ACL "check_recipient"
 263 end of ACL "check_recipient": ACCEPT
 264 SMTP>> 250 Accepted
 265 SMTP<< rcpt to:<c@d>
 266 using ACL "check_recipient"
 267 processing "accept" (TESTSUITE/test-config 26)
 268 check domains = +local_domains
 269 d in "@"? no (end of list)
 270 d in "+local_domains"? no (end of list)
 271 accept: condition test failed in ACL "check_recipient"
 272 processing "accept" (TESTSUITE/test-config 27)
 273 check hosts = +relay_hosts
 274 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 275   cached open
 276 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 277   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
 278 LRU list:
 279 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 280   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
 281 cached data used for lookup of select * from them where id='10.10.10.10'
 282   in TESTSUITE/aux-fixed/sqlitedb
 283 lookup yielded: name=ok id=10.10.10.10
 284 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'")
 285 host in "+relay_hosts"? yes (matched "+relay_hosts")
 286 accept: condition test succeeded in ACL "check_recipient"
 287 end of ACL "check_recipient": ACCEPT
 288 SMTP>> 250 Accepted
 289 SMTP<< quit
 290 SMTP>> 221 myhost.test.ex closing connection
 291 LOG: smtp_connection MAIN
 292   SMTP connection from [10.10.10.10] closed by QUIT
 293 search_tidyup called
 294 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 295 Exim version x.yz ....
 296 changed uid/gid: forcing real = effective
 297   uid=uuuu gid=CALLER_GID pid=pppp
 298 configuration file is TESTSUITE/test-config
 299 admin user
 300 changed uid/gid: privilege not needed
 301   uid=EXIM_UID gid=EXIM_GID pid=pppp
 302 seeking password data for user "CALLER": cache not available
 303 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 304 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 305 sender address = CALLER@myhost.test.ex
 306 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 307 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 308 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 309 Sender: CALLER@myhost.test.ex
 310 Recipients:
 311   userx
 312 search_tidyup called
 313 >>Headers received:
 314
 315 rewrite_one_header: type=F:
 316   From: CALLER_NAME <CALLER@myhost.test.ex>
 317 search_tidyup called
 318 >>Headers after rewriting and local additions:
 319 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 320 F From: CALLER_NAME <CALLER@myhost.test.ex>
 321   Date: Tue, 2 Mar 1999 09:44:33 +0000
 322
 323 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 324 Data file written for message 10HmaX-0005vi-00
 325 >>Generated Received: header line
 326 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 327         (envelope-from <CALLER@myhost.test.ex>)
 328         id 10HmaX-0005vi-00
 329         for userx@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 330 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 331 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 332 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 333 Size of headers = sss
 334 LOG: MAIN
 335   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 336 created log directory TESTSUITE/spool/log
 337 search_tidyup called
 338 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 339 Exim version x.yz ....
 340 changed uid/gid: forcing real = effective
 341   uid=uuuu gid=EXIM_GID pid=pppp
 342 configuration file is TESTSUITE/test-config
 343 trusted user
 344 admin user
 345 dropping to exim gid; retaining priv uid
 346 seeking password data for user "CALLER": cache not available
 347 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 348 set_process_info: pppp delivering specified messages
 349 set_process_info: pppp delivering 10HmaX-0005vi-00
 350 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 351 reading spool file 10HmaX-0005vi-00-H
 352 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 353 sender_local=1 ident=CALLER
 354 Non-recipients:
 355 Empty Tree
 356 ---- End of tree ----
 357 recipients_count=1
 358 **** SPOOL_IN - No additional fields
 359 body_linecount=1 message_linecount=7
 360 DSN: set orcpt:   flags: 0x0
 361 Delivery address list:
 362   userx@myhost.test.ex
 363  locking TESTSUITE/spool/db/retry.lockfile
 364  locked  TESTSUITE/spool/db/retry.lockfile
 365  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 366  returned from EXIM_DBOPEN: (nil)
 367  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 368  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
 369 no retry data available
 370 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 371 Considering: userx@myhost.test.ex
 372 unique = userx@myhost.test.ex
 373 no   domain  retry record
 374 no   address retry record
 375 userx@myhost.test.ex: queued for routing
 376 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 377 routing userx@myhost.test.ex
 378 --------> r1 router <--------
 379 local_part=userx domain=myhost.test.ex
 380 processing address_data
 381  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 382  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 383    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 384  LRU list:
 385  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 386    type=sqlite key="select name from them where id='userx'" opts=NULL
 387  file lookup required for select name from them where id='userx'
 388    in TESTSUITE/aux-fixed/sqlitedb
 389  creating new cache entry
 390  lookup yielded: Ayen Other
 391 calling r1 router
 392 r1 router called for userx@myhost.test.ex
 393   domain = myhost.test.ex
 394 set transport t1
 395 queued for t1 transport: local_part = userx
 396 domain = myhost.test.ex
 397   errors_to=NULL
 398   domain_data=NULL localpart_data=NULL
 399 routed by r1 router
 400   envelope to: userx@myhost.test.ex
 401   transport: t1
 402 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 403 After routing:
 404   Local deliveries:
 405     userx@myhost.test.ex
 406   Remote deliveries:
 407   Failed addresses:
 408   Deferred addresses:
 409 search_tidyup called
 410 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 411 --------> userx@myhost.test.ex <--------
 412  locking TESTSUITE/spool/db/retry.lockfile
 413  locked  TESTSUITE/spool/db/retry.lockfile
 414  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 415  returned from EXIM_DBOPEN: (nil)
 416  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 417 no retry data available
 418 search_tidyup called
 419 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
 420   uid=CALLER_UID gid=CALLER_GID pid=pppp
 421   home=NULL current=/
 422 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
 423 appendfile transport entered
 424  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 425  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 426    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
 427  LRU list:
 428  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 429    type=sqlite key="select id from them where id='userx'" opts=NULL
 430  file lookup required for select id from them where id='userx'
 431    in TESTSUITE/aux-fixed/sqlitedb
 432  creating new cache entry
 433  lookup yielded: userx
 434 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 435   file=TESTSUITE/test-mail/userx format=unix
 436   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 437   message_suffix=\n
 438   maildir_use_size_file=no
 439   locking by lockfile fcntl
 440 lock name: TESTSUITE/test-mail/userx.lock
 441 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
 442 lock file created
 443 mailbox TESTSUITE/test-mail/userx is locked
 444 writing to file TESTSUITE/test-mail/userx
 445 writing data block fd=dddd size=sss timeout=0
 446 cannot use sendfile for body: spoolfile not wireformat
 447 writing data block fd=dddd size=sss timeout=0
 448 writing data block fd=dddd size=sss timeout=0
 449 appendfile yields 0 with errno=dd more_errno=dd
 450 search_tidyup called
 451 journalling userx@myhost.test.ex
 452 t1 transport returned OK for userx@myhost.test.ex
 453 post-process userx@myhost.test.ex (0)
 454 userx@myhost.test.ex delivered
 455 LOG: MAIN
 456   => userx <userx@myhost.test.ex> R=r1 T=t1
 457 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 458 changed uid/gid: post-delivery tidying
 459   uid=EXIM_UID gid=EXIM_GID pid=pppp
 460 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 461 Processing retry items
 462 Succeeded addresses:
 463  userx@myhost.test.ex: no retry items
 464 Failed addresses:
 465 Deferred addresses:
 466 end of retry processing
 467 DSN: processing router : r1
 468 DSN: processing successful delivery address: userx@myhost.test.ex
 469 DSN: Sender_address: CALLER@myhost.test.ex
 470 DSN: orcpt: NULL  flags: 0x0
 471 DSN: envid: NULL  ret: 0
 472 DSN: Final recipient: userx@myhost.test.ex
 473 DSN: Remote SMTP server supports DSN: 0
 474 DSN: not sending DSN success message
 475 LOG: MAIN
 476   Completed
 477 end delivery of 10HmaX-0005vi-00
 478 search_tidyup called
 479 search_tidyup called
 480 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 481 search_tidyup called
 482 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 483 Exim version x.yz ....
 484 configuration file is TESTSUITE/test-config
 485 admin user
 486 dropping to exim gid; retaining priv uid
 487  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
 488  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 489    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 490  LRU list:
 491  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
 492    type=sqlite key="select name from them where id='userx';" opts=NULL
 493  file lookup required for select name from them where id='userx';
 494    in TESTSUITE/aux-fixed/sqlitedb
 495  creating new cache entry
 496  lookup yielded: Ayen Other
 497 search_tidyup called
 498 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 499 Exim version x.yz ....
 500 configuration file is TESTSUITE/test-config
 501 admin user
 502 dropping to exim gid; retaining priv uid
 503  search_open: sqlite "NULL"
 504  search_find: file="NULL"
 505    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
 506  LRU list:
 507  internal_search_find: file="NULL"
 508    type=sqlite key="select name from them where id='userx';" opts=NULL
 509  database lookup required for select name from them where id='userx';
 510  creating new cache entry
 511  lookup yielded: Ayen Other
 512 search_tidyup called
 513 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>