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