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_tidyup called
 118 close MYSQL connection: localhost/test/root
 119 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 120 Exim version x.yz ....
 121 changed uid/gid: forcing real = effective
 122   uid=uuuu gid=CALLER_GID pid=pppp
 123 configuration file is TESTSUITE/test-config
 124 admin user
 125 changed uid/gid: privilege not needed
 126   uid=EXIM_UID gid=EXIM_GID pid=pppp
 127 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 128 sender address = CALLER@myhost.test.ex
 129 sender_fullhost = [10.0.0.0]
 130 sender_rcvhost = [10.0.0.0]
 131 host in hosts_connection_nolog? no (option unset)
 132 LOG: smtp_connection MAIN
 133   SMTP connection from [10.0.0.0]
 134 host in host_lookup? no (option unset)
 135 set_process_info: pppp handling incoming connection from [10.0.0.0]
 136 host in host_reject_connection? no (option unset)
 137 host in sender_unqualified_hosts? no (option unset)
 138 host in recipient_unqualified_hosts? no (option unset)
 139 host in helo_verify_hosts? no (option unset)
 140 host in helo_try_verify_hosts? no (option unset)
 141 host in helo_accept_junk_hosts? no (option unset)
 142 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000\r
 143 smtp_setup_msg entered
 144 SMTP<< mail from:<a@b>
 145 SMTP>> 250 OK\r
 146 SMTP<< rcpt to:<c@d>
 147 using ACL "check_recipient"
 148 processing "accept"
 149 check domains = +local_domains
 150 d in "@"? no (end of list)
 151 d in "+local_domains"? no (end of list)
 152 accept: condition test failed
 153 processing "accept"
 154 check hosts = +relay_hosts
 155 search_open: mysql "NULL"
 156 search_find: file="NULL"
 157   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0
 158 LRU list:
 159 internal_search_find: file="NULL"
 160   type=mysql key="select * from them where id='10.0.0.0'"
 161 database lookup required for select * from them where id='10.0.0.0'
 162 MYSQL query: select * from them where id='10.0.0.0'
 163 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
 164 MYSQL: no data found
 165 lookup failed
 166 host in "net-mysql;select * from them where id='10.0.0.0'"? no (end of list)
 167 host in "+relay_hosts"? no (end of list)
 168 accept: condition test failed
 169 processing "deny"
 170 deny: condition test succeeded
 171 SMTP>> 550 relay not permitted\r
 172 LOG: MAIN REJECT
 173   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
 174 SMTP<< quit
 175 SMTP>> 221 myhost.test.ex closing connection\r
 176 LOG: smtp_connection MAIN
 177   SMTP connection from [10.0.0.0] closed by QUIT
 178 search_tidyup called
 179 close MYSQL connection: localhost/test/root
 180 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 181 Exim version x.yz ....
 182 changed uid/gid: forcing real = effective
 183   uid=uuuu gid=CALLER_GID pid=pppp
 184 configuration file is TESTSUITE/test-config
 185 admin user
 186 changed uid/gid: privilege not needed
 187   uid=EXIM_UID gid=EXIM_GID pid=pppp
 188 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 189 sender address = CALLER@myhost.test.ex
 190 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
 191 Sender: CALLER@myhost.test.ex
 192 Recipients:
 193   CALLER
 194 search_tidyup called
 195 >>Headers received:
 196
 197 rewrite_one_header: type=F:
 198   From: CALLER_NAME <CALLER@myhost.test.ex>
 199 search_tidyup called
 200 >>Headers after rewriting and local additions:
 201 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 202 F From: CALLER_NAME <CALLER@myhost.test.ex>
 203   Date: Tue, 2 Mar 1999 09:44:33 +0000
 204
 205 Data file written for message 10HmaX-0005vi-00
 206 >>Generated Received: header line
 207 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 208         (envelope-from <CALLER@myhost.test.ex>)
 209         id 10HmaX-0005vi-00
 210         for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 211 calling local_scan(); timeout=300
 212 local_scan() returned 0 NULL
 213 Writing spool header file
 214 Size of headers = sss
 215 LOG: MAIN
 216   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 217 created log directory TESTSUITE/spool/log
 218 search_tidyup called
 219 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
 220 Exim version x.yz ....
 221 changed uid/gid: forcing real = effective
 222   uid=uuuu gid=EXIM_GID pid=pppp
 223 configuration file is TESTSUITE/test-config
 224 trusted user
 225 admin user
 226 skipping ACL configuration - not needed
 227 set_process_info: pppp delivering specified messages
 228 set_process_info: pppp delivering 10HmaX-0005vi-00
 229 reading spool file 10HmaX-0005vi-00-H
 230 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
 231 sender_local=1 ident=CALLER
 232 Non-recipients:
 233 Empty Tree
 234 ---- End of tree ----
 235 recipients_count=1
 236 body_linecount=1 message_linecount=7
 237 Delivery address list:
 238   CALLER@myhost.test.ex
 239 locking TESTSUITE/spool/db/retry.lockfile
 240 locked TESTSUITE/spool/db/retry.lockfile
 241 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 242 returned from EXIM_DBOPEN
 243 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 244 no retry data available
 245 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 246 Considering: CALLER@myhost.test.ex
 247 unique = CALLER@myhost.test.ex
 248 no domain retry record
 249 no address retry record
 250 CALLER@myhost.test.ex: queued for routing
 251 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 252 routing CALLER@myhost.test.ex
 253 --------> r1 router <--------
 254 local_part=CALLER domain=myhost.test.ex
 255 processing address_data
 256 search_open: mysql "NULL"
 257 search_find: file="NULL"
 258   key="select name from them where id='CALLER'" partial=-1 affix=NULL starflags=0
 259 LRU list:
 260 internal_search_find: file="NULL"
 261   type=mysql key="select name from them where id='CALLER'"
 262 database lookup required for select name from them where id='CALLER'
 263 MYSQL query: select name from them where id='CALLER'
 264 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
 265 lookup yielded: Philip Hazel
 266 calling r1 router
 267 r1 router called for CALLER@myhost.test.ex
 268   domain = myhost.test.ex
 269 set transport t1
 270 queued for t1 transport: local_part = CALLER
 271 domain = myhost.test.ex
 272   errors_to=NULL
 273   domain_data=NULL localpart_data=NULL
 274 routed by r1 router
 275   envelope to: CALLER@myhost.test.ex
 276   transport: t1
 277 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 278 After routing:
 279   Local deliveries:
 280     CALLER@myhost.test.ex
 281   Remote deliveries:
 282   Failed addresses:
 283   Deferred addresses:
 284 search_tidyup called
 285 close MYSQL connection: localhost/test/root
 286 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 287 --------> CALLER@myhost.test.ex <--------
 288 locking TESTSUITE/spool/db/retry.lockfile
 289 locked TESTSUITE/spool/db/retry.lockfile
 290 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 291 returned from EXIM_DBOPEN
 292 no retry data available
 293 search_tidyup called
 294 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
 295   uid=CALLER_UID gid=CALLER_GID pid=pppp
 296   home=NULL current=/
 297 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 298 appendfile transport entered
 299 search_open: mysql "NULL"
 300 search_find: file="NULL"
 301   key="select id from them where id='CALLER'" partial=-1 affix=NULL starflags=0
 302 LRU list:
 303 internal_search_find: file="NULL"
 304   type=mysql key="select id from them where id='CALLER'"
 305 database lookup required for select id from them where id='CALLER'
 306 MYSQL query: select id from them where id='CALLER'
 307 MYSQL new connection: host=localhost port=0 socket=NULL database=test user=root
 308 lookup yielded: CALLER
 309 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 310   file=TESTSUITE/test-mail/CALLER format=unix
 311   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 312   message_suffix=\n
 313   maildir_use_size_file=no
 314   locking by lockfile fcntl
 315 lock name: TESTSUITE/test-mail/CALLER.lock
 316 hitch name: TESTSUITE/test-mail/CALLER.lock.test.ex.dddddddd.pppppppp
 317 lock file created
 318 mailbox TESTSUITE/test-mail/CALLER is locked
 319 writing to file TESTSUITE/test-mail/CALLER
 320 writing data block fd=8 size=sss timeout=0
 321 writing data block fd=8 size=sss timeout=0
 322 writing data block fd=8 size=sss timeout=0
 323 appendfile yields 0 with errno=dd more_errno=dd
 324 search_tidyup called
 325 close MYSQL connection: localhost/test/root
 326 journalling CALLER@myhost.test.ex
 327 t1 transport returned OK for CALLER@myhost.test.ex
 328 post-process CALLER@myhost.test.ex (0)
 329 CALLER@myhost.test.ex delivered
 330 LOG: MAIN
 331   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
 332 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 333 changed uid/gid: post-delivery tidying
 334   uid=EXIM_UID gid=EXIM_GID pid=pppp
 335 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 336 Processing retry items
 337 Succeeded addresses:
 338 CALLER@myhost.test.ex: no retry items
 339 Failed addresses:
 340 Deferred addresses:
 341 end of retry processing
 342 LOG: MAIN
 343   Completed
 344 end delivery of 10HmaX-0005vi-00
 345 search_tidyup called
 346 search_tidyup called
 347 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 348 search_tidyup called
 349 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>