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 search_open: pgsql "NULL"
   9 search_find: file="NULL"
  10   key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
  11 LRU list:
  12 internal_search_find: file="NULL"
  13   type=pgsql key="select name from them where id='ph10';"
  14 database lookup required for select name from them where id='ph10';
  15 PostgreSQL query: select name from them where id='ph10';
  16 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
  17 lookup yielded: Philip Hazel
  18 search_open: pgsql "NULL"
  19   cached open
  20 search_find: file="NULL"
  21   key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
  22 LRU list:
  23 internal_search_find: file="NULL"
  24   type=pgsql key="select name from them where id='ph10';"
  25 cached data used for lookup of select name from them where id='ph10';
  26 lookup yielded: Philip Hazel
  27 search_open: pgsql "NULL"
  28   cached open
  29 search_find: file="NULL"
  30   key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0
  31 LRU list:
  32 internal_search_find: file="NULL"
  33   type=pgsql key="select name from them where id='xxxx';"
  34 database lookup required for select name from them where id='xxxx';
  35 PostgreSQL query: select name from them where id='xxxx';
  36 PGSQL using cached connection for localhost:1223/test/CALLER
  37 PGSQL: no data found
  38 lookup failed
  39 search_open: pgsql "NULL"
  40   cached open
  41 search_find: file="NULL"
  42   key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0
  43 LRU list:
  44 internal_search_find: file="NULL"
  45   type=pgsql key="select name from them where id='nothing';"
  46 database lookup required for select name from them where id='nothing';
  47 PostgreSQL query: select name from them where id='nothing';
  48 PGSQL using cached connection for localhost:1223/test/CALLER
  49 lookup yielded:
  50 search_open: pgsql "NULL"
  51   cached open
  52 search_find: file="NULL"
  53   key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0
  54 LRU list:
  55 internal_search_find: file="NULL"
  56   type=pgsql key="select id,name from them where id='nothing';"
  57 database lookup required for select id,name from them where id='nothing';
  58 PostgreSQL query: select id,name from them where id='nothing';
  59 PGSQL using cached connection for localhost:1223/test/CALLER
  60 lookup yielded: id=nothing name=""
  61 search_open: pgsql "NULL"
  62   cached open
  63 search_find: file="NULL"
  64   key="delete from them where id='nonexist';" partial=-1 affix=NULL starflags=0
  65 LRU list:
  66 internal_search_find: file="NULL"
  67   type=pgsql key="delete from them where id='nonexist';"
  68 database lookup required for delete from them where id='nonexist';
  69 PostgreSQL query: delete from them where id='nonexist';
  70 PGSQL using cached connection for localhost:1223/test/CALLER
  71 PGSQL: command does not return any data but was successful. Rows affected: 0
  72 lookup forced cache cleanup
  73 lookup yielded: 0
  74 search_open: pgsql "NULL"
  75   cached open
  76 search_find: file="NULL"
  77   key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0
  78 LRU list:
  79 internal_search_find: file="NULL"
  80   type=pgsql key="select * from them where id='quote2';"
  81 database lookup required for select * from them where id='quote2';
  82 PostgreSQL query: select * from them where id='quote2';
  83 PGSQL using cached connection for localhost:1223/test/CALLER
  84 lookup yielded: name="\"stquot" id=quote2
  85 search_open: pgsql "NULL"
  86   cached open
  87 search_find: file="NULL"
  88   key="select * from them where id='newline';" partial=-1 affix=NULL starflags=0
  89 LRU list:
  90 internal_search_find: file="NULL"
  91   type=pgsql key="select * from them where id='newline';"
  92 database lookup required for select * from them where id='newline';
  93 PostgreSQL query: select * from them where id='newline';
  94 PGSQL using cached connection for localhost:1223/test/CALLER
  95 lookup yielded: name="before\r
  96 after" id=newline
  97 search_open: pgsql "NULL"
  98   cached open
  99 search_find: file="NULL"
 100   key="select * from them where id='tab';" partial=-1 affix=NULL starflags=0
 101 LRU list:
 102 internal_search_find: file="NULL"
 103   type=pgsql key="select * from them where id='tab';"
 104 database lookup required for select * from them where id='tab';
 105 PostgreSQL query: select * from them where id='tab';
 106 PGSQL using cached connection for localhost:1223/test/CALLER
 107 lookup yielded: name="x x" id=tab
 108 search_open: pgsql "NULL"
 109   cached open
 110 search_find: file="NULL"
 111   key="select * from them where name='''stquot';" partial=-1 affix=NULL starflags=0
 112 LRU list:
 113 internal_search_find: file="NULL"
 114   type=pgsql key="select * from them where name='''stquot';"
 115 database lookup required for select * from them where name='''stquot';
 116 PostgreSQL query: select * from them where name='''stquot';
 117 PGSQL using cached connection for localhost:1223/test/CALLER
 118 lookup yielded: name='stquot id=quote1
 119 search_open: pgsql "NULL"
 120   cached open
 121 search_find: file="NULL"
 122   key="servers=x:localhost; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
 123 LRU list:
 124 internal_search_find: file="NULL"
 125   type=pgsql key="servers=x:localhost; select name from them where id='ph10';"
 126 database lookup required for servers=x:localhost; select name from them where id='ph10';
 127 PostgreSQL query: servers=x:localhost; select name from them where id='ph10';
 128 lookup deferred: PostgreSQL server "x" not found in pgsql_servers
 129 search_open: pgsql "NULL"
 130   cached open
 131 search_find: file="NULL"
 132   key="servers=localhost::1223:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
 133 LRU list:
 134 internal_search_find: file="NULL"
 135   type=pgsql key="servers=localhost::1223:x; select name from them where id='ph10';"
 136 database lookup required for servers=localhost::1223:x; select name from them where id='ph10';
 137 PostgreSQL query: servers=localhost::1223:x; select name from them where id='ph10';
 138 PGSQL using cached connection for localhost:1223/test/CALLER
 139 lookup yielded: Philip Hazel
 140 search_open: pgsql "NULL"
 141   cached open
 142 search_find: file="NULL"
 143   key="servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
 144 LRU list:
 145 internal_search_find: file="NULL"
 146   type=pgsql key="servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';"
 147 database lookup required for servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';
 148 PostgreSQL query: servers=localhost::1223/test/CALLER/:x; select name from them where id='ph10';
 149 PGSQL using cached connection for localhost:1223/test/CALLER
 150 lookup yielded: Philip Hazel
 151 search_open: pgsql "NULL"
 152   cached open
 153 search_find: file="NULL"
 154   key="servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
 155 LRU list:
 156 internal_search_find: file="NULL"
 157   type=pgsql key="servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';"
 158 database lookup required for servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';
 159 PostgreSQL query: servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';
 160 PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 161 lookup yielded: Philip Hazel
 162 search_open: pgsql "NULL"
 163   cached open
 164 search_find: file="NULL"
 165   key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0
 166 LRU list:
 167 internal_search_find: file="NULL"
 168   type=pgsql key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');"
 169 database lookup required for SELECT name FROM them WHERE id IN ('ph10', 'aaaa');
 170 PostgreSQL query: SELECT name FROM them WHERE id IN ('ph10', 'aaaa');
 171 PGSQL using cached connection for localhost:1223/test/CALLER
 172 lookup yielded: Philip Hazel
 173 Aristotle
 174 search_open: pgsql "NULL"
 175   cached open
 176 search_find: file="NULL"
 177   key="SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0
 178 LRU list:
 179 internal_search_find: file="NULL"
 180   type=pgsql key="SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');"
 181 database lookup required for SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');
 182 PostgreSQL query: SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');
 183 PGSQL using cached connection for localhost:1223/test/CALLER
 184 lookup yielded: name="Philip Hazel" id=ph10
 185 name=Aristotle id=aaaa
 186 search_open: pgsql "NULL"
 187   cached open
 188 search_find: file="NULL"
 189   key="delete from them where id='aaaa'" partial=-1 affix=NULL starflags=0
 190 LRU list:
 191 internal_search_find: file="NULL"
 192   type=pgsql key="delete from them where id='aaaa'"
 193 database lookup required for delete from them where id='aaaa'
 194 PostgreSQL query: delete from them where id='aaaa'
 195 PGSQL using cached connection for localhost:1223/test/CALLER
 196 PGSQL: command does not return any data but was successful. Rows affected: 1
 197 lookup forced cache cleanup
 198 lookup yielded: 1
 199 search_tidyup called
 200 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 201 close PGSQL connection: localhost:1223/test/CALLER
 202 >>>>>>>>>>>>>>>> Exim pid=pppp (main: expansion test) terminating with rc=0 >>>>>>>>>>>>>>>>
 203 Exim version x.yz ....
 204 changed uid/gid: forcing real = effective
 205   uid=uuuu gid=CALLER_GID pid=pppp
 206 configuration file is TESTSUITE/test-config
 207 admin user
 208 changed uid/gid: privilege not needed
 209   uid=EXIM_UID gid=EXIM_GID pid=pppp
 210 seeking password data for user "CALLER": cache not available
 211 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 212 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 213 sender address = CALLER@myhost.test.ex
 214 sender_fullhost = [10.0.0.0]
 215 sender_rcvhost = [10.0.0.0]
 216 host in hosts_connection_nolog? no (option unset)
 217 LOG: smtp_connection MAIN
 218   SMTP connection from [10.0.0.0]
 219 host in host_lookup? no (option unset)
 220 set_process_info: pppp handling incoming connection from [10.0.0.0]
 221 host in host_reject_connection? no (option unset)
 222 host in sender_unqualified_hosts? no (option unset)
 223 host in recipient_unqualified_hosts? no (option unset)
 224 host in helo_verify_hosts? no (option unset)
 225 host in helo_try_verify_hosts? no (option unset)
 226 host in helo_accept_junk_hosts? no (option unset)
 227 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 228 smtp_setup_msg entered
 229 SMTP<< mail from:<a@b>
 230 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 231 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 232 SMTP>> 250 OK
 233 SMTP<< rcpt to:<c@d>
 234 using ACL "check_recipient"
 235 processing "accept" (TESTSUITE/test-config 25)
 236 check domains = +local_domains
 237 d in "@"? no (end of list)
 238 d in "+local_domains"? no (end of list)
 239 accept: condition test failed in ACL "check_recipient"
 240 processing "accept" (TESTSUITE/test-config 26)
 241 check hosts = +relay_hosts
 242 search_open: pgsql "NULL"
 243 search_find: file="NULL"
 244   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0
 245 LRU list:
 246 internal_search_find: file="NULL"
 247   type=pgsql key="select * from them where id='10.0.0.0'"
 248 database lookup required for select * from them where id='10.0.0.0'
 249 PostgreSQL query: select * from them where id='10.0.0.0'
 250 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 251 PGSQL: no data found
 252 lookup failed
 253 host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 254 host in "+relay_hosts"? no (end of list)
 255 accept: condition test failed in ACL "check_recipient"
 256 processing "deny" (TESTSUITE/test-config 27)
 257   message: relay not permitted
 258 deny: condition test succeeded in ACL "check_recipient"
 259 end of ACL "check_recipient": DENY
 260 SMTP>> 550 relay not permitted
 261 LOG: MAIN REJECT
 262   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 263 SMTP<< rcpt to:<c@d>
 264 using ACL "check_recipient"
 265 processing "accept" (TESTSUITE/test-config 25)
 266 check domains = +local_domains
 267 d in "@"? no (end of list)
 268 d in "+local_domains"? no (end of list)
 269 accept: condition test failed in ACL "check_recipient"
 270 processing "accept" (TESTSUITE/test-config 26)
 271 check hosts = +relay_hosts
 272 search_open: pgsql "NULL"
 273   cached open
 274 search_find: file="NULL"
 275   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0
 276 LRU list:
 277 internal_search_find: file="NULL"
 278   type=pgsql key="select * from them where id='10.0.0.0'"
 279 cached data used for lookup of select * from them where id='10.0.0.0'
 280 lookup failed
 281 host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 282 host in "+relay_hosts"? no (end of list)
 283 accept: condition test failed in ACL "check_recipient"
 284 processing "deny" (TESTSUITE/test-config 27)
 285   message: relay not permitted
 286 deny: condition test succeeded in ACL "check_recipient"
 287 end of ACL "check_recipient": DENY
 288 SMTP>> 550 relay not permitted
 289 LOG: MAIN REJECT
 290   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 291 SMTP<< quit
 292 SMTP>> 221 myhost.test.ex closing connection
 293 LOG: smtp_connection MAIN
 294   SMTP connection from [10.0.0.0] closed by QUIT
 295 search_tidyup called
 296 close PGSQL connection: localhost:1223/test/CALLER
 297 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 298 Exim version x.yz ....
 299 changed uid/gid: forcing real = effective
 300   uid=uuuu gid=CALLER_GID pid=pppp
 301 configuration file is TESTSUITE/test-config
 302 admin user
 303 changed uid/gid: privilege not needed
 304   uid=EXIM_UID gid=EXIM_GID pid=pppp
 305 seeking password data for user "CALLER": cache not available
 306 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 307 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 308 sender address = CALLER@myhost.test.ex
 309 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 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 Sender: CALLER@myhost.test.ex
 313 Recipients:
 314   CALLER
 315 search_tidyup called
 316 >>Headers received:
 317
 318 rewrite_one_header: type=F:
 319   From: CALLER_NAME <CALLER@myhost.test.ex>
 320 search_tidyup called
 321 >>Headers after rewriting and local additions:
 322 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 323 F From: CALLER_NAME <CALLER@myhost.test.ex>
 324   Date: Tue, 2 Mar 1999 09:44:33 +0000
 325
 326 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 327 Data file written for message 10HmaX-0005vi-00
 328 >>Generated Received: header line
 329 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 330         (envelope-from <CALLER@myhost.test.ex>)
 331         id 10HmaX-0005vi-00
 332         for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 333 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 334 DSN: Write SPOOL :-dsn_envid NULL
 335 DSN: Write SPOOL :-dsn_ret 0
 336 DSN: Flags :0
 337 DSN: **** SPOOL_OUT - address: |CALLER@myhost.test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 338 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 339 Size of headers = sss
 340 LOG: MAIN
 341   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 342 created log directory TESTSUITE/spool/log
 343 search_tidyup called
 344 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -odi -Mc 10HmaX-0005vi-00
 345 Exim version x.yz ....
 346 changed uid/gid: forcing real = effective
 347   uid=uuuu gid=EXIM_GID pid=pppp
 348 configuration file is TESTSUITE/test-config
 349 trusted user
 350 admin user
 351 seeking password data for user "CALLER": cache not available
 352 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 353 set_process_info: pppp delivering specified messages
 354 set_process_info: pppp delivering 10HmaX-0005vi-00
 355 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 356 reading spool file 10HmaX-0005vi-00-H
 357 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 358 sender_local=1 ident=CALLER
 359 Non-recipients:
 360 Empty Tree
 361 ---- End of tree ----
 362 recipients_count=1
 363 **** SPOOL_IN - No additional fields
 364 body_linecount=1 message_linecount=7
 365 DSN: set orcpt:   flags: 0
 366 Delivery address list:
 367   CALLER@myhost.test.ex
 368  locking TESTSUITE/spool/db/retry.lockfile
 369  locked  TESTSUITE/spool/db/retry.lockfile
 370  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 371  returned from EXIM_DBOPEN: (nil)
 372  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 373  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
 374 no retry data available
 375 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 376 Considering: CALLER@myhost.test.ex
 377 unique = CALLER@myhost.test.ex
 378 no   domain  retry record
 379 no   address retry record
 380 CALLER@myhost.test.ex: queued for routing
 381 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 382 routing CALLER@myhost.test.ex
 383 --------> r1 router <--------
 384 local_part=CALLER domain=myhost.test.ex
 385 processing address_data
 386 search_open: pgsql "NULL"
 387 search_find: file="NULL"
 388   key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0
 389 LRU list:
 390 internal_search_find: file="NULL"
 391   type=pgsql key="select name from them where id='ph10'"
 392 database lookup required for select name from them where id='ph10'
 393 PostgreSQL query: select name from them where id='ph10'
 394 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 395 lookup yielded: Philip Hazel
 396 calling r1 router
 397 r1 router called for CALLER@myhost.test.ex
 398   domain = myhost.test.ex
 399 set transport t1
 400 queued for t1 transport: local_part = CALLER
 401 domain = myhost.test.ex
 402   errors_to=NULL
 403   domain_data=NULL localpart_data=NULL
 404 routed by r1 router
 405   envelope to: CALLER@myhost.test.ex
 406   transport: t1
 407 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 408 After routing:
 409   Local deliveries:
 410     CALLER@myhost.test.ex
 411   Remote deliveries:
 412   Failed addresses:
 413   Deferred addresses:
 414 search_tidyup called
 415 close PGSQL connection: localhost:1223/test/CALLER
 416 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 417 --------> CALLER@myhost.test.ex <--------
 418  locking TESTSUITE/spool/db/retry.lockfile
 419  locked  TESTSUITE/spool/db/retry.lockfile
 420  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 421  returned from EXIM_DBOPEN: (nil)
 422  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 423 no retry data available
 424 search_tidyup called
 425 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 426   uid=CALLER_UID gid=CALLER_GID pid=pppp
 427   home=NULL current=/
 428 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 429 appendfile transport entered
 430 search_open: pgsql "NULL"
 431 search_find: file="NULL"
 432   key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0
 433 LRU list:
 434 internal_search_find: file="NULL"
 435   type=pgsql key="select id from them where id='ph10'"
 436 database lookup required for select id from them where id='ph10'
 437 PostgreSQL query: select id from them where id='ph10'
 438 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 439 lookup yielded: ph10
 440 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 441   file=TESTSUITE/test-mail/ph10 format=unix
 442   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 443   message_suffix=\n
 444   maildir_use_size_file=no
 445   locking by lockfile fcntl
 446 lock name: TESTSUITE/test-mail/ph10.lock
 447 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
 448 lock file created
 449 mailbox TESTSUITE/test-mail/ph10 is locked
 450 writing to file TESTSUITE/test-mail/ph10
 451 writing data block fd=dddd size=sss timeout=0
 452 cannot use sendfile for body: spoolfile not wireformat
 453 writing data block fd=dddd size=sss timeout=0
 454 writing data block fd=dddd size=sss timeout=0
 455 appendfile yields 0 with errno=dd more_errno=dd
 456 search_tidyup called
 457 close PGSQL connection: localhost:1223/test/CALLER
 458 journalling CALLER@myhost.test.ex
 459 t1 transport returned OK for CALLER@myhost.test.ex
 460 post-process CALLER@myhost.test.ex (0)
 461 CALLER@myhost.test.ex delivered
 462 LOG: MAIN
 463   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 464 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 465 changed uid/gid: post-delivery tidying
 466   uid=EXIM_UID gid=EXIM_GID pid=pppp
 467 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 468 Processing retry items
 469 Succeeded addresses:
 470  CALLER@myhost.test.ex: no retry items
 471 Failed addresses:
 472 Deferred addresses:
 473 end of retry processing
 474 DSN: processing router : r1
 475 DSN: processing successful delivery address: CALLER@myhost.test.ex
 476 DSN: Sender_address: CALLER@myhost.test.ex
 477 DSN: orcpt: NULL  flags: 0
 478 DSN: envid: NULL  ret: 0
 479 DSN: Final recipient: CALLER@myhost.test.ex
 480 DSN: Remote SMTP server supports DSN: 0
 481 DSN: not sending DSN success message
 482 LOG: MAIN
 483   Completed
 484 end delivery of 10HmaX-0005vi-00
 485 search_tidyup called
 486 search_tidyup called
 487 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 488 search_tidyup called
 489 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 490 Exim version x.yz ....
 491 configuration file is TESTSUITE/test-config
 492 admin user
 493 search_open: pgsql "NULL"
 494 search_find: file="NULL"
 495   key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
 496 LRU list:
 497 internal_search_find: file="NULL"
 498   type=pgsql key="select name from them where id='ph10';"
 499 database lookup required for select name from them where id='ph10';
 500 PostgreSQL query: select name from them where id='ph10';
 501 PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 502 lookup yielded: Philip Hazel
 503 search_tidyup called
 504 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 505 >>>>>>>>>>>>>>>> Exim pid=pppp (main: expansion test) terminating with rc=0 >>>>>>>>>>>>>>>>
 506
 507 ******** SERVER ********