test/stderr/2620

   1
   2 WARNING: enabling "trust" authentication for local connections
   3 You can change this by editing pg_hba.conf or using the option -A, or
   4 --auth-local and --auth-host, the next time you run initdb.
   5 Exim version x.yz ....
   6 configuration file is TESTSUITE/test-config
   7 admin user
   8 dropping to exim gid; retaining priv uid
   9  search_open: pgsql "NULL"
  10  search_find: file="NULL"
  11    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
  12  LRU list:
  13  internal_search_find: file="NULL"
  14    type=pgsql key="select name from them where id='ph10';" opts=NULL
  15  database lookup required for select name from them where id='ph10';
  16  PostgreSQL query: "select name from them where id='ph10';" opts 'NULL'
  17  PGSQL new connection: host=localhost port=1223 database=test user=CALLER
  18  creating new cache entry
  19  lookup yielded: Philip Hazel
  20  search_open: pgsql "NULL"
  21    cached open
  22  search_find: file="NULL"
  23    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
  24  LRU list:
  25  internal_search_find: file="NULL"
  26    type=pgsql key="select name from them where id='ph10';" opts=NULL
  27  cached data used for lookup of select name from them where id='ph10';
  28  lookup yielded: Philip Hazel
  29  search_open: pgsql "NULL"
  30    cached open
  31  search_find: file="NULL"
  32    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts=NULL
  33  LRU list:
  34  internal_search_find: file="NULL"
  35    type=pgsql key="select name from them where id='xxxx';" opts=NULL
  36  database lookup required for select name from them where id='xxxx';
  37  PostgreSQL query: "select name from them where id='xxxx';" opts 'NULL'
  38  PGSQL using cached connection for localhost:1223/test/CALLER
  39  PGSQL: no data found
  40  creating new cache entry
  41  lookup failed
  42  search_open: pgsql "NULL"
  43    cached open
  44  search_find: file="NULL"
  45    key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts=NULL
  46  LRU list:
  47  internal_search_find: file="NULL"
  48    type=pgsql key="select name from them where id='nothing';" opts=NULL
  49  database lookup required for select name from them where id='nothing';
  50  PostgreSQL query: "select name from them where id='nothing';" opts 'NULL'
  51  PGSQL using cached connection for localhost:1223/test/CALLER
  52  creating new cache entry
  53  lookup yielded:
  54  search_open: pgsql "NULL"
  55    cached open
  56  search_find: file="NULL"
  57    key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts=NULL
  58  LRU list:
  59  internal_search_find: file="NULL"
  60    type=pgsql key="select id,name from them where id='nothing';" opts=NULL
  61  database lookup required for select id,name from them where id='nothing';
  62  PostgreSQL query: "select id,name from them where id='nothing';" opts 'NULL'
  63  PGSQL using cached connection for localhost:1223/test/CALLER
  64  creating new cache entry
  65  lookup yielded: id=nothing name=""
  66  search_open: pgsql "NULL"
  67    cached open
  68  search_find: file="NULL"
  69    key="delete from them where id='nonexist';" partial=-1 affix=NULL starflags=0 opts=NULL
  70  LRU list:
  71  internal_search_find: file="NULL"
  72    type=pgsql key="delete from them where id='nonexist';" opts=NULL
  73  database lookup required for delete from them where id='nonexist';
  74  PostgreSQL query: "delete from them where id='nonexist';" opts 'NULL'
  75  PGSQL using cached connection for localhost:1223/test/CALLER
  76  PGSQL: command does not return any data but was successful. Rows affected: 0
  77  lookup forced cache cleanup
  78  lookup yielded: 0
  79  search_open: pgsql "NULL"
  80    cached open
  81  search_find: file="NULL"
  82    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts=NULL
  83  LRU list:
  84  internal_search_find: file="NULL"
  85    type=pgsql key="select * from them where id='quote2';" opts=NULL
  86  database lookup required for select * from them where id='quote2';
  87  PostgreSQL query: "select * from them where id='quote2';" opts 'NULL'
  88  PGSQL using cached connection for localhost:1223/test/CALLER
  89  creating new cache entry
  90  lookup yielded: name="\"stquot" id=quote2
  91  search_open: pgsql "NULL"
  92    cached open
  93  search_find: file="NULL"
  94    key="select * from them where id='newline';" partial=-1 affix=NULL starflags=0 opts=NULL
  95  LRU list:
  96  internal_search_find: file="NULL"
  97    type=pgsql key="select * from them where id='newline';" opts=NULL
  98  database lookup required for select * from them where id='newline';
  99  PostgreSQL query: "select * from them where id='newline';" opts 'NULL'
 100  PGSQL using cached connection for localhost:1223/test/CALLER
 101  creating new cache entry
 102  lookup yielded: name="before\r
 103  after" id=newline
 104  search_open: pgsql "NULL"
 105    cached open
 106  search_find: file="NULL"
 107    key="select * from them where id='tab';" partial=-1 affix=NULL starflags=0 opts=NULL
 108  LRU list:
 109  internal_search_find: file="NULL"
 110    type=pgsql key="select * from them where id='tab';" opts=NULL
 111  database lookup required for select * from them where id='tab';
 112  PostgreSQL query: "select * from them where id='tab';" opts 'NULL'
 113  PGSQL using cached connection for localhost:1223/test/CALLER
 114  creating new cache entry
 115  lookup yielded: name="x        x" id=tab
 116  search_open: pgsql "NULL"
 117    cached open
 118  search_find: file="NULL"
 119    key="select * from them where name='''stquot';" partial=-1 affix=NULL starflags=0 opts=NULL
 120  LRU list:
 121  internal_search_find: file="NULL"
 122    type=pgsql key="select * from them where name='''stquot';" opts=NULL
 123  database lookup required for select * from them where name='''stquot';
 124  PostgreSQL query: "select * from them where name='''stquot';" opts 'NULL'
 125  PGSQL using cached connection for localhost:1223/test/CALLER
 126  creating new cache entry
 127  lookup yielded: name='stquot id=quote1
 128  search_open: pgsql "NULL"
 129    cached open
 130  search_find: file="NULL"
 131    key="servers=x:localhost; 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=pgsql key="servers=x:localhost; select name from them where id='ph10';" opts=NULL
 135  database lookup required for servers=x:localhost; select name from them where id='ph10';
 136  PostgreSQL query: "servers=x:localhost; select name from them where id='ph10';" opts 'NULL'
 137  lookup deferred: PostgreSQL server "x" not found in pgsql_servers
 138  search_open: pgsql "NULL"
 139    cached open
 140  search_find: file="NULL"
 141    key="servers=localhost::1223:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 142  LRU list:
 143  internal_search_find: file="NULL"
 144    type=pgsql key="servers=localhost::1223:x; select name from them where id='ph10';" opts=NULL
 145  database lookup required for servers=localhost::1223:x; select name from them where id='ph10';
 146  PostgreSQL query: "servers=localhost::1223:x; select name from them where id='ph10';" opts 'NULL'
 147  PGSQL using cached connection for localhost:1223/test/CALLER
 148  creating new cache entry
 149  lookup yielded: Philip Hazel
 150  search_open: pgsql "NULL"
 151    cached open
 152  search_find: file="NULL"
 153    key="servers=localhost::1223/test/CALLER/:x; 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=pgsql key="servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';" opts=NULL
 157  database lookup required for servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';
 158  PostgreSQL query: "servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';" opts 'NULL'
 159  PGSQL using cached connection for localhost:1223/test/CALLER
 160  creating new cache entry
 161  lookup yielded: Philip Hazel
 162  search_open: pgsql "NULL"
 163    cached open
 164  search_find: file="NULL"
 165    key="servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 166  LRU list:
 167  internal_search_find: file="NULL"
 168    type=pgsql key="servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';" opts=NULL
 169  database lookup required for servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';
 170  PostgreSQL query: "servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';" opts 'NULL'
 171  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 172  creating new cache entry
 173  lookup yielded: Philip Hazel
 174  search_open: pgsql "NULL"
 175    cached open
 176  search_find: file="NULL"
 177    key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0 opts=NULL
 178  LRU list:
 179  internal_search_find: file="NULL"
 180    type=pgsql key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
 181  database lookup required for SELECT name FROM them WHERE id IN ('ph10', 'aaaa');
 182  PostgreSQL query: "SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
 183  PGSQL using cached connection for localhost:1223/test/CALLER
 184  creating new cache entry
 185  lookup yielded: Philip Hazel
 186  Aristotle
 187  search_open: pgsql "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=pgsql 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  PostgreSQL query: "SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
 196  PGSQL using cached connection for localhost:1223/test/CALLER
 197  creating new cache entry
 198  lookup yielded: name="Philip Hazel" id=ph10
 199  name=Aristotle id=aaaa
 200  search_open: pgsql "NULL"
 201    cached open
 202  search_find: file="NULL"
 203    key="delete from them where id='aaaa'" partial=-1 affix=NULL starflags=0 opts=NULL
 204  LRU list:
 205  internal_search_find: file="NULL"
 206    type=pgsql key="delete from them where id='aaaa'" opts=NULL
 207  database lookup required for delete from them where id='aaaa'
 208  PostgreSQL query: "delete from them where id='aaaa'" opts 'NULL'
 209  PGSQL using cached connection for localhost:1223/test/CALLER
 210  PGSQL: command does not return any data but was successful. Rows affected: 1
 211  lookup forced cache cleanup
 212  lookup yielded: 1
 213 search_tidyup called
 214 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 215 close PGSQL connection: localhost:1223/test/CALLER
 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<< helo test
 244 test in helo_lookup_domains? no (end of list)
 245 sender_fullhost = (test) [10.0.0.0]
 246 sender_rcvhost = [10.0.0.0] (helo=test)
 247 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
 248 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
 249 SMTP<< mail from:<a@b>
 250 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 251 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 252 SMTP>> 250 OK
 253 SMTP<< rcpt to:<c@d>
 254 using ACL "check_recipient"
 255 processing "warn" (TESTSUITE/test-config 27)
 256  search_open: pgsql "NULL"
 257  search_find: file="NULL"
 258    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 259  LRU list:
 260  internal_search_find: file="NULL"
 261    type=pgsql key="select name from them where id = 'c'" opts=NULL
 262  database lookup required for select name from them where id = 'c'
 263  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
 264  PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 265  PGSQL: no data found
 266  creating new cache entry
 267  lookup failed
 268 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '$local_part'}}
 269                  = ok:
 270  search_open: pgsql "NULL"
 271    cached open
 272  search_find: file="NULL"
 273    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::1223/test/CALLER/"
 274  LRU list:
 275  internal_search_find: file="NULL"
 276    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::1223/test/CALLER/"
 277  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 278  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::1223/test/CALLER/'
 279  PGSQL using cached connection for localhost:1223/test/CALLER
 280  PGSQL: no data found
 281  replacing old cache entry
 282  lookup failed
 283 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::1223/test/CALLER/    {select name from them where id = '$local_part'}}
 284                  = ok:
 285  search_open: pgsql "NULL"
 286    cached open
 287  search_find: file="NULL"
 288    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::1223"
 289  LRU list:
 290  internal_search_find: file="NULL"
 291    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::1223"
 292  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 293  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::1223'
 294  PGSQL using cached connection for localhost:1223/test/CALLER
 295  PGSQL: no data found
 296  replacing old cache entry
 297  lookup failed
 298 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::1223    {select name from them where id = '$local_part'}}
 299                  = ok:
 300  search_open: pgsql "NULL"
 301    cached open
 302  search_find: file="NULL"
 303    key="servers=localhost::1223/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 304  LRU list:
 305  internal_search_find: file="NULL"
 306    type=pgsql key="servers=localhost::1223/test/CALLER/; select name from them where id = 'c'" opts=NULL
 307  database lookup required for servers=localhost::1223/test/CALLER/; select name from them where id = 'c'
 308  PostgreSQL query: "servers=localhost::1223/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
 309  lookup deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 310 warn: condition test deferred in ACL "check_recipient"
 311 LOG: MAIN
 312   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 313 processing "warn" (TESTSUITE/test-config 38)
 314 check set acl_m0 = ok:   hostlist
 315 check hosts = net-pgsql;select * from them where id='$local_part'
 316 search_open: pgsql "NULL"
 317   cached open
 318 search_find: file="NULL"
 319   key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 320 LRU list:
 321 internal_search_find: file="NULL"
 322   type=pgsql key="select * from them where id='c'" opts=NULL
 323 database lookup required for select * from them where id='c'
 324 PostgreSQL query: "select * from them where id='c'" opts 'NULL'
 325 PGSQL using cached connection for localhost:1223/test/CALLER
 326 PGSQL: no data found
 327 creating new cache entry
 328 lookup failed
 329 host in "net-pgsql;select * from them where id='c'"? no (end of list)
 330 warn: condition test failed in ACL "check_recipient"
 331 processing "warn" (TESTSUITE/test-config 41)
 332 check set acl_m0 = FAIL: hostlist
 333 check hosts = <& net-pgsql;servers=localhost::1223/test/CALLER/; select * from them where id='$local_part'
 334 search_open: pgsql "NULL"
 335   cached open
 336 search_find: file="NULL"
 337   key="servers=localhost::1223/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 338 LRU list:
 339 internal_search_find: file="NULL"
 340   type=pgsql key="servers=localhost::1223/test/CALLER/; select * from them where id='c'" opts=NULL
 341 database lookup required for servers=localhost::1223/test/CALLER/; select * from them where id='c'
 342 PostgreSQL query: "servers=localhost::1223/test/CALLER/; select * from them where id='c'" opts 'NULL'
 343 lookup deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 344 host in "<& net-pgsql;servers=localhost::1223/test/CALLER/; select * from them where id='c'"? list match deferred for net-pgsql;servers=localhost::1223/test/CALLER/; select * from them where id='c'
 345 warn: condition test deferred in ACL "check_recipient"
 346 LOG: MAIN
 347   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 348 processing "warn" (TESTSUITE/test-config 46)
 349 check set acl_m0 = FAIL: hostlist
 350 check hosts = <& net-pgsql,servers=localhost::1223/test/CALLER/; select * from them where id='$local_part'
 351 search_open: pgsql "NULL"
 352   cached open
 353 search_find: file="NULL"
 354   key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::1223/test/CALLER/"
 355 LRU list:
 356 internal_search_find: file="NULL"
 357   type=pgsql key=" select * from them where id='c'" opts="servers=localhost::1223/test/CALLER/"
 358 database lookup required for  select * from them where id='c'
 359 PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::1223/test/CALLER/'
 360 lookup deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 361 host in "<& net-pgsql,servers=localhost::1223/test/CALLER/; select * from them where id='c'"? list match deferred for net-pgsql,servers=localhost::1223/test/CALLER/; select * from them where id='c'
 362 warn: condition test deferred in ACL "check_recipient"
 363 LOG: MAIN
 364   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 365 processing "accept" (TESTSUITE/test-config 49)
 366 check domains = +local_domains
 367 d in "@"? no (end of list)
 368 d in "+local_domains"? no (end of list)
 369 accept: condition test failed in ACL "check_recipient"
 370 processing "accept" (TESTSUITE/test-config 50)
 371 check hosts = +relay_hosts
 372 search_open: pgsql "NULL"
 373   cached open
 374 search_find: file="NULL"
 375   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 376 LRU list:
 377 internal_search_find: file="NULL"
 378   type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
 379 database lookup required for select * from them where id='10.0.0.0'
 380 PostgreSQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
 381 PGSQL using cached connection for localhost:1223/test/CALLER
 382 PGSQL: no data found
 383 creating new cache entry
 384 lookup failed
 385 host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 386 host in "+relay_hosts"? no (end of list)
 387 accept: condition test failed in ACL "check_recipient"
 388 processing "deny" (TESTSUITE/test-config 51)
 389   message: relay not permitted
 390 deny: condition test succeeded in ACL "check_recipient"
 391 end of ACL "check_recipient": DENY
 392 SMTP>> 550 relay not permitted
 393 LOG: MAIN REJECT
 394   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 395 SMTP<< rcpt to:<c@d>
 396 using ACL "check_recipient"
 397 processing "warn" (TESTSUITE/test-config 27)
 398  search_open: pgsql "NULL"
 399    cached open
 400  search_find: file="NULL"
 401    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 402  LRU list:
 403  internal_search_find: file="NULL"
 404    type=pgsql key="select name from them where id = 'c'" opts=NULL
 405  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 406  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
 407  PGSQL using cached connection for localhost:1223/test/CALLER
 408  PGSQL: no data found
 409  replacing old cache entry
 410  lookup failed
 411 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '$local_part'}}
 412                  = ok:
 413  search_open: pgsql "NULL"
 414    cached open
 415  search_find: file="NULL"
 416    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::1223/test/CALLER/"
 417  LRU list:
 418  internal_search_find: file="NULL"
 419    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::1223/test/CALLER/"
 420  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 421  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::1223/test/CALLER/'
 422  PGSQL using cached connection for localhost:1223/test/CALLER
 423  PGSQL: no data found
 424  replacing old cache entry
 425  lookup failed
 426 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::1223/test/CALLER/    {select name from them where id = '$local_part'}}
 427                  = ok:
 428  search_open: pgsql "NULL"
 429    cached open
 430  search_find: file="NULL"
 431    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::1223"
 432  LRU list:
 433  internal_search_find: file="NULL"
 434    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::1223"
 435  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 436  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::1223'
 437  PGSQL using cached connection for localhost:1223/test/CALLER
 438  PGSQL: no data found
 439  replacing old cache entry
 440  lookup failed
 441 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::1223    {select name from them where id = '$local_part'}}
 442                  = ok:
 443  search_open: pgsql "NULL"
 444    cached open
 445  search_find: file="NULL"
 446    key="servers=localhost::1223/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 447  LRU list:
 448  internal_search_find: file="NULL"
 449    type=pgsql key="servers=localhost::1223/test/CALLER/; select name from them where id = 'c'" opts=NULL
 450  database lookup required for servers=localhost::1223/test/CALLER/; select name from them where id = 'c'
 451  PostgreSQL query: "servers=localhost::1223/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
 452  lookup deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 453 warn: condition test deferred in ACL "check_recipient"
 454 LOG: MAIN
 455   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 456 processing "warn" (TESTSUITE/test-config 38)
 457 check set acl_m0 = ok:   hostlist
 458 check hosts = net-pgsql;select * from them where id='$local_part'
 459 search_open: pgsql "NULL"
 460   cached open
 461 search_find: file="NULL"
 462   key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 463 LRU list:
 464 internal_search_find: file="NULL"
 465   type=pgsql key="select * from them where id='c'" opts=NULL
 466 cached data used for lookup of select * from them where id='c'
 467 lookup failed
 468 host in "net-pgsql;select * from them where id='c'"? no (end of list)
 469 warn: condition test failed in ACL "check_recipient"
 470 processing "warn" (TESTSUITE/test-config 41)
 471 check set acl_m0 = FAIL: hostlist
 472 check hosts = <& net-pgsql;servers=localhost::1223/test/CALLER/; select * from them where id='$local_part'
 473 search_open: pgsql "NULL"
 474   cached open
 475 search_find: file="NULL"
 476   key="servers=localhost::1223/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 477 LRU list:
 478 internal_search_find: file="NULL"
 479   type=pgsql key="servers=localhost::1223/test/CALLER/; select * from them where id='c'" opts=NULL
 480 database lookup required for servers=localhost::1223/test/CALLER/; select * from them where id='c'
 481 PostgreSQL query: "servers=localhost::1223/test/CALLER/; select * from them where id='c'" opts 'NULL'
 482 lookup deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 483 host in "<& net-pgsql;servers=localhost::1223/test/CALLER/; select * from them where id='c'"? list match deferred for net-pgsql;servers=localhost::1223/test/CALLER/; select * from them where id='c'
 484 warn: condition test deferred in ACL "check_recipient"
 485 LOG: MAIN
 486   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 487 processing "warn" (TESTSUITE/test-config 46)
 488 check set acl_m0 = FAIL: hostlist
 489 check hosts = <& net-pgsql,servers=localhost::1223/test/CALLER/; select * from them where id='$local_part'
 490 search_open: pgsql "NULL"
 491   cached open
 492 search_find: file="NULL"
 493   key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::1223/test/CALLER/"
 494 LRU list:
 495 internal_search_find: file="NULL"
 496   type=pgsql key=" select * from them where id='c'" opts="servers=localhost::1223/test/CALLER/"
 497 database lookup required for  select * from them where id='c'
 498 PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::1223/test/CALLER/'
 499 lookup deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 500 host in "<& net-pgsql,servers=localhost::1223/test/CALLER/; select * from them where id='c'"? list match deferred for net-pgsql,servers=localhost::1223/test/CALLER/; select * from them where id='c'
 501 warn: condition test deferred in ACL "check_recipient"
 502 LOG: MAIN
 503   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:1223/test/CALLER/" is tainted
 504 processing "accept" (TESTSUITE/test-config 49)
 505 check domains = +local_domains
 506 d in "@"? no (end of list)
 507 d in "+local_domains"? no (end of list)
 508 accept: condition test failed in ACL "check_recipient"
 509 processing "accept" (TESTSUITE/test-config 50)
 510 check hosts = +relay_hosts
 511 search_open: pgsql "NULL"
 512   cached open
 513 search_find: file="NULL"
 514   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 515 LRU list:
 516 internal_search_find: file="NULL"
 517   type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
 518 cached data used for lookup of select * from them where id='10.0.0.0'
 519 lookup failed
 520 host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 521 host in "+relay_hosts"? no (end of list)
 522 accept: condition test failed in ACL "check_recipient"
 523 processing "deny" (TESTSUITE/test-config 51)
 524   message: relay not permitted
 525 deny: condition test succeeded in ACL "check_recipient"
 526 end of ACL "check_recipient": DENY
 527 SMTP>> 550 relay not permitted
 528 LOG: MAIN REJECT
 529   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 530 SMTP<< quit
 531 SMTP>> 221 myhost.test.ex closing connection
 532 LOG: smtp_connection MAIN
 533   SMTP connection from (test) [10.0.0.0] closed by QUIT
 534 search_tidyup called
 535 close PGSQL connection: localhost:1223/test/CALLER
 536 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 537 Exim version x.yz ....
 538 changed uid/gid: forcing real = effective
 539   uid=uuuu gid=CALLER_GID pid=pppp
 540 configuration file is TESTSUITE/test-config
 541 admin user
 542 changed uid/gid: privilege not needed
 543   uid=EXIM_UID gid=EXIM_GID pid=pppp
 544 seeking password data for user "CALLER": cache not available
 545 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 546 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 547 sender address = CALLER@myhost.test.ex
 548 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 549 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 550 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 551 Sender: CALLER@myhost.test.ex
 552 Recipients:
 553   CALLER
 554 search_tidyup called
 555 >>Headers received:
 556
 557 rewrite_one_header: type=F:
 558   From: CALLER_NAME <CALLER@myhost.test.ex>
 559 search_tidyup called
 560 >>Headers after rewriting and local additions:
 561 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 562 F From: CALLER_NAME <CALLER@myhost.test.ex>
 563   Date: Tue, 2 Mar 1999 09:44:33 +0000
 564
 565 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 566 Data file written for message 10HmaX-0005vi-00
 567 >>Generated Received: header line
 568 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 569         (envelope-from <CALLER@myhost.test.ex>)
 570         id 10HmaX-0005vi-00
 571         for CALLER@myhost.test.ex;
 572         Tue, 2 Mar 1999 09:44:33 +0000
 573 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 574 DSN: **** SPOOL_OUT - address: <CALLER@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 575 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 576 Size of headers = sss
 577 LOG: MAIN
 578   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 579 created log directory TESTSUITE/spool/log
 580 search_tidyup called
 581 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 582 Exim version x.yz ....
 583 changed uid/gid: forcing real = effective
 584   uid=uuuu gid=EXIM_GID pid=pppp
 585 configuration file is TESTSUITE/test-config
 586 trusted user
 587 admin user
 588 dropping to exim gid; retaining priv uid
 589 seeking password data for user "CALLER": cache not available
 590 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 591 set_process_info: pppp delivering specified messages
 592 set_process_info: pppp delivering 10HmaX-0005vi-00
 593 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 594 reading spool file 10HmaX-0005vi-00-H
 595 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 596 sender_local=1 ident=CALLER
 597 Non-recipients:
 598  Empty Tree
 599 ---- End of tree ----
 600 recipients_count=1
 601 **** SPOOL_IN - No additional fields
 602 body_linecount=1 message_linecount=8
 603 DSN: set orcpt:   flags: 0x0
 604 Delivery address list:
 605   CALLER@myhost.test.ex
 606  locking TESTSUITE/spool/db/retry.lockfile
 607  locked  TESTSUITE/spool/db/retry.lockfile
 608  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 609  returned from EXIM_DBOPEN: (nil)
 610  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 611 no retry data available
 612 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 613 Considering: CALLER@myhost.test.ex
 614 unique = CALLER@myhost.test.ex
 615 no   domain  retry record
 616 no   address retry record
 617 CALLER@myhost.test.ex: queued for routing
 618 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 619 routing CALLER@myhost.test.ex
 620 --------> r1 router <--------
 621 local_part=CALLER domain=myhost.test.ex
 622 processing address_data
 623  search_open: pgsql "NULL"
 624  search_find: file="NULL"
 625    key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
 626  LRU list:
 627  internal_search_find: file="NULL"
 628    type=pgsql key="select name from them where id='ph10'" opts=NULL
 629  database lookup required for select name from them where id='ph10'
 630  PostgreSQL query: "select name from them where id='ph10'" opts 'NULL'
 631  PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 632  creating new cache entry
 633  lookup yielded: Philip Hazel
 634 calling r1 router
 635 r1 router called for CALLER@myhost.test.ex
 636   domain = myhost.test.ex
 637 set transport t1
 638 queued for t1 transport: local_part = CALLER
 639 domain = myhost.test.ex
 640   errors_to=NULL
 641   domain_data=NULL local_part_data=NULL
 642 routed by r1 router
 643   envelope to: CALLER@myhost.test.ex
 644   transport: t1
 645 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 646 After routing:
 647   Local deliveries:
 648     CALLER@myhost.test.ex
 649   Remote deliveries:
 650   Failed addresses:
 651   Deferred addresses:
 652 search_tidyup called
 653 close PGSQL connection: localhost:1223/test/CALLER
 654 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 655 --------> CALLER@myhost.test.ex <--------
 656  locking TESTSUITE/spool/db/retry.lockfile
 657  locked  TESTSUITE/spool/db/retry.lockfile
 658  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 659  returned from EXIM_DBOPEN: (nil)
 660  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 661 no retry data available
 662 search_tidyup called
 663 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 664   uid=CALLER_UID gid=CALLER_GID pid=pppp
 665   home=NULL current=/
 666 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 667 appendfile transport entered
 668  search_open: pgsql "NULL"
 669  search_find: file="NULL"
 670    key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
 671  LRU list:
 672  internal_search_find: file="NULL"
 673    type=pgsql key="select id from them where id='ph10'" opts=NULL
 674  database lookup required for select id from them where id='ph10'
 675  PostgreSQL query: "select id from them where id='ph10'" opts 'NULL'
 676  PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 677  creating new cache entry
 678  lookup yielded: ph10
 679 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 680   file=TESTSUITE/test-mail/ph10 format=unix
 681   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 682   message_suffix=\n
 683   maildir_use_size_file=no
 684   locking by lockfile fcntl
 685 lock name: TESTSUITE/test-mail/ph10.lock
 686 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
 687 lock file created
 688 mailbox TESTSUITE/test-mail/ph10 is locked
 689 writing to file TESTSUITE/test-mail/ph10
 690 writing data block fd=dddd size=sss timeout=0
 691 cannot use sendfile for body: spoolfile not wireformat
 692 writing data block fd=dddd size=sss timeout=0
 693 writing data block fd=dddd size=sss timeout=0
 694 appendfile yields 0 with errno=dd more_errno=dd
 695 search_tidyup called
 696 close PGSQL connection: localhost:1223/test/CALLER
 697 journalling CALLER@myhost.test.ex
 698 t1 transport returned OK for CALLER@myhost.test.ex
 699 post-process CALLER@myhost.test.ex (0)
 700 CALLER@myhost.test.ex delivered
 701 LOG: MAIN
 702   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 703 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 704 changed uid/gid: post-delivery tidying
 705   uid=EXIM_UID gid=EXIM_GID pid=pppp
 706 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 707 Processing retry items
 708 Succeeded addresses:
 709  CALLER@myhost.test.ex: no retry items
 710 Failed addresses:
 711 Deferred addresses:
 712 end of retry processing
 713 DSN: processing router : r1
 714 DSN: processing successful delivery address: CALLER@myhost.test.ex
 715 DSN: Sender_address: CALLER@myhost.test.ex
 716 DSN: orcpt: NULL  flags: 0x0
 717 DSN: envid: NULL  ret: 0
 718 DSN: Final recipient: CALLER@myhost.test.ex
 719 DSN: Remote SMTP server supports DSN: 0
 720 DSN: not sending DSN success message
 721 LOG: MAIN
 722   Completed
 723 end delivery of 10HmaX-0005vi-00
 724 search_tidyup called
 725 search_tidyup called
 726 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 727 search_tidyup called
 728 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 729 Exim version x.yz ....
 730 configuration file is TESTSUITE/test-config
 731 admin user
 732 dropping to exim gid; retaining priv uid
 733  search_open: pgsql "NULL"
 734  search_find: file="NULL"
 735    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 736  LRU list:
 737  internal_search_find: file="NULL"
 738    type=pgsql key="select name from them where id='ph10';" opts=NULL
 739  database lookup required for select name from them where id='ph10';
 740  PostgreSQL query: "select name from them where id='ph10';" opts 'NULL'
 741  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 742  creating new cache entry
 743  lookup yielded: Philip Hazel
 744 search_tidyup called
 745 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 746 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 747
 748 ******** SERVER ********