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=PORT_N 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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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::PORT_N: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::PORT_N:x; select name from them where id='ph10';" opts=NULL
 145  database lookup required for servers=localhost::PORT_N:x; select name from them where id='ph10';
 146  PostgreSQL query: "servers=localhost::PORT_N:x; select name from them where id='ph10';" opts 'NULL'
 147  PGSQL using cached connection for localhost:PORT_N/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::PORT_N/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::PORT_N/test/CALLER/:x; select name from them where id='ph10';" opts=NULL
 157  database lookup required for servers=localhost::PORT_N/test/CALLER/:x; select name from them where id='ph10';
 158  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/:x; select name from them where id='ph10';" opts 'NULL'
 159  PGSQL using cached connection for localhost:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/test/CALLER
 216 >>>>>>>>>>>>>>>> Exim pid=p1235 (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=p1236
 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=p1236
 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 host in pipelining_connect_advertise_hosts?
 242  list element:
 243 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 244 smtp_setup_msg entered
 245 SMTP<< helo test
 246 test in helo_lookup_domains?
 247  list element: @
 248  list element: @[]
 249 test in helo_lookup_domains? no (end of list)
 250 sender_fullhost = (test) [10.0.0.0]
 251 sender_rcvhost = [10.0.0.0] (helo=test)
 252 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
 253 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
 254 SMTP<< mail from:<a@b>
 255 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 256 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 257 SMTP>> 250 OK
 258 SMTP<< rcpt to:<c@d>
 259 using ACL "check_recipient"
 260 processing "warn" (TESTSUITE/test-config 27)
 261  search_open: pgsql "NULL"
 262  search_find: file="NULL"
 263    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 264  LRU list:
 265  internal_search_find: file="NULL"
 266    type=pgsql key="select name from them where id = 'c'" opts=NULL
 267  database lookup required for select name from them where id = 'c'
 268                               (tainted, quoted:pgsql)
 269  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
 270  PGSQL new connection: host=localhost port=PORT_N database=test user=CALLER
 271  PGSQL: no data found
 272  creating new cache entry
 273  lookup failed
 274 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '${quote_pgsql:$local_part}'}}
 275                  = ok:
 276  search_open: pgsql "NULL"
 277    cached open
 278  search_find: file="NULL"
 279    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="cache=no_rd"
 280  LRU list:
 281  internal_search_find: file="NULL"
 282    type=pgsql key="select name from them where id = 'c'" opts=NULL
 283  cached data found but no_rd option set;  database lookup required for select name from them where id = 'c'
 284                               (tainted)
 285 LOG: MAIN PANIC
 286   tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 27): select name from them where id = 'c'
 287  search_type NN (pgsql) quoting -1 (none)
 288  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
 289  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 290  PGSQL: no data found
 291  replacing old cache entry
 292  lookup failed
 293 check set acl_m0 = FAIL: ${lookup pgsql,cache=no_rd        {select name from them where id = '$local_part'}}
 294                  = FAIL:
 295 warn: condition test succeeded in ACL "check_recipient"
 296 processing "warn" (TESTSUITE/test-config 32)
 297  search_open: pgsql "NULL"
 298    cached open
 299  search_find: file="NULL"
 300    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
 301  LRU list:
 302  internal_search_find: file="NULL"
 303    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N/test/CALLER/"
 304  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 305                               (tainted, quoted:pgsql)
 306  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N/test/CALLER/'
 307  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 308  PGSQL: no data found
 309  replacing old cache entry
 310  lookup failed
 311 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N/test/CALLER/      {select name from them where id = '${quote_pgsql:$local_part}'}}
 312                  = ok:
 313  search_open: pgsql "NULL"
 314    cached open
 315  search_find: file="NULL"
 316    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N"
 317  LRU list:
 318  internal_search_find: file="NULL"
 319    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N"
 320  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 321                               (tainted, quoted:pgsql)
 322  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N'
 323  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 324  PGSQL: no data found
 325  replacing old cache entry
 326  lookup failed
 327 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N    {select name from them where id = '${quote_pgsql:$local_part}'}}
 328                  = ok:
 329  search_open: pgsql "NULL"
 330    cached open
 331  search_find: file="NULL"
 332    key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 333  LRU list:
 334  internal_search_find: file="NULL"
 335    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts=NULL
 336  database lookup required for servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'
 337                               (tainted, quoted:pgsql)
 338  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
 339  lookup deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 340 warn: condition test deferred in ACL "check_recipient"
 341 LOG: MAIN
 342   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 343 processing "warn" (TESTSUITE/test-config 41)
 344 check set acl_m0 = ok:   hostlist
 345 check hosts = net-pgsql;select * from them where id='${quote_pgsql:$local_part}'
 346 host in "net-pgsql;select * from them where id='c'"?
 347  list element: net-pgsql;select * from them where id='c'
 348  search_open: pgsql "NULL"
 349    cached open
 350  search_find: file="NULL"
 351    key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 352  LRU list:
 353  internal_search_find: file="NULL"
 354    type=pgsql key="select * from them where id='c'" opts=NULL
 355  database lookup required for select * from them where id='c'
 356                               (tainted, quoted:pgsql)
 357  PostgreSQL query: "select * from them where id='c'" opts 'NULL'
 358  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 359  PGSQL: no data found
 360  creating new cache entry
 361  lookup failed
 362 host in "net-pgsql;select * from them where id='c'"? no (end of list)
 363 warn: condition test failed in ACL "check_recipient"
 364 processing "warn" (TESTSUITE/test-config 44)
 365 check set acl_m0 = FAIL: hostlist
 366 check hosts = <& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
 367 host in "<& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
 368  list element: net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
 369  search_open: pgsql "NULL"
 370    cached open
 371  search_find: file="NULL"
 372    key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 373  LRU list:
 374  internal_search_find: file="NULL"
 375    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts=NULL
 376  database lookup required for servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
 377                               (tainted, quoted:pgsql)
 378  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts 'NULL'
 379  lookup deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 380 host in "<& net-pgsql;servers=localhost::PORT_N/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'
 381 warn: condition test deferred in ACL "check_recipient"
 382 LOG: MAIN
 383   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 384 processing "warn" (TESTSUITE/test-config 49)
 385 check set acl_m0 = FAIL: hostlist
 386 check hosts = <& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
 387 host in "<& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
 388  list element: net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
 389  search_open: pgsql "NULL"
 390    cached open
 391  search_find: file="NULL"
 392    key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
 393  LRU list:
 394  internal_search_find: file="NULL"
 395    type=pgsql key=" select * from them where id='c'" opts="servers=localhost::PORT_N/test/CALLER/"
 396  database lookup required for  select * from them where id='c'
 397                               (tainted, quoted:pgsql)
 398  PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::PORT_N/test/CALLER/'
 399  lookup deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 400 host in "<& net-pgsql,servers=localhost::PORT_N/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'
 401 warn: condition test deferred in ACL "check_recipient"
 402 LOG: MAIN
 403   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 404 processing "accept" (TESTSUITE/test-config 52)
 405 check domains = +local_domains
 406 d in "+local_domains"?
 407  list element: +local_domains
 408   start sublist local_domains
 409    d in "@"?
 410    ╎list element: @
 411    d in "@"? no (end of list)
 412   end sublist local_domains
 413 d in "+local_domains"? no (end of list)
 414 accept: condition test failed in ACL "check_recipient"
 415 processing "accept" (TESTSUITE/test-config 53)
 416 check hosts = +relay_hosts
 417 host in "+relay_hosts"?
 418  list element: +relay_hosts
 419   start sublist relay_hosts
 420   host in "net-pgsql;select * from them where id='10.0.0.0'"?
 421    ╎list element: net-pgsql;select * from them where id='10.0.0.0'
 422    ╎search_open: pgsql "NULL"
 423    ╎  cached open
 424    ╎search_find: file="NULL"
 425    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 426    ╎LRU list:
 427    ╎internal_search_find: file="NULL"
 428    ╎  type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
 429    ╎database lookup required for select * from them where id='10.0.0.0'
 430    ╎PostgreSQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
 431    ╎PGSQL using cached connection for localhost:PORT_N/test/CALLER
 432    ╎PGSQL: no data found
 433    ╎creating new cache entry
 434    ╎lookup failed
 435   host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 436   end sublist relay_hosts
 437 host in "+relay_hosts"? no (end of list)
 438 accept: condition test failed in ACL "check_recipient"
 439 processing "deny" (TESTSUITE/test-config 54)
 440   message: relay not permitted
 441 deny: condition test succeeded in ACL "check_recipient"
 442 end of ACL "check_recipient": DENY
 443 SMTP>> 550 relay not permitted
 444 LOG: MAIN REJECT
 445   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 446 SMTP<< rcpt to:<c@d>
 447 using ACL "check_recipient"
 448 processing "warn" (TESTSUITE/test-config 27)
 449  search_open: pgsql "NULL"
 450    cached open
 451  search_find: file="NULL"
 452    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 453  LRU list:
 454  internal_search_find: file="NULL"
 455    type=pgsql key="select name from them where id = 'c'" opts=NULL
 456  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 457                               (tainted, quoted:pgsql)
 458  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
 459  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 460  PGSQL: no data found
 461  replacing old cache entry
 462  lookup failed
 463 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '${quote_pgsql:$local_part}'}}
 464                  = ok:
 465  search_open: pgsql "NULL"
 466    cached open
 467  search_find: file="NULL"
 468    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="cache=no_rd"
 469  LRU list:
 470  internal_search_find: file="NULL"
 471    type=pgsql key="select name from them where id = 'c'" opts=NULL
 472  cached data found but no_rd option set;  database lookup required for select name from them where id = 'c'
 473                               (tainted)
 474 LOG: MAIN PANIC
 475   tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 27): select name from them where id = 'c'
 476  search_type NN (pgsql) quoting -1 (none)
 477  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
 478  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 479  PGSQL: no data found
 480  replacing old cache entry
 481  lookup failed
 482 check set acl_m0 = FAIL: ${lookup pgsql,cache=no_rd        {select name from them where id = '$local_part'}}
 483                  = FAIL:
 484 warn: condition test succeeded in ACL "check_recipient"
 485 processing "warn" (TESTSUITE/test-config 32)
 486  search_open: pgsql "NULL"
 487    cached open
 488  search_find: file="NULL"
 489    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
 490  LRU list:
 491  internal_search_find: file="NULL"
 492    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N/test/CALLER/"
 493  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 494                               (tainted, quoted:pgsql)
 495  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N/test/CALLER/'
 496  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 497  PGSQL: no data found
 498  replacing old cache entry
 499  lookup failed
 500 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N/test/CALLER/      {select name from them where id = '${quote_pgsql:$local_part}'}}
 501                  = ok:
 502  search_open: pgsql "NULL"
 503    cached open
 504  search_find: file="NULL"
 505    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N"
 506  LRU list:
 507  internal_search_find: file="NULL"
 508    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N"
 509  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
 510                               (tainted, quoted:pgsql)
 511  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N'
 512  PGSQL using cached connection for localhost:PORT_N/test/CALLER
 513  PGSQL: no data found
 514  replacing old cache entry
 515  lookup failed
 516 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N    {select name from them where id = '${quote_pgsql:$local_part}'}}
 517                  = ok:
 518  search_open: pgsql "NULL"
 519    cached open
 520  search_find: file="NULL"
 521    key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
 522  LRU list:
 523  internal_search_find: file="NULL"
 524    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts=NULL
 525  database lookup required for servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'
 526                               (tainted, quoted:pgsql)
 527  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
 528  lookup deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 529 warn: condition test deferred in ACL "check_recipient"
 530 LOG: MAIN
 531   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 532 processing "warn" (TESTSUITE/test-config 41)
 533 check set acl_m0 = ok:   hostlist
 534 check hosts = net-pgsql;select * from them where id='${quote_pgsql:$local_part}'
 535 host in "net-pgsql;select * from them where id='c'"?
 536  list element: net-pgsql;select * from them where id='c'
 537  search_open: pgsql "NULL"
 538    cached open
 539  search_find: file="NULL"
 540    key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 541  LRU list:
 542  internal_search_find: file="NULL"
 543    type=pgsql key="select * from them where id='c'" opts=NULL
 544  cached data used for lookup of select * from them where id='c'
 545  lookup failed
 546 host in "net-pgsql;select * from them where id='c'"? no (end of list)
 547 warn: condition test failed in ACL "check_recipient"
 548 processing "warn" (TESTSUITE/test-config 44)
 549 check set acl_m0 = FAIL: hostlist
 550 check hosts = <& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
 551 host in "<& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
 552  list element: net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
 553  search_open: pgsql "NULL"
 554    cached open
 555  search_find: file="NULL"
 556    key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
 557  LRU list:
 558  internal_search_find: file="NULL"
 559    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts=NULL
 560  database lookup required for servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
 561                               (tainted, quoted:pgsql)
 562  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts 'NULL'
 563  lookup deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 564 host in "<& net-pgsql;servers=localhost::PORT_N/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'
 565 warn: condition test deferred in ACL "check_recipient"
 566 LOG: MAIN
 567   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 568 processing "warn" (TESTSUITE/test-config 49)
 569 check set acl_m0 = FAIL: hostlist
 570 check hosts = <& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
 571 host in "<& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
 572  list element: net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
 573  search_open: pgsql "NULL"
 574    cached open
 575  search_find: file="NULL"
 576    key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
 577  LRU list:
 578  internal_search_find: file="NULL"
 579    type=pgsql key=" select * from them where id='c'" opts="servers=localhost::PORT_N/test/CALLER/"
 580  database lookup required for  select * from them where id='c'
 581                               (tainted, quoted:pgsql)
 582  PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::PORT_N/test/CALLER/'
 583  lookup deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 584 host in "<& net-pgsql,servers=localhost::PORT_N/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'
 585 warn: condition test deferred in ACL "check_recipient"
 586 LOG: MAIN
 587   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test/CALLER/" is tainted
 588 processing "accept" (TESTSUITE/test-config 52)
 589 check domains = +local_domains
 590 d in "+local_domains"?
 591  list element: +local_domains
 592   start sublist local_domains
 593    d in "@"?
 594    ╎list element: @
 595    d in "@"? no (end of list)
 596   end sublist local_domains
 597 d in "+local_domains"? no (end of list)
 598 accept: condition test failed in ACL "check_recipient"
 599 processing "accept" (TESTSUITE/test-config 53)
 600 check hosts = +relay_hosts
 601 host in "+relay_hosts"?
 602  list element: +relay_hosts
 603   start sublist relay_hosts
 604   host in "net-pgsql;select * from them where id='10.0.0.0'"?
 605    ╎list element: net-pgsql;select * from them where id='10.0.0.0'
 606    ╎search_open: pgsql "NULL"
 607    ╎  cached open
 608    ╎search_find: file="NULL"
 609    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
 610    ╎LRU list:
 611    ╎internal_search_find: file="NULL"
 612    ╎  type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
 613    ╎cached data used for lookup of select * from them where id='10.0.0.0'
 614    ╎lookup failed
 615   host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 616   end sublist relay_hosts
 617 host in "+relay_hosts"? no (end of list)
 618 accept: condition test failed in ACL "check_recipient"
 619 processing "deny" (TESTSUITE/test-config 54)
 620   message: relay not permitted
 621 deny: condition test succeeded in ACL "check_recipient"
 622 end of ACL "check_recipient": DENY
 623 SMTP>> 550 relay not permitted
 624 LOG: MAIN REJECT
 625   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 626 SMTP<< quit
 627 SMTP>> 221 myhost.test.ex closing connection
 628 LOG: smtp_connection MAIN
 629   SMTP connection from (test) [10.0.0.0] closed by QUIT
 630 search_tidyup called
 631 close PGSQL connection: localhost:PORT_N/test/CALLER
 632 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 633 Exim version x.yz ....
 634 changed uid/gid: forcing real = effective
 635   uid=uuuu gid=CALLER_GID pid=p1237
 636 configuration file is TESTSUITE/test-config
 637 admin user
 638 changed uid/gid: privilege not needed
 639   uid=EXIM_UID gid=EXIM_GID pid=p1237
 640 seeking password data for user "CALLER": cache not available
 641 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 642 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 643 sender address = CALLER@myhost.test.ex
 644 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 645 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 646 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 647 Sender: CALLER@myhost.test.ex
 648 Recipients:
 649   CALLER
 650 search_tidyup called
 651 >>Headers received:
 652
 653 qualify & rewrite recipients list
 654 global rewrite rules
 655 rewrite headers
 656  rewrite_one_header: type=F:
 657    From: CALLER_NAME <CALLER@myhost.test.ex>
 658 search_tidyup called
 659 >>Headers after rewriting and local additions:
 660 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 661 F From: CALLER_NAME <CALLER@myhost.test.ex>
 662   Date: Tue, 2 Mar 1999 09:44:33 +0000
 663
 664 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 665 Data file written for message 10HmaX-0005vi-00
 666 >>Generated Received: header line
 667 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 668         (envelope-from <CALLER@myhost.test.ex>)
 669         id 10HmaX-0005vi-00
 670         for CALLER@myhost.test.ex;
 671         Tue, 2 Mar 1999 09:44:33 +0000
 672 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 673 DSN: **** SPOOL_OUT - address: <CALLER@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 674 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 675 Size of headers = sss
 676 LOG: MAIN
 677   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 678 created log directory TESTSUITE/spool/log
 679 search_tidyup called
 680 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 681 Exim version x.yz ....
 682 changed uid/gid: forcing real = effective
 683   uid=uuuu gid=EXIM_GID pid=p1238
 684 configuration file is TESTSUITE/test-config
 685 trusted user
 686 admin user
 687 dropping to exim gid; retaining priv uid
 688 seeking password data for user "CALLER": cache not available
 689 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 690 set_process_info: pppp delivering specified messages
 691 set_process_info: pppp delivering 10HmaX-0005vi-00
 692 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 693 reading spool file 10HmaX-0005vi-00-H
 694 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 695 sender_local=1 ident=CALLER
 696 Non-recipients:
 697  Empty Tree
 698 ---- End of tree ----
 699 recipients_count=1
 700 **** SPOOL_IN - No additional fields
 701 body_linecount=1 message_linecount=8
 702 DSN: set orcpt:   flags: 0x0
 703 Delivery address list:
 704   CALLER@myhost.test.ex
 705  locking TESTSUITE/spool/db/retry.lockfile
 706  locked  TESTSUITE/spool/db/retry.lockfile
 707  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 708  returned from EXIM_DBOPEN: (nil)
 709  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 710 no retry data available
 711 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 712 Considering: CALLER@myhost.test.ex
 713 unique = CALLER@myhost.test.ex
 714 no   domain  retry record
 715 no   address retry record
 716 CALLER@myhost.test.ex: queued for routing
 717 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 718 routing CALLER@myhost.test.ex
 719 --------> r1 router <--------
 720 local_part=CALLER domain=myhost.test.ex
 721 processing address_data
 722  search_open: pgsql "NULL"
 723  search_find: file="NULL"
 724    key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
 725  LRU list:
 726  internal_search_find: file="NULL"
 727    type=pgsql key="select name from them where id='ph10'" opts=NULL
 728  database lookup required for select name from them where id='ph10'
 729  PostgreSQL query: "select name from them where id='ph10'" opts 'NULL'
 730  PGSQL new connection: host=localhost port=PORT_N database=test user=CALLER
 731  creating new cache entry
 732  lookup yielded: Philip Hazel
 733 calling r1 router
 734 r1 router called for CALLER@myhost.test.ex
 735   domain = myhost.test.ex
 736 set transport t1
 737 queued for t1 transport: local_part = CALLER
 738 domain = myhost.test.ex
 739   errors_to=NULL
 740   domain_data=NULL local_part_data=NULL
 741 routed by r1 router
 742   envelope to: CALLER@myhost.test.ex
 743   transport: t1
 744 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 745 After routing:
 746   Local deliveries:
 747     CALLER@myhost.test.ex
 748   Remote deliveries:
 749   Failed addresses:
 750   Deferred addresses:
 751 search_tidyup called
 752 close PGSQL connection: localhost:PORT_N/test/CALLER
 753 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 754 --------> CALLER@myhost.test.ex <--------
 755  locking TESTSUITE/spool/db/retry.lockfile
 756  locked  TESTSUITE/spool/db/retry.lockfile
 757  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 758  returned from EXIM_DBOPEN: (nil)
 759  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 760 no retry data available
 761 search_tidyup called
 762 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 763   uid=CALLER_UID gid=CALLER_GID pid=p1239
 764   home=NULL current=/
 765 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 766 appendfile transport entered
 767  search_open: pgsql "NULL"
 768  search_find: file="NULL"
 769    key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
 770  LRU list:
 771  internal_search_find: file="NULL"
 772    type=pgsql key="select id from them where id='ph10'" opts=NULL
 773  database lookup required for select id from them where id='ph10'
 774  PostgreSQL query: "select id from them where id='ph10'" opts 'NULL'
 775  PGSQL new connection: host=localhost port=PORT_N database=test user=CALLER
 776  creating new cache entry
 777  lookup yielded: ph10
 778 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 779   file=TESTSUITE/test-mail/ph10 format=unix
 780   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 781   message_suffix=\n
 782   maildir_use_size_file=no
 783   locking by lockfile fcntl
 784 lock name: TESTSUITE/test-mail/ph10.lock
 785 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
 786 lock file created
 787 mailbox TESTSUITE/test-mail/ph10 is locked
 788 writing to file TESTSUITE/test-mail/ph10
 789 writing data block fd=dddd size=sss timeout=0
 790 cannot use sendfile for body: spoolfile not wireformat
 791 writing data block fd=dddd size=sss timeout=0
 792 writing data block fd=dddd size=sss timeout=0
 793 appendfile yields 0 with errno=dd more_errno=dd
 794 search_tidyup called
 795 close PGSQL connection: localhost:PORT_N/test/CALLER
 796 journalling CALLER@myhost.test.ex
 797 t1 transport returned OK for CALLER@myhost.test.ex
 798 post-process CALLER@myhost.test.ex (0)
 799 CALLER@myhost.test.ex delivered
 800 LOG: MAIN
 801   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 802 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 803 changed uid/gid: post-delivery tidying
 804   uid=EXIM_UID gid=EXIM_GID pid=p1238
 805 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 806 Processing retry items
 807 Succeeded addresses:
 808  CALLER@myhost.test.ex: no retry items
 809 Failed addresses:
 810 Deferred addresses:
 811 end of retry processing
 812 DSN: processing router : r1
 813 DSN: processing successful delivery address: CALLER@myhost.test.ex
 814 DSN: Sender_address: CALLER@myhost.test.ex
 815 DSN: orcpt: NULL  flags: 0x0
 816 DSN: envid: NULL  ret: 0
 817 DSN: Final recipient: CALLER@myhost.test.ex
 818 DSN: Remote SMTP server supports DSN: 0
 819 DSN: not sending DSN success message
 820 LOG: MAIN
 821   Completed
 822 end delivery of 10HmaX-0005vi-00
 823 search_tidyup called
 824 search_tidyup called
 825 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 826 search_tidyup called
 827 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 828 Exim version x.yz ....
 829 configuration file is TESTSUITE/test-config
 830 admin user
 831 dropping to exim gid; retaining priv uid
 832  search_open: pgsql "NULL"
 833  search_find: file="NULL"
 834    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
 835  LRU list:
 836  internal_search_find: file="NULL"
 837    type=pgsql key="select name from them where id='ph10';" opts=NULL
 838  database lookup required for select name from them where id='ph10';
 839  PostgreSQL query: "select name from them where id='ph10';" opts 'NULL'
 840  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 841  creating new cache entry
 842  lookup yielded: Philip Hazel
 843 search_tidyup called
 844 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 845 >>>>>>>>>>>>>>>> Exim pid=p1240 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 846
 847 ******** SERVER ********
 848 2017-07-30 18:51:05.712 [pppp] LOG:  redirecting log output to logging collector process
 849 2017-07-30 18:51:05.712 [pppp] HINT:  Future log output will appear in directory "log".