test/stderr/2620

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