test/stderr/2610

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