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