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  lookup yielded: Philip Hazel
  15  search_open: mysql "NULL"
  16    cached open
  17  search_find: file="NULL"
  18    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
  19  LRU list:
  20  internal_search_find: file="NULL"
  21    type=mysql key="select name from them where id='ph10';" opts=NULL
  22  cached data used for lookup of select name from them where id='ph10';
  23  lookup yielded: Philip Hazel
  24  search_open: mysql "NULL"
  25    cached open
  26  search_find: file="NULL"
  27    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts=NULL
  28  LRU list:
  29  internal_search_find: file="NULL"
  30    type=mysql key="select name from them where id='xxxx';" opts=NULL
  31  database lookup required for select name from them where id='xxxx';
  32  MySQL query: "select name from them where id='xxxx';" opts 'NULL'
  33  MYSQL using cached connection for 127.0.0.1:1223/test/root
  34  MYSQL: no data found
  35  lookup failed
  36  search_open: mysql "NULL"
  37    cached open
  38  search_find: file="NULL"
  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="NULL"
  42    type=mysql key="select name from them where id='nothing';" opts=NULL
  43  database lookup required for select name from them where id='nothing';
  44  MySQL query: "select name from them where id='nothing';" opts 'NULL'
  45  MYSQL using cached connection for 127.0.0.1:1223/test/root
  46  lookup yielded:
  47  search_open: mysql "NULL"
  48    cached open
  49  search_find: file="NULL"
  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="NULL"
  53    type=mysql key="select id,name from them where id='nothing';" opts=NULL
  54  database lookup required for select id,name from them where id='nothing';
  55  MySQL query: "select id,name from them where id='nothing';" opts 'NULL'
  56  MYSQL using cached connection for 127.0.0.1:1223/test/root
  57  lookup yielded: id=nothing name=""
  58  search_open: mysql "NULL"
  59    cached open
  60  search_find: file="NULL"
  61    key="delete from them where id='nonexist';" partial=-1 affix=NULL starflags=0 opts=NULL
  62  LRU list:
  63  internal_search_find: file="NULL"
  64    type=mysql key="delete from them where id='nonexist';" opts=NULL
  65  database lookup required for delete from them where id='nonexist';
  66  MySQL query: "delete from them where id='nonexist';" opts 'NULL'
  67  MYSQL using cached connection for 127.0.0.1:1223/test/root
  68  MYSQL: query was not one that returns data
  69  lookup forced cache cleanup
  70  lookup yielded: 0
  71  search_open: mysql "NULL"
  72    cached open
  73  search_find: file="NULL"
  74    key="select * from them where id='quote';" partial=-1 affix=NULL starflags=0 opts=NULL
  75  LRU list:
  76  internal_search_find: file="NULL"
  77    type=mysql key="select * from them where id='quote';" opts=NULL
  78  database lookup required for select * from them where id='quote';
  79  MySQL query: "select * from them where id='quote';" opts 'NULL'
  80  MYSQL using cached connection for 127.0.0.1:1223/test/root
  81  MYSQL: no data found
  82  lookup failed
  83  search_open: mysql "NULL"
  84    cached open
  85  search_find: file="NULL"
  86    key="select * from them where id='filter';" partial=-1 affix=NULL starflags=0 opts=NULL
  87  LRU list:
  88  internal_search_find: file="NULL"
  89    type=mysql key="select * from them where id='filter';" opts=NULL
  90  database lookup required for select * from them where id='filter';
  91  MySQL query: "select * from them where id='filter';" opts 'NULL'
  92  MYSQL using cached connection for 127.0.0.1:1223/test/root
  93  MYSQL: no data found
  94  lookup failed
  95  search_open: mysql "NULL"
  96    cached open
  97  search_find: file="NULL"
  98    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts=NULL
  99  LRU list:
 100  internal_search_find: file="NULL"
 101    type=mysql key="select * from them where id='quote2';" opts=NULL
 102  database lookup required for select * from them where id='quote2';
 103  MySQL query: "select * from them where id='quote2';" opts 'NULL'
 104  MYSQL using cached connection for 127.0.0.1:1223/test/root
 105  lookup yielded: name="\"stquot" id=quote2
 106  search_open: mysql "NULL"
 107    cached open
 108  search_find: file="NULL"
 109    key="select * from them where id='nlonly';" partial=-1 affix=NULL starflags=0 opts=NULL
 110  LRU list:
 111  internal_search_find: file="NULL"
 112    type=mysql key="select * from them where id='nlonly';" opts=NULL
 113  database lookup required for select * from them where id='nlonly';
 114  MySQL query: "select * from them where id='nlonly';" opts 'NULL'
 115  MYSQL using cached connection for 127.0.0.1:1223/test/root
 116  MYSQL: no data found
 117  lookup failed
 118  search_open: mysql "NULL"
 119    cached open
 120  search_find: file="NULL"
 121    key="servers=x:127.0.0.1::1223; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 122  LRU list:
 123  internal_search_find: file="NULL"
 124    type=mysql key="servers=x:127.0.0.1::1223; select name from them where id='ph10';" opts=NULL
 125  database lookup required for servers=x:127.0.0.1::1223; select name from them where id='ph10';
 126  MySQL query: "servers=x:127.0.0.1::1223; select name from them where id='ph10';" opts 'NULL'
 127  lookup deferred: MySQL server "x" not found in mysql_servers
 128  search_open: mysql "NULL"
 129    cached open
 130  search_find: file="NULL"
 131    key="servers=127.0.0.1::1223:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 132  LRU list:
 133  internal_search_find: file="NULL"
 134    type=mysql key="servers=127.0.0.1::1223:x; select name from them where id='ph10';" opts=NULL
 135  database lookup required for servers=127.0.0.1::1223:x; select name from them where id='ph10';
 136  MySQL query: "servers=127.0.0.1::1223:x; select name from them where id='ph10';" opts 'NULL'
 137  MYSQL using cached connection for 127.0.0.1:1223/test/root
 138  lookup yielded: Philip Hazel
 139  search_open: mysql "NULL"
 140    cached open
 141  search_find: file="NULL"
 142    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
 143  LRU list:
 144  internal_search_find: file="NULL"
 145    type=mysql key="servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';" opts=NULL
 146  database lookup required for servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';
 147  MySQL query: "servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';" opts 'NULL'
 148  MYSQL using cached connection for 127.0.0.1:1223/test/root
 149  lookup yielded: Philip Hazel
 150  search_open: mysql "NULL"
 151    cached open
 152  search_find: file="NULL"
 153    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
 154  LRU list:
 155  internal_search_find: file="NULL"
 156    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
 157  database lookup required for servers=ip4.ip4.ip4.ip4::1223/test/root/:127.0.0.1::1223; select name from them where id='ph10';
 158  MySQL query: "servers=ip4.ip4.ip4.ip4::1223/test/root/:127.0.0.1::1223; select name from them where id='ph10';" opts 'NULL'
 159  MYSQL new connection: host=ip4.ip4.ip4.ip4 port=1223 socket=NULL database=test user=root
 160  lookup yielded: Philip Hazel
 161  search_open: mysql "NULL"
 162    cached open
 163  search_find: file="NULL"
 164    key="servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 165  LRU list:
 166  internal_search_find: file="NULL"
 167    type=mysql key="servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';" opts=NULL
 168  database lookup required for servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';
 169  MySQL query: "servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';" opts 'NULL'
 170  MYSQL new connection: host=localhost port=0 socket=TESTSUITE/mysql/sock database=test user=root
 171  lookup yielded: Philip Hazel
 172  search_open: mysql "NULL"
 173    cached open
 174  search_find: file="NULL"
 175    key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0 opts=NULL
 176  LRU list:
 177  internal_search_find: file="NULL"
 178    type=mysql key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
 179  database lookup required for SELECT name FROM them WHERE id IN ('ph10', 'aaaa');
 180  MySQL query: "SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
 181  MYSQL using cached connection for 127.0.0.1:1223/test/root
 182  lookup yielded: Philip Hazel
 183  Aristotle
 184  search_open: mysql "NULL"
 185    cached open
 186  search_find: file="NULL"
 187    key="SELECT *    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 *    FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
 191  database lookup required for SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');
 192  MySQL query: "SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
 193  MYSQL using cached connection for 127.0.0.1:1223/test/root
 194  lookup yielded: name="Philip Hazel" id=ph10
 195  name=Aristotle id=aaaa
 196  search_open: mysql "NULL"
 197    cached open
 198  search_find: file="NULL"
 199    key="delete from them where id='aaaa'" partial=-1 affix=NULL starflags=0 opts=NULL
 200  LRU list:
 201  internal_search_find: file="NULL"
 202    type=mysql key="delete from them where id='aaaa'" opts=NULL
 203  database lookup required for delete from them where id='aaaa'
 204  MySQL query: "delete from them where id='aaaa'" opts 'NULL'
 205  MYSQL using cached connection for 127.0.0.1:1223/test/root
 206  MYSQL: query was not one that returns data
 207  lookup forced cache cleanup
 208  lookup yielded: 1
 209 search_tidyup called
 210 close MYSQL connection: localhost(TESTSUITE/mysql/sock)/test/root
 211 close MYSQL connection: ip4.ip4.ip4.ip4:1223/test/root
 212 close MYSQL connection: 127.0.0.1:1223/test/root
 213 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 214 Exim version x.yz ....
 215 changed uid/gid: forcing real = effective
 216   uid=uuuu gid=CALLER_GID pid=pppp
 217 configuration file is TESTSUITE/test-config
 218 admin user
 219 changed uid/gid: privilege not needed
 220   uid=EXIM_UID gid=EXIM_GID pid=pppp
 221 seeking password data for user "CALLER": cache not available
 222 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 223 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 224 sender address = CALLER@myhost.test.ex
 225 sender_fullhost = [10.0.0.0]
 226 sender_rcvhost = [10.0.0.0]
 227 host in hosts_connection_nolog? no (option unset)
 228 LOG: smtp_connection MAIN
 229   SMTP connection from [10.0.0.0]
 230 host in host_lookup? no (option unset)
 231 set_process_info: pppp handling incoming connection from [10.0.0.0]
 232 host in host_reject_connection? no (option unset)
 233 host in sender_unqualified_hosts? no (option unset)
 234 host in recipient_unqualified_hosts? no (option unset)
 235 host in helo_verify_hosts? no (option unset)
 236 host in helo_try_verify_hosts? no (option unset)
 237 host in helo_accept_junk_hosts? no (option unset)
 238 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 239 smtp_setup_msg entered
 240 SMTP<< mail from:<a@b>
 241 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 242 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 243 SMTP>> 250 OK
 244 SMTP<< rcpt to:<c@d>
 245 using ACL "check_recipient"
 246 processing "warn" (TESTSUITE/test-config 25)
 247  search_open: mysql "NULL"
 248  search_find: file="NULL"
 249    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 250  LRU list:
 251  internal_search_find: file="NULL"
 252    type=mysql key="select name from them where id = 'c'" opts=NULL
 253  database lookup required for select name from them where id = 'c'
 254  MySQL query: "select name from them where id = 'c'" opts 'NULL'
 255  MYSQL new connection: host=127.0.0.1 port=1223 socket=NULL database=test user=root
 256  MYSQL: no data found
 257  lookup failed
 258 check set acl_m0 = ok:   ${lookup mysql                    {select name from them where id = '$local_part'}}
 259                  = ok:
 260  search_open: mysql "NULL"
 261    cached open
 262  search_find: file="NULL"
 263    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::1223/test/root/"
 264  LRU list:
 265  internal_search_find: file="NULL"
 266    type=mysql key="select name from them where id = 'c'" opts="servers=127.0.0.1::1223/test/root/"
 267  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 268  MySQL query: "select name from them where id = 'c'" opts 'servers=127.0.0.1::1223/test/root/'
 269  MYSQL using cached connection for 127.0.0.1:1223/test/root
 270  MYSQL: no data found
 271  lookup failed
 272 check set acl_m0 = ok:   ${lookup mysql,servers=127.0.0.1::1223/test/root/      {select name from them where id = '$local_part'}}
 273                  = ok:
 274  search_open: mysql "NULL"
 275    cached open
 276  search_find: file="NULL"
 277    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::1223"
 278  LRU list:
 279  internal_search_find: file="NULL"
 280    type=mysql key="select name from them where id = 'c'" opts="servers=127.0.0.1::1223"
 281  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 282  MySQL query: "select name from them where id = 'c'" opts 'servers=127.0.0.1::1223'
 283  MYSQL using cached connection for 127.0.0.1:1223/test/root
 284  MYSQL: no data found
 285  lookup failed
 286 check set acl_m0 = ok:   ${lookup mysql,servers=127.0.0.1::1223    {select name from them where id = '$local_part'}}
 287                  = ok:
 288  search_open: mysql "NULL"
 289    cached open
 290  search_find: file="NULL"
 291    key="servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 292  LRU list:
 293  internal_search_find: file="NULL"
 294    type=mysql key="servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'" opts=NULL
 295  database lookup required for servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'
 296  MySQL query: "servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'" opts 'NULL'
 297  lookup deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
 298 warn: condition test deferred in ACL "check_recipient"
 299 LOG: MAIN
 300   H=[10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
 301 processing "warn" (TESTSUITE/test-config 36)
 302 check set acl_m0 = ok:   hostlist
 303 check hosts = net-mysql;select * from them where id='$local_part'
 304 search_open: mysql "NULL"
 305   cached open
 306 search_find: file="NULL"
 307   key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 308 LRU list:
 309 internal_search_find: file="NULL"
 310   type=mysql key="select * from them where id='c'" opts=NULL
 311 database lookup required for select * from them where id='c'
 312 MySQL query: "select * from them where id='c'" opts 'NULL'
 313 MYSQL using cached connection for 127.0.0.1:1223/test/root
 314 MYSQL: no data found
 315 lookup failed
 316 host in "net-mysql;select * from them where id='c'"? no (end of list)
 317 warn: condition test failed in ACL "check_recipient"
 318 processing "warn" (TESTSUITE/test-config 39)
 319 check set acl_m0 = FAIL: hostlist
 320 check hosts = <& net-mysql;servers=127.0.0.1::1223/test/root/; select * from them where id='$local_part'
 321 search_open: mysql "NULL"
 322   cached open
 323 search_find: file="NULL"
 324   key="servers=127.0.0.1::1223/test/root/; 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="servers=127.0.0.1::1223/test/root/; select * from them where id='c'" opts=NULL
 328 database lookup required for servers=127.0.0.1::1223/test/root/; select * from them where id='c'
 329 MySQL query: "servers=127.0.0.1::1223/test/root/; select * from them where id='c'" opts 'NULL'
 330 lookup deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
 331 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'
 332 warn: condition test deferred in ACL "check_recipient"
 333 LOG: MAIN
 334   H=[10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
 335 processing "warn" (TESTSUITE/test-config 44)
 336 check set acl_m0 = FAIL: hostlist
 337 check hosts = <& net-mysql,servers=127.0.0.1::1223/test/root/; select * from them where id='$local_part'
 338 search_open: mysql "NULL"
 339   cached open
 340 search_find: file="NULL"
 341   key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::1223/test/root/"
 342 LRU list:
 343 internal_search_find: file="NULL"
 344   type=mysql key=" select * from them where id='c'" opts="servers=127.0.0.1::1223/test/root/"
 345 database lookup required for  select * from them where id='c'
 346 MySQL query: " select * from them where id='c'" opts 'servers=127.0.0.1::1223/test/root/'
 347 lookup deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
 348 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'
 349 warn: condition test deferred in ACL "check_recipient"
 350 LOG: MAIN
 351   H=[10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
 352 processing "accept" (TESTSUITE/test-config 47)
 353 check domains = +local_domains
 354 d in "@"? no (end of list)
 355 d in "+local_domains"? no (end of list)
 356 accept: condition test failed in ACL "check_recipient"
 357 processing "accept" (TESTSUITE/test-config 48)
 358 check hosts = +relay_hosts
 359 search_open: mysql "NULL"
 360   cached open
 361 search_find: file="NULL"
 362   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 363 LRU list:
 364 internal_search_find: file="NULL"
 365   type=mysql key="select * from them where id='10.0.0.0'" opts=NULL
 366 database lookup required for select * from them where id='10.0.0.0'
 367 MySQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
 368 MYSQL using cached connection for 127.0.0.1:1223/test/root
 369 MYSQL: no data found
 370 lookup failed
 371 host in "net-mysql;select * from them where id='10.0.0.0'"? no (end of list)
 372 host in "+relay_hosts"? no (end of list)
 373 accept: condition test failed in ACL "check_recipient"
 374 processing "deny" (TESTSUITE/test-config 49)
 375   message: relay not permitted
 376 deny: condition test succeeded in ACL "check_recipient"
 377 end of ACL "check_recipient": DENY
 378 SMTP>> 550 relay not permitted
 379 LOG: MAIN REJECT
 380   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 381 SMTP<< quit
 382 SMTP>> 221 myhost.test.ex closing connection
 383 LOG: smtp_connection MAIN
 384   SMTP connection from [10.0.0.0] closed by QUIT
 385 search_tidyup called
 386 close MYSQL connection: 127.0.0.1:1223/test/root
 387 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 388 Exim version x.yz ....
 389 changed uid/gid: forcing real = effective
 390   uid=uuuu gid=CALLER_GID pid=pppp
 391 configuration file is TESTSUITE/test-config
 392 admin user
 393 changed uid/gid: privilege not needed
 394   uid=EXIM_UID gid=EXIM_GID pid=pppp
 395 seeking password data for user "CALLER": cache not available
 396 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 397 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 398 sender address = CALLER@myhost.test.ex
 399 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 400 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 401 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 402 Sender: CALLER@myhost.test.ex
 403 Recipients:
 404   ph10
 405 search_tidyup called
 406 >>Headers received:
 407
 408 rewrite_one_header: type=F:
 409   From: CALLER_NAME <CALLER@myhost.test.ex>
 410 search_tidyup called
 411 >>Headers after rewriting and local additions:
 412 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 413 F From: CALLER_NAME <CALLER@myhost.test.ex>
 414   Date: Tue, 2 Mar 1999 09:44:33 +0000
 415
 416 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 417 Data file written for message 10HmaX-0005vi-00
 418 >>Generated Received: header line
 419 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 420         (envelope-from <CALLER@myhost.test.ex>)
 421         id 10HmaX-0005vi-00
 422         for ph10@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 423 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 424 DSN: Write SPOOL: -dsn_envid NULL
 425 DSN: Write SPOOL  :-dsn_ret 0
 426 DSN: Flags: 0x0
 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 localpart_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 >>>>>>>>>>>>>>>>