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 qualify & rewrite recipients list
 558 global rewrite rules
 559 rewrite headers
 560  rewrite_one_header: type=F:
 561    From: CALLER_NAME <CALLER@myhost.test.ex>
 562 search_tidyup called
 563 >>Headers after rewriting and local additions:
 564 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 565 F From: CALLER_NAME <CALLER@myhost.test.ex>
 566   Date: Tue, 2 Mar 1999 09:44:33 +0000
 567
 568 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 569 Data file written for message 10HmaX-0005vi-00
 570 >>Generated Received: header line
 571 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 572         (envelope-from <CALLER@myhost.test.ex>)
 573         id 10HmaX-0005vi-00
 574         for CALLER@myhost.test.ex;
 575         Tue, 2 Mar 1999 09:44:33 +0000
 576 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 577 DSN: **** SPOOL_OUT - address: <CALLER@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 578 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 579 Size of headers = sss
 580 LOG: MAIN
 581   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 582 created log directory TESTSUITE/spool/log
 583 search_tidyup called
 584 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 585 Exim version x.yz ....
 586 changed uid/gid: forcing real = effective
 587   uid=uuuu gid=EXIM_GID pid=pppp
 588 configuration file is TESTSUITE/test-config
 589 trusted user
 590 admin user
 591 dropping to exim gid; retaining priv uid
 592 seeking password data for user "CALLER": cache not available
 593 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 594 set_process_info: pppp delivering specified messages
 595 set_process_info: pppp delivering 10HmaX-0005vi-00
 596 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 597 reading spool file 10HmaX-0005vi-00-H
 598 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 599 sender_local=1 ident=CALLER
 600 Non-recipients:
 601  Empty Tree
 602 ---- End of tree ----
 603 recipients_count=1
 604 **** SPOOL_IN - No additional fields
 605 body_linecount=1 message_linecount=8
 606 DSN: set orcpt:   flags: 0x0
 607 Delivery address list:
 608   CALLER@myhost.test.ex
 609  locking TESTSUITE/spool/db/retry.lockfile
 610  locked  TESTSUITE/spool/db/retry.lockfile
 611  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 612  returned from EXIM_DBOPEN: (nil)
 613  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 614 no retry data available
 615 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 616 Considering: CALLER@myhost.test.ex
 617 unique = CALLER@myhost.test.ex
 618 no   domain  retry record
 619 no   address retry record
 620 CALLER@myhost.test.ex: queued for routing
 621 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 622 routing CALLER@myhost.test.ex
 623 --------> r1 router <--------
 624 local_part=CALLER domain=myhost.test.ex
 625 processing address_data
 626  search_open: pgsql "NULL"
 627  search_find: file="NULL"
 628    key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
 629  LRU list:
 630  internal_search_find: file="NULL"
 631    type=pgsql key="select name from them where id='ph10'" opts=NULL
 632  database lookup required for select name from them where id='ph10'
 633  PostgreSQL query: "select name from them where id='ph10'" opts 'NULL'
 634  PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 635  creating new cache entry
 636  lookup yielded: Philip Hazel
 637 calling r1 router
 638 r1 router called for CALLER@myhost.test.ex
 639   domain = myhost.test.ex
 640 set transport t1
 641 queued for t1 transport: local_part = CALLER
 642 domain = myhost.test.ex
 643   errors_to=NULL
 644   domain_data=NULL local_part_data=NULL
 645 routed by r1 router
 646   envelope to: CALLER@myhost.test.ex
 647   transport: t1
 648 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 649 After routing:
 650   Local deliveries:
 651     CALLER@myhost.test.ex
 652   Remote deliveries:
 653   Failed addresses:
 654   Deferred addresses:
 655 search_tidyup called
 656 close PGSQL connection: localhost:1223/test/CALLER
 657 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 658 --------> CALLER@myhost.test.ex <--------
 659  locking TESTSUITE/spool/db/retry.lockfile
 660  locked  TESTSUITE/spool/db/retry.lockfile
 661  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 662  returned from EXIM_DBOPEN: (nil)
 663  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 664 no retry data available
 665 search_tidyup called
 666 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 667   uid=CALLER_UID gid=CALLER_GID pid=pppp
 668   home=NULL current=/
 669 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 670 appendfile transport entered
 671  search_open: pgsql "NULL"
 672  search_find: file="NULL"
 673    key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
 674  LRU list:
 675  internal_search_find: file="NULL"
 676    type=pgsql key="select id from them where id='ph10'" opts=NULL
 677  database lookup required for select id from them where id='ph10'
 678  PostgreSQL query: "select id from them where id='ph10'" opts 'NULL'
 679  PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 680  creating new cache entry
 681  lookup yielded: ph10
 682 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 683   file=TESTSUITE/test-mail/ph10 format=unix
 684   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 685   message_suffix=\n
 686   maildir_use_size_file=no
 687   locking by lockfile fcntl
 688 lock name: TESTSUITE/test-mail/ph10.lock
 689 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
 690 lock file created
 691 mailbox TESTSUITE/test-mail/ph10 is locked
 692 writing to file TESTSUITE/test-mail/ph10
 693 writing data block fd=dddd size=sss timeout=0
 694 cannot use sendfile for body: spoolfile not wireformat
 695 writing data block fd=dddd size=sss timeout=0
 696 writing data block fd=dddd size=sss timeout=0
 697 appendfile yields 0 with errno=dd more_errno=dd
 698 search_tidyup called
 699 close PGSQL connection: localhost:1223/test/CALLER
 700 journalling CALLER@myhost.test.ex
 701 t1 transport returned OK for CALLER@myhost.test.ex
 702 post-process CALLER@myhost.test.ex (0)
 703 CALLER@myhost.test.ex delivered
 704 LOG: MAIN
 705   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 706 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 707 changed uid/gid: post-delivery tidying
 708   uid=EXIM_UID gid=EXIM_GID pid=pppp
 709 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 710 Processing retry items
 711 Succeeded addresses:
 712  CALLER@myhost.test.ex: no retry items
 713 Failed addresses:
 714 Deferred addresses:
 715 end of retry processing
 716 DSN: processing router : r1
 717 DSN: processing successful delivery address: CALLER@myhost.test.ex
 718 DSN: Sender_address: CALLER@myhost.test.ex
 719 DSN: orcpt: NULL  flags: 0x0
 720 DSN: envid: NULL  ret: 0
 721 DSN: Final recipient: CALLER@myhost.test.ex
 722 DSN: Remote SMTP server supports DSN: 0
 723 DSN: not sending DSN success message
 724 LOG: MAIN
 725   Completed
 726 end delivery of 10HmaX-0005vi-00
 727 search_tidyup called
 728 search_tidyup called
 729 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 730 search_tidyup called
 731 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 732 Exim version x.yz ....
 733 configuration file is TESTSUITE/test-config
 734 admin user
 735 dropping to exim gid; retaining priv uid
 736  search_open: pgsql "NULL"
 737  search_find: file="NULL"
 738    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 739  LRU list:
 740  internal_search_find: file="NULL"
 741    type=pgsql key="select name from them where id='ph10';" opts=NULL
 742  database lookup required for select name from them where id='ph10';
 743  PostgreSQL query: "select name from them where id='ph10';" opts 'NULL'
 744  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 745  creating new cache entry
 746  lookup yielded: Philip Hazel
 747 search_tidyup called
 748 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 749 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 750
 751 ******** SERVER ********