test/stderr/0275

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=pppp
   4 configuration file is TESTSUITE/test-config
   5 admin user
   6 dropping to exim gid; retaining priv uid
   7 seeking password data for user "CALLER": cache not available
   8 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
   9 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  10 sender address = CALLER@test.ex
  11 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
  12 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  13 Testing userx@test.ex
  14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  15 Considering userx@test.ex
  16 test.ex in "! *.ex"? no (matched "! *.ex")
  17 test.ex in "test.ex"? yes (matched "test.ex")
  18 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
  19 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  20 routing userx@test.ex
  21 --------> r00 router <--------
  22 local_part=userx domain=test.ex
  23 checking domains
  24 test.ex in "userx"? no (end of list)
  25 test.ex in "+nocache"? no (end of list)
  26 r00 router skipped: domains mismatch
  27 --------> r01 router <--------
  28 local_part=userx domain=test.ex
  29 checking domains
  30 test.ex in "userx"? no (end of list)
  31 test.ex in "+nocache"? no (end of list)
  32 r01 router skipped: domains mismatch
  33 --------> r02 router <--------
  34 local_part=userx domain=test.ex
  35 checking domains
  36 test.ex in "userx"? no (end of list)
  37 test.ex in "+nocache"? no (end of list)
  38 test.ex in "+nocache2"? no (end of list)
  39 r02 router skipped: domains mismatch
  40 --------> r03 router <--------
  41 local_part=userx domain=test.ex
  42 checking domains
  43 test.ex in "userx"? no (end of list)
  44 test.ex in "+nocache"? no (end of list)
  45 test.ex in "+nocache2"? no (end of list)
  46 r03 router skipped: domains mismatch
  47 --------> r04 router <--------
  48 local_part=userx domain=test.ex
  49 checking domains
  50 test.ex in "userx"? no (end of list)
  51 test.ex in "+forcecache"? no (end of list)
  52 r04 router skipped: domains mismatch
  53 --------> r05 router <--------
  54 local_part=userx domain=test.ex
  55 checking domains
  56 cached no match for +forcecache
  57 cached lookup data = NULL
  58 test.ex in "+forcecache"? no (end of list)
  59 r05 router skipped: domains mismatch
  60 --------> r1 router <--------
  61 local_part=userx domain=test.ex
  62 checking domains
  63 test.ex in "never.ex"? no (end of list)
  64 test.ex in "never1.ex"? no (end of list)
  65 test.ex in "test.ex"? yes (matched "test.ex")
  66 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
  67 r1 router skipped: domains mismatch
  68 --------> r2 router <--------
  69 local_part=userx domain=test.ex
  70 checking domains
  71 cached no match for +never_domains
  72 cached lookup data = NULL
  73 cached no match for +n1_domains
  74 cached lookup data = NULL
  75 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
  76 cached yes match for +local_domains
  77 cached lookup data = NULL
  78 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
  79 r2 router skipped: domains mismatch
  80 --------> r3 router <--------
  81 local_part=userx domain=test.ex
  82 checking domains
  83 cached yes match for +local_domains
  84 cached lookup data = NULL
  85 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
  86 checking local_parts
  87 userx in "userx"? yes (matched "userx")
  88 calling r3 router
  89 r3 router called for userx@test.ex
  90   domain = test.ex
  91 set transport t1
  92 queued for t1 transport: local_part = userx
  93 domain = test.ex
  94   errors_to=NULL
  95   domain_data=NULL localpart_data=NULL
  96 routed by r3 router
  97   envelope to: userx@test.ex
  98   transport: t1
  99 search_tidyup called
 100 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 101 Exim version x.yz ....
 102 changed uid/gid: forcing real = effective
 103   uid=uuuu gid=CALLER_GID pid=pppp
 104 configuration file is TESTSUITE/test-config
 105 admin user
 106 changed uid/gid: privilege not needed
 107   uid=EXIM_UID gid=EXIM_GID pid=pppp
 108 seeking password data for user "CALLER": cache not available
 109 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 110 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 111 sender address = CALLER@test.ex
 112 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
 113 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 114 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 115 Sender: CALLER@test.ex
 116 Recipients:
 117   userx@test.ex
 118 search_tidyup called
 119 >>Headers received:
 120
 121 rewrite_one_header: type=F:
 122   From: CALLER_NAME <CALLER@test.ex>
 123 search_tidyup called
 124 >>Headers after rewriting and local additions:
 125 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
 126 F From: CALLER_NAME <CALLER@test.ex>
 127   Date: Tue, 2 Mar 1999 09:44:33 +0000
 128
 129 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 130 Data file written for message 10HmaX-0005vi-00
 131 >>Generated Received: header line
 132 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 133         (envelope-from <CALLER@test.ex>)
 134         id 10HmaX-0005vi-00
 135         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
 136 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 137 DSN: Write SPOOL: -dsn_envid NULL
 138 DSN: Write SPOOL  :-dsn_ret 0
 139 DSN: Flags: 0x0
 140 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 141 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 142 Size of headers = sss
 143 LOG: MAIN
 144   <= CALLER@test.ex U=CALLER P=local S=sss
 145 created log directory TESTSUITE/spool/log
 146 search_tidyup called
 147 fresh-exec forking for local-accept-delivery
 148 postfork: local-accept-delivery
 149 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 150 fresh-exec forked for local-accept-delivery: npppp
 151 Exim version x.yz ....
 152 changed uid/gid: forcing real = effective
 153   uid=uuuu gid=EXIM_GID pid=pppp
 154 configuration file is TESTSUITE/test-config
 155 trusted user
 156 admin user
 157 dropping to exim gid; retaining priv uid
 158 seeking password data for user "CALLER": cache not available
 159 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 160 set_process_info: pppp delivering specified messages
 161 set_process_info: pppp delivering 10HmaX-0005vi-00
 162 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 163 reading spool file 10HmaX-0005vi-00-H
 164 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 165 sender_local=1 ident=CALLER
 166 Non-recipients:
 167 Empty Tree
 168 ---- End of tree ----
 169 recipients_count=1
 170 **** SPOOL_IN - No additional fields
 171 body_linecount=0 message_linecount=7
 172 DSN: set orcpt:   flags: 0x0
 173 Delivery address list:
 174   userx@test.ex
 175  locking TESTSUITE/spool/db/retry.lockfile
 176  locked  TESTSUITE/spool/db/retry.lockfile
 177  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 178  returned from EXIM_DBOPEN: (nil)
 179  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 180  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
 181 no retry data available
 182 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 183 Considering: userx@test.ex
 184 test.ex in "! *.ex"? no (matched "! *.ex")
 185 test.ex in "test.ex"? yes (matched "test.ex")
 186 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
 187 cached no match for +hold_domains
 188 test.ex in hold_domains? no (end of list)
 189 unique = userx@test.ex
 190 no   domain  retry record
 191 no   address retry record
 192 userx@test.ex: queued for routing
 193 cached yes match for +not_queue_domains
 194 test.ex in queue_domains? no (matched "!+not_queue_domains" - cached)
 195 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 196 routing userx@test.ex
 197 --------> r00 router <--------
 198 local_part=userx domain=test.ex
 199 checking domains
 200 test.ex in "userx"? no (end of list)
 201 test.ex in "+nocache"? no (end of list)
 202 r00 router skipped: domains mismatch
 203 --------> r01 router <--------
 204 local_part=userx domain=test.ex
 205 checking domains
 206 test.ex in "userx"? no (end of list)
 207 test.ex in "+nocache"? no (end of list)
 208 r01 router skipped: domains mismatch
 209 --------> r02 router <--------
 210 local_part=userx domain=test.ex
 211 checking domains
 212 test.ex in "userx"? no (end of list)
 213 test.ex in "+nocache"? no (end of list)
 214 test.ex in "+nocache2"? no (end of list)
 215 r02 router skipped: domains mismatch
 216 --------> r03 router <--------
 217 local_part=userx domain=test.ex
 218 checking domains
 219 test.ex in "userx"? no (end of list)
 220 test.ex in "+nocache"? no (end of list)
 221 test.ex in "+nocache2"? no (end of list)
 222 r03 router skipped: domains mismatch
 223 --------> r04 router <--------
 224 local_part=userx domain=test.ex
 225 checking domains
 226 test.ex in "userx"? no (end of list)
 227 test.ex in "+forcecache"? no (end of list)
 228 r04 router skipped: domains mismatch
 229 --------> r05 router <--------
 230 local_part=userx domain=test.ex
 231 checking domains
 232 cached no match for +forcecache
 233 cached lookup data = NULL
 234 test.ex in "+forcecache"? no (end of list)
 235 r05 router skipped: domains mismatch
 236 --------> r1 router <--------
 237 local_part=userx domain=test.ex
 238 checking domains
 239 test.ex in "never.ex"? no (end of list)
 240 test.ex in "never1.ex"? no (end of list)
 241 test.ex in "test.ex"? yes (matched "test.ex")
 242 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
 243 r1 router skipped: domains mismatch
 244 --------> r2 router <--------
 245 local_part=userx domain=test.ex
 246 checking domains
 247 cached no match for +never_domains
 248 cached lookup data = NULL
 249 cached no match for +n1_domains
 250 cached lookup data = NULL
 251 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
 252 cached yes match for +local_domains
 253 cached lookup data = NULL
 254 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
 255 r2 router skipped: domains mismatch
 256 --------> r3 router <--------
 257 local_part=userx domain=test.ex
 258 checking domains
 259 cached yes match for +local_domains
 260 cached lookup data = NULL
 261 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
 262 checking local_parts
 263 userx in "userx"? yes (matched "userx")
 264 calling r3 router
 265 r3 router called for userx@test.ex
 266   domain = test.ex
 267 set transport t1
 268 queued for t1 transport: local_part = userx
 269 domain = test.ex
 270   errors_to=NULL
 271   domain_data=NULL localpart_data=NULL
 272 routed by r3 router
 273   envelope to: userx@test.ex
 274   transport: t1
 275 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 276 After routing:
 277   Local deliveries:
 278     userx@test.ex
 279   Remote deliveries:
 280   Failed addresses:
 281   Deferred addresses:
 282 search_tidyup called
 283 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 284 --------> userx@test.ex <--------
 285  locking TESTSUITE/spool/db/retry.lockfile
 286  locked  TESTSUITE/spool/db/retry.lockfile
 287  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 288  returned from EXIM_DBOPEN: (nil)
 289  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 290 no retry data available
 291 search_tidyup called
 292 local-accept-delivery forking for delivery-local
 293 postfork: delivery-local
 294 changed uid/gid: local delivery to userx <userx@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 userx using t1
 298 appendfile transport entered
 299 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 300   file=/dev/null format=unix
 301   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 302   message_suffix=\n
 303   maildir_use_size_file=no
 304   locking by lockfile fcntl
 305 search_tidyup called
 306 local-accept-delivery forked for delivery-local: npppp
 307 journalling userx@test.ex
 308 t1 transport returned OK for userx@test.ex
 309 post-process userx@test.ex (0)
 310 userx@test.ex delivered
 311 LOG: MAIN
 312   => userx <userx@test.ex> R=r3 T=t1
 313 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 314 changed uid/gid: post-delivery tidying
 315   uid=EXIM_UID gid=EXIM_GID pid=pppp
 316 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 317 Processing retry items
 318 Succeeded addresses:
 319  userx@test.ex: no retry items
 320 Failed addresses:
 321 Deferred addresses:
 322 end of retry processing
 323 DSN: processing router : r3
 324 DSN: processing successful delivery address: userx@test.ex
 325 DSN: Sender_address: CALLER@test.ex
 326 DSN: orcpt: NULL  flags: 0x0
 327 DSN: envid: NULL  ret: 0
 328 DSN: Final recipient: userx@test.ex
 329 DSN: Remote SMTP server supports DSN: 0
 330 DSN: not sending DSN success message
 331 LOG: MAIN
 332   Completed
 333 end delivery of 10HmaX-0005vi-00
 334 search_tidyup called
 335 search_tidyup called
 336 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 337 search_tidyup called
 338 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
 339 Exim version x.yz ....
 340 changed uid/gid: forcing real = effective
 341   uid=uuuu gid=CALLER_GID pid=pppp
 342 configuration file is TESTSUITE/test-config
 343 admin user
 344 changed uid/gid: privilege not needed
 345   uid=EXIM_UID gid=EXIM_GID pid=pppp
 346 seeking password data for user "CALLER": cache not available
 347 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 348 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 349 sender address = CALLER@test.ex
 350 sender_fullhost = [127.0.0.1]
 351 sender_rcvhost = [127.0.0.1]
 352 host in hosts_connection_nolog? no (option unset)
 353 LOG: smtp_connection MAIN
 354   SMTP connection from [127.0.0.1]
 355 host in host_lookup? no (option unset)
 356 set_process_info: pppp handling incoming connection from [127.0.0.1]
 357 host in host_reject_connection? no (option unset)
 358 host in sender_unqualified_hosts? no (option unset)
 359 host in recipient_unqualified_hosts? no (option unset)
 360 host in helo_verify_hosts? no (option unset)
 361 host in helo_try_verify_hosts? no (option unset)
 362 host in helo_accept_junk_hosts? no (option unset)
 363 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 364 smtp_setup_msg entered
 365 SMTP<< HELO test
 366 test in helo_lookup_domains? no (end of list)
 367 sender_fullhost = (test) [127.0.0.1]
 368 sender_rcvhost = [127.0.0.1] (helo=test)
 369 set_process_info: pppp handling incoming connection from (test) [127.0.0.1]
 370 SMTP>> 250 myhost.test.ex Hello test [127.0.0.1]
 371 SMTP<< MAIL FROM:<test@test.ex>
 372 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 373 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 374 SMTP>> 250 OK
 375 SMTP<< RCPT TO:<error@test.ex>
 376 test.ex in "! *.ex"? no (matched "! *.ex")
 377 test.ex in "test.ex"? yes (matched "test.ex")
 378 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
 379 processing "accept" (TESTSUITE/test-config 102)
 380 check verify = recipient
 381 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 382 Verifying error@test.ex
 383 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 384 Considering error@test.ex
 385 cached no match for +hold_domains
 386 cached yes match for +not_queue_domains
 387 test.ex in percent_hack_domains? yes (matched "+not_queue_domains" - cached)
 388 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 389 routing error@test.ex
 390 --------> r0f router <--------
 391 local_part=error domain=test.ex
 392 checking domains
 393 LOG: MAIN PANIC
 394   unknown named domain list "+no_such_list"
 395 domains check lookup or other defer
 396 ----------- end verify ------------
 397 accept: condition test deferred in inline ACL
 398 SMTP>> 451 Temporary local problem - please try later
 399 LOG: MAIN REJECT
 400   H=(test) [127.0.0.1] F=<test@test.ex> temporarily rejected RCPT <error@test.ex>: domains check lookup or other defer
 401 SMTP<< QUIT
 402 SMTP>> 221 myhost.test.ex closing connection
 403 LOG: smtp_connection MAIN
 404   SMTP connection from (test) [127.0.0.1] closed by QUIT
 405 search_tidyup called
 406 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>