test/stderr/2600

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