test/stderr/9100

   1 Exim version x.yz ....
   2 configuration file is TESTSUITE/test-config
   3 admin user
   4 search_open: mysql "NULL"
   5 search_find: file="NULL"
   6   key="select name from them where id='CALLER';" partial=-1 affix=NULL starflags=0
   7 LRU list:
   8 internal_search_find: file="NULL"
   9   type=mysql key="select name from them where id='CALLER';"
  10 database lookup required for select name from them where id='CALLER';
  11 MySQL query: select name from them where id='CALLER';
  12 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
  13 lookup yielded: Philip Hazel
  14 search_open: mysql "NULL"
  15   cached open
  16 search_find: file="NULL"
  17   key="select name from them where id='CALLER';" partial=-1 affix=NULL starflags=0
  18 LRU list:
  19 internal_search_find: file="NULL"
  20   type=mysql key="select name from them where id='CALLER';"
  21 cached data used for lookup of select name from them where id='CALLER';
  22 lookup yielded: Philip Hazel
  23 search_open: mysql "NULL"
  24   cached open
  25 search_find: file="NULL"
  26   key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0
  27 LRU list:
  28 internal_search_find: file="NULL"
  29   type=mysql key="select name from them where id='xxxx';"
  30 database lookup required for select name from them where id='xxxx';
  31 MySQL query: select name from them where id='xxxx';
  32 MYSQL using cached connection for localhost/test/root
  33 MYSQL: no data found
  34 lookup failed
  35 search_open: mysql "NULL"
  36   cached open
  37 search_find: file="NULL"
  38   key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0
  39 LRU list:
  40 internal_search_find: file="NULL"
  41   type=mysql key="select name from them where id='nothing';"
  42 database lookup required for select name from them where id='nothing';
  43 MySQL query: select name from them where id='nothing';
  44 MYSQL using cached connection for localhost/test/root
  45 MYSQL: no data found
  46 lookup failed
  47 search_open: mysql "NULL"
  48   cached open
  49 search_find: file="NULL"
  50   key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0
  51 LRU list:
  52 internal_search_find: file="NULL"
  53   type=mysql key="select id,name from them where id='nothing';"
  54 database lookup required for select id,name from them where id='nothing';
  55 MySQL query: select id,name from them where id='nothing';
  56 MYSQL using cached connection for localhost/test/root
  57 lookup yielded: id=nothing name=""
  58 search_open: mysql "NULL"
  59   cached open
  60 search_find: file="NULL"
  61   key="delete from them where id='nonexist';" partial=-1 affix=NULL starflags=0
  62 LRU list:
  63 internal_search_find: file="NULL"
  64   type=mysql key="delete from them where id='nonexist';"
  65 database lookup required for delete from them where id='nonexist';
  66 MySQL query: delete from them where id='nonexist';
  67 MYSQL using cached connection for localhost/test/root
  68 MYSQL: query was not one that returns data
  69 lookup forced cache cleanup
  70 lookup yielded: 0
  71 search_open: mysql "NULL"
  72   cached open
  73 search_find: file="NULL"
  74   key="select * from them where id='quote';" partial=-1 affix=NULL starflags=0
  75 LRU list:
  76 internal_search_find: file="NULL"
  77   type=mysql key="select * from them where id='quote';"
  78 database lookup required for select * from them where id='quote';
  79 MySQL query: select * from them where id='quote';
  80 MYSQL using cached connection for localhost/test/root
  81 lookup yielded: id=quote name="\"Q\" Oted" home=/home/quoted mail=quoted@xxx
  82 search_open: mysql "NULL"
  83   cached open
  84 search_find: file="NULL"
  85   key="select * from them where id='filter';" partial=-1 affix=NULL starflags=0
  86 LRU list:
  87 internal_search_find: file="NULL"
  88   type=mysql key="select * from them where id='filter';"
  89 database lookup required for select * from them where id='filter';
  90 MySQL query: select * from them where id='filter';
  91 MYSQL using cached connection for localhost/test/root
  92 lookup yielded: id=filter name=filter home=filter mail="#Exim filter
  93 deliver postmaster"
  94 search_open: mysql "NULL"
  95   cached open
  96 search_find: file="NULL"
  97   key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0
  98 LRU list:
  99 internal_search_find: file="NULL"
 100   type=mysql key="select * from them where id='quote2';"
 101 database lookup required for select * from them where id='quote2';
 102 MySQL query: select * from them where id='quote2';
 103 MYSQL using cached connection for localhost/test/root
 104 lookup yielded: id=quote2 name="\"qnosp" home=/home/q2 mail=""
 105 search_open: mysql "NULL"
 106   cached open
 107 search_find: file="NULL"
 108   key="select * from them where id='nlonly';" partial=-1 affix=NULL starflags=0
 109 LRU list:
 110 internal_search_find: file="NULL"
 111   type=mysql key="select * from them where id='nlonly';"
 112 database lookup required for select * from them where id='nlonly';
 113 MySQL query: select * from them where id='nlonly';
 114 MYSQL using cached connection for localhost/test/root
 115 lookup yielded: id=nlonly name=filter home=filter mail="Before
 116 After"
 117 search_open: mysql "NULL"
 118   cached open
 119 search_find: file="NULL"
 120   key="servers=x:localhost; select name from them where id='CALLER';" partial=-1 affix=NULL starflags=0
 121 LRU list:
 122 internal_search_find: file="NULL"
 123   type=mysql key="servers=x:localhost; select name from them where id='CALLER';"
 124 database lookup required for servers=x:localhost; select name from them where id='CALLER';
 125 MySQL query: servers=x:localhost; select name from them where id='CALLER';
 126 lookup deferred: MySQL server "x" not found in mysql_servers
 127 search_open: mysql "NULL"
 128   cached open
 129 search_find: file="NULL"
 130   key="servers=localhost:x; select name from them where id='CALLER';" partial=-1 affix=NULL starflags=0
 131 LRU list:
 132 internal_search_find: file="NULL"
 133   type=mysql key="servers=localhost:x; select name from them where id='CALLER';"
 134 database lookup required for servers=localhost:x; select name from them where id='CALLER';
 135 MySQL query: servers=localhost:x; select name from them where id='CALLER';
 136 MYSQL using cached connection for localhost/test/root
 137 lookup yielded: Philip Hazel
 138 search_open: mysql "NULL"
 139   cached open
 140 search_find: file="NULL"
 141   key="servers=localhost/test/root/:x; select name from them where id='CALLER';" partial=-1 affix=NULL starflags=0
 142 LRU list:
 143 internal_search_find: file="NULL"
 144   type=mysql key="servers=localhost/test/root/:x; select name from them where id='CALLER';"
 145 database lookup required for servers=localhost/test/root/:x; select name from them where id='CALLER';
 146 MySQL query: servers=localhost/test/root/:x; select name from them where id='CALLER';
 147 MYSQL using cached connection for localhost/test/root
 148 lookup yielded: Philip Hazel
 149 search_open: mysql "NULL"
 150   cached open
 151 search_find: file="NULL"
 152   key="servers=HOST_IPV4/test/root/:localhost; select name from them where id='CALLER';" partial=-1 affix=NULL starflags=0
 153 LRU list:
 154 internal_search_find: file="NULL"
 155   type=mysql key="servers=HOST_IPV4/test/root/:localhost; select name from them where id='CALLER';"
 156 database lookup required for servers=HOST_IPV4/test/root/:localhost; select name from them where id='CALLER';
 157 MySQL query: servers=HOST_IPV4/test/root/:localhost; select name from them where id='CALLER';
 158 MYSQL new connection: host=HOST_IPV4 port=0 socket=NULL database=test user=root
 159 MYSQL connection failed: Unknown MySQL server host 'HOST_IPV4' (1)
 160 MYSQL using cached connection for localhost/test/root
 161 lookup yielded: Philip Hazel
 162 search_tidyup called
 163 close MYSQL connection: localhost/test/root
 164 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 165 Exim version x.yz ....
 166 changed uid/gid: forcing real = effective
 167   uid=uuuu gid=CALLER_GID pid=pppp
 168 configuration file is TESTSUITE/test-config
 169 admin user
 170 changed uid/gid: privilege not needed
 171   uid=EXIM_UID gid=EXIM_GID pid=pppp
 172 seeking password data for user "CALLER": cache not available
 173 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 174 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 175 sender address = CALLER@myhost.test.ex
 176 sender_fullhost = [10.0.0.0]
 177 sender_rcvhost = [10.0.0.0]
 178 host in hosts_connection_nolog? no (option unset)
 179 LOG: smtp_connection MAIN
 180   SMTP connection from [10.0.0.0]
 181 host in host_lookup? no (option unset)
 182 set_process_info: pppp handling incoming connection from [10.0.0.0]
 183 host in host_reject_connection? no (option unset)
 184 host in sender_unqualified_hosts? no (option unset)
 185 host in recipient_unqualified_hosts? no (option unset)
 186 host in helo_verify_hosts? no (option unset)
 187 host in helo_try_verify_hosts? no (option unset)
 188 host in helo_accept_junk_hosts? no (option unset)
 189 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 190 smtp_setup_msg entered
 191 SMTP<< mail from:<a@b>
 192 SMTP>> 250 OK
 193 SMTP<< rcpt to:<c@d>
 194 using ACL "check_recipient"
 195 processing "accept"
 196 check domains = +local_domains
 197 d in "@"? no (end of list)
 198 d in "+local_domains"? no (end of list)
 199 accept: condition test failed
 200 processing "accept"
 201 check hosts = +relay_hosts
 202 search_open: mysql "NULL"
 203 search_find: file="NULL"
 204   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0
 205 LRU list:
 206 internal_search_find: file="NULL"
 207   type=mysql key="select * from them where id='10.0.0.0'"
 208 database lookup required for select * from them where id='10.0.0.0'
 209 MySQL query: select * from them where id='10.0.0.0'
 210 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
 211 MYSQL: no data found
 212 lookup failed
 213 host in "net-mysql;select * from them where id='10.0.0.0'"? no (end of list)
 214 host in "+relay_hosts"? no (end of list)
 215 accept: condition test failed
 216 processing "deny"
 217 deny: condition test succeeded
 218 SMTP>> 550 relay not permitted
 219 LOG: MAIN REJECT
 220   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 221 SMTP<< quit
 222 SMTP>> 221 myhost.test.ex closing connection
 223 LOG: smtp_connection MAIN
 224   SMTP connection from [10.0.0.0] closed by QUIT
 225 search_tidyup called
 226 close MYSQL connection: localhost/test/root
 227 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 228 Exim version x.yz ....
 229 changed uid/gid: forcing real = effective
 230   uid=uuuu gid=CALLER_GID pid=pppp
 231 configuration file is TESTSUITE/test-config
 232 admin user
 233 changed uid/gid: privilege not needed
 234   uid=EXIM_UID gid=EXIM_GID pid=pppp
 235 seeking password data for user "CALLER": cache not available
 236 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 237 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 238 sender address = CALLER@myhost.test.ex
 239 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 240 Sender: CALLER@myhost.test.ex
 241 Recipients:
 242   CALLER
 243 search_tidyup called
 244 >>Headers received:
 245
 246 rewrite_one_header: type=F:
 247   From: CALLER_NAME <CALLER@myhost.test.ex>
 248 search_tidyup called
 249 >>Headers after rewriting and local additions:
 250 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 251 F From: CALLER_NAME <CALLER@myhost.test.ex>
 252   Date: Tue, 2 Mar 1999 09:44:33 +0000
 253
 254 Data file written for message 10HmaX-0005vi-00
 255 >>Generated Received: header line
 256 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 257         (envelope-from <CALLER@myhost.test.ex>)
 258         id 10HmaX-0005vi-00
 259         for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 260 calling local_scan(); timeout=300
 261 local_scan() returned 0 NULL
 262 Writing spool header file
 263 Size of headers = sss
 264 LOG: MAIN
 265   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 266 created log directory TESTSUITE/spool/log
 267 search_tidyup called
 268 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
 269 Exim version x.yz ....
 270 changed uid/gid: forcing real = effective
 271   uid=uuuu gid=EXIM_GID pid=pppp
 272 configuration file is TESTSUITE/test-config
 273 trusted user
 274 admin user
 275 skipping ACL configuration - not needed
 276 seeking password data for user "CALLER": cache not available
 277 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 278 set_process_info: pppp delivering specified messages
 279 set_process_info: pppp delivering 10HmaX-0005vi-00
 280 reading spool file 10HmaX-0005vi-00-H
 281 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 282 sender_local=1 ident=CALLER
 283 Non-recipients:
 284 Empty Tree
 285 ---- End of tree ----
 286 recipients_count=1
 287 body_linecount=1 message_linecount=7
 288 Delivery address list:
 289   CALLER@myhost.test.ex
 290 locking TESTSUITE/spool/db/retry.lockfile
 291 locked TESTSUITE/spool/db/retry.lockfile
 292 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 293 returned from EXIM_DBOPEN
 294 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 295 no retry data available
 296 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 297 Considering: CALLER@myhost.test.ex
 298 unique = CALLER@myhost.test.ex
 299 no domain retry record
 300 no address retry record
 301 CALLER@myhost.test.ex: queued for routing
 302 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 303 routing CALLER@myhost.test.ex
 304 --------> r1 router <--------
 305 local_part=CALLER domain=myhost.test.ex
 306 processing address_data
 307 search_open: mysql "NULL"
 308 search_find: file="NULL"
 309   key="select name from them where id='CALLER'" partial=-1 affix=NULL starflags=0
 310 LRU list:
 311 internal_search_find: file="NULL"
 312   type=mysql key="select name from them where id='CALLER'"
 313 database lookup required for select name from them where id='CALLER'
 314 MySQL query: select name from them where id='CALLER'
 315 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
 316 lookup yielded: Philip Hazel
 317 calling r1 router
 318 r1 router called for CALLER@myhost.test.ex
 319   domain = myhost.test.ex
 320 set transport t1
 321 queued for t1 transport: local_part = CALLER
 322 domain = myhost.test.ex
 323   errors_to=NULL
 324   domain_data=NULL localpart_data=NULL
 325 routed by r1 router
 326   envelope to: CALLER@myhost.test.ex
 327   transport: t1
 328 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 329 After routing:
 330   Local deliveries:
 331     CALLER@myhost.test.ex
 332   Remote deliveries:
 333   Failed addresses:
 334   Deferred addresses:
 335 search_tidyup called
 336 close MYSQL connection: localhost/test/root
 337 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 338 --------> CALLER@myhost.test.ex <--------
 339 locking TESTSUITE/spool/db/retry.lockfile
 340 locked TESTSUITE/spool/db/retry.lockfile
 341 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 342 returned from EXIM_DBOPEN
 343 no retry data available
 344 search_tidyup called
 345 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 346   uid=CALLER_UID gid=CALLER_GID pid=pppp
 347   home=NULL current=/
 348 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 349 appendfile transport entered
 350 search_open: mysql "NULL"
 351 search_find: file="NULL"
 352   key="select id from them where id='CALLER'" partial=-1 affix=NULL starflags=0
 353 LRU list:
 354 internal_search_find: file="NULL"
 355   type=mysql key="select id from them where id='CALLER'"
 356 database lookup required for select id from them where id='CALLER'
 357 MySQL query: select id from them where id='CALLER'
 358 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
 359 lookup yielded: CALLER
 360 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 361   file=TESTSUITE/test-mail/CALLER format=unix
 362   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 363   message_suffix=\n
 364   maildir_use_size_file=no
 365   locking by lockfile fcntl
 366 lock name: TESTSUITE/test-mail/CALLER.lock
 367 hitch name: TESTSUITE/test-mail/CALLER.lock.test.ex.dddddddd.pppppppp
 368 lock file created
 369 mailbox TESTSUITE/test-mail/CALLER is locked
 370 writing to file TESTSUITE/test-mail/CALLER
 371 writing data block fd=dddd size=sss timeout=0
 372 writing data block fd=dddd size=sss timeout=0
 373 writing data block fd=dddd size=sss timeout=0
 374 appendfile yields 0 with errno=dd more_errno=dd
 375 search_tidyup called
 376 close MYSQL connection: localhost/test/root
 377 journalling CALLER@myhost.test.ex
 378 t1 transport returned OK for CALLER@myhost.test.ex
 379 post-process CALLER@myhost.test.ex (0)
 380 CALLER@myhost.test.ex delivered
 381 LOG: MAIN
 382   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 383 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 384 changed uid/gid: post-delivery tidying
 385   uid=EXIM_UID gid=EXIM_GID pid=pppp
 386 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 387 Processing retry items
 388 Succeeded addresses:
 389 CALLER@myhost.test.ex: no retry items
 390 Failed addresses:
 391 Deferred addresses:
 392 end of retry processing
 393 LOG: MAIN
 394   Completed
 395 end delivery of 10HmaX-0005vi-00
 396 search_tidyup called
 397 search_tidyup called
 398 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 399 search_tidyup called
 400 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>