test/stderr/9200

   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_tidyup called
 163 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 164 close PGSQL connection: localhost:1223/test/CALLER
 165 >>>>>>>>>>>>>>>> Exim pid=pppp (main: expansion test) terminating with rc=0 >>>>>>>>>>>>>>>>
 166 Exim version x.yz ....
 167 changed uid/gid: forcing real = effective
 168   uid=uuuu gid=CALLER_GID pid=pppp
 169 configuration file is TESTSUITE/test-config
 170 admin user
 171 changed uid/gid: privilege not needed
 172   uid=EXIM_UID gid=EXIM_GID pid=pppp
 173 seeking password data for user "CALLER": cache not available
 174 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 175 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 176 sender address = CALLER@myhost.test.ex
 177 sender_fullhost = [10.0.0.0]
 178 sender_rcvhost = [10.0.0.0]
 179 host in hosts_connection_nolog? no (option unset)
 180 LOG: smtp_connection MAIN
 181   SMTP connection from [10.0.0.0]
 182 host in host_lookup? no (option unset)
 183 set_process_info: pppp handling incoming connection from [10.0.0.0]
 184 host in host_reject_connection? no (option unset)
 185 host in sender_unqualified_hosts? no (option unset)
 186 host in recipient_unqualified_hosts? no (option unset)
 187 host in helo_verify_hosts? no (option unset)
 188 host in helo_try_verify_hosts? no (option unset)
 189 host in helo_accept_junk_hosts? no (option unset)
 190 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 191 smtp_setup_msg entered
 192 SMTP<< mail from:<a@b>
 193 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 194 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 195 SMTP>> 250 OK
 196 SMTP<< rcpt to:<c@d>
 197 using ACL "check_recipient"
 198 processing "accept"
 199 check domains = +local_domains
 200 d in "@"? no (end of list)
 201 d in "+local_domains"? no (end of list)
 202 accept: condition test failed in ACL "check_recipient"
 203 processing "accept"
 204 check hosts = +relay_hosts
 205 search_open: pgsql "NULL"
 206 search_find: file="NULL"
 207   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0
 208 LRU list:
 209 internal_search_find: file="NULL"
 210   type=pgsql key="select * from them where id='10.0.0.0'"
 211 database lookup required for select * from them where id='10.0.0.0'
 212 PostgreSQL query: select * from them where id='10.0.0.0'
 213 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 214 PGSQL: no data found
 215 lookup failed
 216 host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 217 host in "+relay_hosts"? no (end of list)
 218 accept: condition test failed in ACL "check_recipient"
 219 processing "deny"
 220   message: relay not permitted
 221 deny: condition test succeeded in ACL "check_recipient"
 222 end of ACL "check_recipient": DENY
 223 SMTP>> 550 relay not permitted
 224 LOG: MAIN REJECT
 225   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 226 SMTP<< rcpt to:<c@d>
 227 using ACL "check_recipient"
 228 processing "accept"
 229 check domains = +local_domains
 230 d in "@"? no (end of list)
 231 d in "+local_domains"? no (end of list)
 232 accept: condition test failed in ACL "check_recipient"
 233 processing "accept"
 234 check hosts = +relay_hosts
 235 search_open: pgsql "NULL"
 236   cached open
 237 search_find: file="NULL"
 238   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0
 239 LRU list:
 240 internal_search_find: file="NULL"
 241   type=pgsql key="select * from them where id='10.0.0.0'"
 242 cached data used for lookup of select * from them where id='10.0.0.0'
 243 lookup failed
 244 host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
 245 host in "+relay_hosts"? no (end of list)
 246 accept: condition test failed in ACL "check_recipient"
 247 processing "deny"
 248   message: relay not permitted
 249 deny: condition test succeeded in ACL "check_recipient"
 250 end of ACL "check_recipient": DENY
 251 SMTP>> 550 relay not permitted
 252 LOG: MAIN REJECT
 253   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 254 SMTP<< quit
 255 SMTP>> 221 myhost.test.ex closing connection
 256 LOG: smtp_connection MAIN
 257   SMTP connection from [10.0.0.0] closed by QUIT
 258 search_tidyup called
 259 close PGSQL connection: localhost:1223/test/CALLER
 260 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 261 Exim version x.yz ....
 262 changed uid/gid: forcing real = effective
 263   uid=uuuu gid=CALLER_GID pid=pppp
 264 configuration file is TESTSUITE/test-config
 265 admin user
 266 changed uid/gid: privilege not needed
 267   uid=EXIM_UID gid=EXIM_GID pid=pppp
 268 seeking password data for user "CALLER": cache not available
 269 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 270 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 271 sender address = CALLER@myhost.test.ex
 272 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 273 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 274 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 275 Sender: CALLER@myhost.test.ex
 276 Recipients:
 277   CALLER
 278 search_tidyup called
 279 >>Headers received:
 280
 281 rewrite_one_header: type=F:
 282   From: CALLER_NAME <CALLER@myhost.test.ex>
 283 search_tidyup called
 284 >>Headers after rewriting and local additions:
 285 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 286 F From: CALLER_NAME <CALLER@myhost.test.ex>
 287   Date: Tue, 2 Mar 1999 09:44:33 +0000
 288
 289 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 290 Data file written for message 10HmaX-0005vi-00
 291 >>Generated Received: header line
 292 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 293         (envelope-from <CALLER@myhost.test.ex>)
 294         id 10HmaX-0005vi-00
 295         for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 296 calling local_scan(); timeout=300
 297 local_scan() returned 0 NULL
 298 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
 299 DSN: Write SPOOL :-dsn_envid NULL
 300 DSN: Write SPOOL :-dsn_ret 0
 301 DSN: Flags :0
 302 DSN: **** SPOOL_OUT - address: |CALLER@myhost.test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 303 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 304 Size of headers = sss
 305 LOG: MAIN
 306   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 307 created log directory TESTSUITE/spool/log
 308 search_tidyup called
 309 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
 310 Exim version x.yz ....
 311 changed uid/gid: forcing real = effective
 312   uid=uuuu gid=EXIM_GID pid=pppp
 313 configuration file is TESTSUITE/test-config
 314 trusted user
 315 admin user
 316 seeking password data for user "CALLER": cache not available
 317 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 318 set_process_info: pppp delivering specified messages
 319 set_process_info: pppp delivering 10HmaX-0005vi-00
 320 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 321 reading spool file 10HmaX-0005vi-00-H
 322 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 323 sender_local=1 ident=CALLER
 324 Non-recipients:
 325 Empty Tree
 326 ---- End of tree ----
 327 recipients_count=1
 328 **** SPOOL_IN - No additional fields
 329 body_linecount=1 message_linecount=7
 330 DSN: set orcpt: NULL  flags: 0
 331 Delivery address list:
 332   CALLER@myhost.test.ex
 333 locking TESTSUITE/spool/db/retry.lockfile
 334 locked  TESTSUITE/spool/db/retry.lockfile
 335 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 336 returned from EXIM_DBOPEN: (nil)
 337 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 338 failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
 339 no retry data available
 340 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 341 Considering: CALLER@myhost.test.ex
 342 unique = CALLER@myhost.test.ex
 343 no domain retry record
 344 no address retry record
 345 CALLER@myhost.test.ex: queued for routing
 346 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 347 routing CALLER@myhost.test.ex
 348 --------> r1 router <--------
 349 local_part=CALLER domain=myhost.test.ex
 350 processing address_data
 351 search_open: pgsql "NULL"
 352 search_find: file="NULL"
 353   key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0
 354 LRU list:
 355 internal_search_find: file="NULL"
 356   type=pgsql key="select name from them where id='ph10'"
 357 database lookup required for select name from them where id='ph10'
 358 PostgreSQL query: select name from them where id='ph10'
 359 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 360 lookup yielded: Philip Hazel
 361 calling r1 router
 362 r1 router called for CALLER@myhost.test.ex
 363   domain = myhost.test.ex
 364 set transport t1
 365 queued for t1 transport: local_part = CALLER
 366 domain = myhost.test.ex
 367   errors_to=NULL
 368   domain_data=NULL localpart_data=NULL
 369 routed by r1 router
 370   envelope to: CALLER@myhost.test.ex
 371   transport: t1
 372 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 373 After routing:
 374   Local deliveries:
 375     CALLER@myhost.test.ex
 376   Remote deliveries:
 377   Failed addresses:
 378   Deferred addresses:
 379 search_tidyup called
 380 close PGSQL connection: localhost:1223/test/CALLER
 381 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 382 --------> CALLER@myhost.test.ex <--------
 383 locking TESTSUITE/spool/db/retry.lockfile
 384 locked  TESTSUITE/spool/db/retry.lockfile
 385 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 386 returned from EXIM_DBOPEN: (nil)
 387 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 388 no retry data available
 389 search_tidyup called
 390 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 391   uid=CALLER_UID gid=CALLER_GID pid=pppp
 392   home=NULL current=/
 393 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 394 appendfile transport entered
 395 search_open: pgsql "NULL"
 396 search_find: file="NULL"
 397   key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0
 398 LRU list:
 399 internal_search_find: file="NULL"
 400   type=pgsql key="select id from them where id='ph10'"
 401 database lookup required for select id from them where id='ph10'
 402 PostgreSQL query: select id from them where id='ph10'
 403 PGSQL new connection: host=localhost port=1223 database=test user=CALLER
 404 lookup yielded: ph10
 405 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 406   file=TESTSUITE/test-mail/ph10 format=unix
 407   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 408   message_suffix=\n
 409   maildir_use_size_file=no
 410   locking by lockfile fcntl
 411 lock name: TESTSUITE/test-mail/ph10.lock
 412 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
 413 lock file created
 414 mailbox TESTSUITE/test-mail/ph10 is locked
 415 writing to file TESTSUITE/test-mail/ph10
 416 writing data block fd=dddd size=sss timeout=0
 417 cannot use sendfile for body: spoolfile not wireformat
 418 writing data block fd=dddd size=sss timeout=0
 419 writing data block fd=dddd size=sss timeout=0
 420 appendfile yields 0 with errno=dd more_errno=dd
 421 search_tidyup called
 422 close PGSQL connection: localhost:1223/test/CALLER
 423 journalling CALLER@myhost.test.ex
 424 t1 transport returned OK for CALLER@myhost.test.ex
 425 post-process CALLER@myhost.test.ex (0)
 426 CALLER@myhost.test.ex delivered
 427 LOG: MAIN
 428   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 429 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 430 changed uid/gid: post-delivery tidying
 431   uid=EXIM_UID gid=EXIM_GID pid=pppp
 432 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 433 Processing retry items
 434 Succeeded addresses:
 435  CALLER@myhost.test.ex: no retry items
 436 Failed addresses:
 437 Deferred addresses:
 438 end of retry processing
 439 DSN: processing router : r1
 440 DSN: processing successful delivery address: CALLER@myhost.test.ex
 441 DSN: Sender_address: CALLER@myhost.test.ex
 442 DSN: orcpt: NULL  flags: 0
 443 DSN: envid: NULL  ret: 0
 444 DSN: Final recipient: CALLER@myhost.test.ex
 445 DSN: Remote SMTP server supports DSN: 0
 446 DSN: not sending DSN success message
 447 LOG: MAIN
 448   Completed
 449 end delivery of 10HmaX-0005vi-00
 450 search_tidyup called
 451 search_tidyup called
 452 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 453 search_tidyup called
 454 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 455 Exim version x.yz ....
 456 configuration file is TESTSUITE/test-config
 457 admin user
 458 search_open: pgsql "NULL"
 459 search_find: file="NULL"
 460   key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0
 461 LRU list:
 462 internal_search_find: file="NULL"
 463   type=pgsql key="select name from them where id='ph10';"
 464 database lookup required for select name from them where id='ph10';
 465 PostgreSQL query: select name from them where id='ph10';
 466 PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
 467 lookup yielded: Philip Hazel
 468 search_tidyup called
 469 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
 470 >>>>>>>>>>>>>>>> Exim pid=pppp (main: expansion test) terminating with rc=0 >>>>>>>>>>>>>>>>
 471
 472 ******** SERVER ********
 473 LOG:  redirecting log output to logging collector process
 474 HINT:  Future log output will appear in directory "pg_log".