test/stderr/2610

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