test/stderr/0278

   1 Exim version x.yz ....
   2 Hints DB:
   3 changed uid/gid: forcing real = effective
   4   uid=uuuu gid=CALLER_GID pid=p1234
   5 configuration file is TESTSUITE/test-config
   6 admin user
   7 dropping to exim gid; retaining priv uid
   8 seeking password data for user "CALLER": cache not available
   9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  11 sender address = CALLER@test.ex
  12 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
  13 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  14 Testing CALLER@test.ex
  15 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  16 Considering CALLER@test.ex
  17 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  18 routing CALLER@test.ex
  19 --------> r1 router <--------
  20 local_part=CALLER domain=test.ex
  21 checking local_parts
  22 CALLER in local_parts?
  23  list element: +never_localparts
  24   start sublist never_localparts
  25    CALLER in "never"?
  26    ╎list element: never
  27    CALLER in "never"? no (end of list)
  28   end sublist never_localparts
  29  list element: +n1_localparts
  30   start sublist n1_localparts
  31    CALLER in "never1"?
  32    ╎list element: never1
  33    CALLER in "never1"? no (end of list)
  34   end sublist n1_localparts
  35  list element: !░+local_localparts
  36   start sublist local_localparts
  37    CALLER in "CALLER"?
  38    ╎list element: CALLER
  39    ╎CALLER in "CALLER"? yes (matched "CALLER")
  40   end sublist local_localparts
  41  data from lookup saved for cache for +local_localparts: key 'CALLER' value 'CALLER'
  42  CALLER in local_parts? no (matched "! +local_localparts")
  43 r1 router skipped: local_parts mismatch
  44 --------> r2 router <--------
  45 local_part=CALLER domain=test.ex
  46 checking local_parts
  47 CALLER in local_parts?
  48  list element: +never_localparts
  49   start sublist never_localparts
  50  cached no match for +never_localparts
  51  cached lookup data = NULL
  52  list element: +n2_localparts
  53   start sublist n2_localparts
  54    list separator: ';'
  55    CALLER in " never2 ; +n1_localparts"?
  56    ╎list element: never2
  57    ╎list element: +n1_localparts
  58    ╎ start sublist n1_localparts
  59    ╎cached no match for +n1_localparts
  60    ╎cached lookup data = NULL
  61    CALLER in " never2 ; +n1_localparts"? no (end of list)
  62   end sublist n2_localparts
  63  list element: !+local_localparts
  64   start sublist local_localparts
  65  cached yes match for +local_localparts
  66  cached lookup data = CALLER
  67  CALLER in local_parts? no (matched "!+local_localparts" - cached)
  68 r2 router skipped: local_parts mismatch
  69 --------> r3 router <--------
  70 local_part=CALLER domain=test.ex
  71 checking local_parts
  72 CALLER in local_parts?
  73  list element: +local_localparts
  74   start sublist local_localparts
  75  cached yes match for +local_localparts
  76  cached lookup data = CALLER
  77  CALLER in local_parts? yes (matched "+local_localparts" - cached)
  78 checking for local user
  79 seeking password data for user "CALLER": using cached result
  80 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  81 calling r3 router
  82 r3 router called for CALLER@test.ex
  83   domain = test.ex
  84 set transport t1
  85 queued for t1 transport: local_part = CALLER
  86 domain = test.ex
  87   errors_to=NULL
  88   domain_data=NULL local_part_data=CALLER
  89 routed by r3 router
  90   envelope to: CALLER@test.ex
  91   transport: t1
  92 search_tidyup called
  93 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
  94 Exim version x.yz ....
  95 Hints DB:
  96 changed uid/gid: forcing real = effective
  97   uid=uuuu gid=CALLER_GID pid=p1235
  98 configuration file is TESTSUITE/test-config
  99 admin user
 100 changed uid/gid: privilege not needed
 101   uid=EXIM_UID gid=EXIM_GID pid=p1235
 102 seeking password data for user "CALLER": cache not available
 103 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 104 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 105 sender address = CALLER@test.ex
 106 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
 107 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 108 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 109 Sender: CALLER@test.ex
 110 Recipients:
 111   CALLER@test.ex
 112 search_tidyup called
 113 >>Headers received:
 114
 115 qualify & rewrite recipients list
 116 rewrite rules on sender address
 117 qualify and rewrite headers
 118  rewrite_one_header: type=F:
 119    From: CALLER_NAME <CALLER@test.ex>
 120 search_tidyup called
 121 >>Headers after rewriting and local additions:
 122  I Message-Id: <E10HmaX-000000005vi-0000@myhost.test.ex>
 123  F From: CALLER_NAME <CALLER@test.ex>
 124    Date: Tue, 2 Mar 1999 09:44:33 +0000
 125
 126 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 127 Data file written for message 10HmaX-000000005vi-0000
 128 >>Generated Received: header line
 129 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
 130         (envelope-from <CALLER@test.ex>)
 131         id 10HmaX-000000005vi-0000
 132         for CALLER@test.ex;
 133         Tue, 2 Mar 1999 09:44:33 +0000
 134 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
 135 DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 136 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
 137 Size of headers = sss
 138 LOG: MAIN
 139   <= CALLER@test.ex U=CALLER P=local S=sss
 140 created log directory TESTSUITE/spool/log
 141 search_tidyup called
 142 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
 143 Exim version x.yz ....
 144 Hints DB:
 145 changed uid/gid: forcing real = effective
 146   uid=uuuu gid=EXIM_GID pid=p1236
 147 configuration file is TESTSUITE/test-config
 148 trusted user
 149 admin user
 150 dropping to exim gid; retaining priv uid
 151 seeking password data for user "CALLER": cache not available
 152 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 153 set_process_info: pppp delivering specified messages
 154 set_process_info: pppp delivering 10HmaX-000000005vi-0000
 155 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 156 reading spool file 10HmaX-000000005vi-0000-H
 157 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 158 sender_local=1 ident=CALLER
 159 Non-recipients:
 160  Empty Tree
 161 ---- End of tree ----
 162 recipients_count=1
 163 **** SPOOL_IN - No additional fields
 164 body_linecount=0 message_linecount=8
 165 DSN: set orcpt:   flags: 0x0
 166 Delivery address list:
 167   CALLER@test.ex
 168 no retry data available
 169 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 170 Considering: CALLER@test.ex
 171 unique = CALLER@test.ex
 172 checking router retry status
 173  no   domain  retry record
 174  no   address retry record
 175 CALLER@test.ex: queued for routing
 176 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 177 routing CALLER@test.ex
 178 --------> r1 router <--------
 179 local_part=CALLER domain=test.ex
 180 checking local_parts
 181 CALLER in local_parts?
 182  list element: +never_localparts
 183   start sublist never_localparts
 184    CALLER in "never"?
 185    ╎list element: never
 186    CALLER in "never"? no (end of list)
 187   end sublist never_localparts
 188  list element: +n1_localparts
 189   start sublist n1_localparts
 190    CALLER in "never1"?
 191    ╎list element: never1
 192    CALLER in "never1"? no (end of list)
 193   end sublist n1_localparts
 194  list element: !░+local_localparts
 195   start sublist local_localparts
 196    CALLER in "CALLER"?
 197    ╎list element: CALLER
 198    ╎CALLER in "CALLER"? yes (matched "CALLER")
 199   end sublist local_localparts
 200  data from lookup saved for cache for +local_localparts: key 'CALLER' value 'CALLER'
 201  CALLER in local_parts? no (matched "! +local_localparts")
 202 r1 router skipped: local_parts mismatch
 203 --------> r2 router <--------
 204 local_part=CALLER domain=test.ex
 205 checking local_parts
 206 CALLER in local_parts?
 207  list element: +never_localparts
 208   start sublist never_localparts
 209  cached no match for +never_localparts
 210  cached lookup data = NULL
 211  list element: +n2_localparts
 212   start sublist n2_localparts
 213    list separator: ';'
 214    CALLER in " never2 ; +n1_localparts"?
 215    ╎list element: never2
 216    ╎list element: +n1_localparts
 217    ╎ start sublist n1_localparts
 218    ╎cached no match for +n1_localparts
 219    ╎cached lookup data = NULL
 220    CALLER in " never2 ; +n1_localparts"? no (end of list)
 221   end sublist n2_localparts
 222  list element: !+local_localparts
 223   start sublist local_localparts
 224  cached yes match for +local_localparts
 225  cached lookup data = CALLER
 226  CALLER in local_parts? no (matched "!+local_localparts" - cached)
 227 r2 router skipped: local_parts mismatch
 228 --------> r3 router <--------
 229 local_part=CALLER domain=test.ex
 230 checking local_parts
 231 CALLER in local_parts?
 232  list element: +local_localparts
 233   start sublist local_localparts
 234  cached yes match for +local_localparts
 235  cached lookup data = CALLER
 236  CALLER in local_parts? yes (matched "+local_localparts" - cached)
 237 checking for local user
 238 seeking password data for user "CALLER": using cached result
 239 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 240 calling r3 router
 241 r3 router called for CALLER@test.ex
 242   domain = test.ex
 243 set transport t1
 244 queued for t1 transport: local_part = CALLER
 245 domain = test.ex
 246   errors_to=NULL
 247   domain_data=NULL local_part_data=CALLER
 248 routed by r3 router
 249   envelope to: CALLER@test.ex
 250   transport: t1
 251 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 252 After routing:
 253   Local deliveries:
 254     CALLER@test.ex
 255   Remote deliveries:
 256   Failed addresses:
 257   Deferred addresses:
 258 search_tidyup called
 259 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 260 --------> CALLER@test.ex <--------
 261 no retry data available
 262 search_tidyup called
 263 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 264   uid=CALLER_UID gid=CALLER_GID pid=p1237
 265   home=CALLER_HOME current=CALLER_HOME
 266 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to CALLER using t1
 267 appendfile transport entered
 268 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 269   file=/dev/null format=unix
 270   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 271   message_suffix=\n
 272   maildir_use_size_file=no
 273   locking by lockfile fcntl
 274 search_tidyup called
 275 >>>>>>>>>>>>>>>> Exim pid=p1237 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
 276 journalling CALLER@test.ex
 277 t1 transport returned OK for CALLER@test.ex
 278 post-process CALLER@test.ex (0)
 279 CALLER@test.ex delivered
 280 LOG: MAIN
 281   => CALLER <CALLER@test.ex> R=r3 T=t1
 282 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 283 changed uid/gid: post-delivery tidying
 284   uid=EXIM_UID gid=EXIM_GID pid=p1236
 285 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
 286 Processing retry items
 287  Succeeded addresses:
 288   CALLER@test.ex: no retry items
 289  Failed addresses:
 290  Deferred addresses:
 291 end of retry processing
 292 DSN: processing router : r3
 293 DSN: processing successful delivery address: CALLER@test.ex
 294 DSN: Sender_address: CALLER@test.ex
 295 DSN: orcpt: NULL  flags: 0x0
 296 DSN: envid: NULL  ret: 0
 297 DSN: Final recipient: CALLER@test.ex
 298 DSN: Remote SMTP server supports DSN: 0
 299 DSN: not sending DSN success message
 300 LOG: MAIN
 301   Completed
 302 end delivery of 10HmaX-000000005vi-0000
 303 search_tidyup called
 304 search_tidyup called
 305 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 306 search_tidyup called
 307 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 308 Exim version x.yz ....
 309 Hints DB:
 310 changed uid/gid: forcing real = effective
 311   uid=uuuu gid=CALLER_GID pid=p1238
 312 configuration file is TESTSUITE/test-config
 313 admin user
 314 dropping to exim gid; retaining priv uid
 315 seeking password data for user "CALLER": cache not available
 316 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 317 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 318 sender address = CALLER@test.ex
 319 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 320 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 321 Testing unknown@test.ex
 322 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 323 Considering unknown@test.ex
 324 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 325 routing unknown@test.ex
 326 --------> r1 router <--------
 327 local_part=unknown domain=test.ex
 328 checking local_parts
 329 unknown in local_parts?
 330  list element: +never_localparts
 331   start sublist never_localparts
 332    unknown in "never"?
 333    ╎list element: never
 334    unknown in "never"? no (end of list)
 335   end sublist never_localparts
 336  list element: +n1_localparts
 337   start sublist n1_localparts
 338    unknown in "never1"?
 339    ╎list element: never1
 340    unknown in "never1"? no (end of list)
 341   end sublist n1_localparts
 342  list element: !░+local_localparts
 343   start sublist local_localparts
 344    unknown in "CALLER"?
 345    ╎list element: CALLER
 346    unknown in "CALLER"? no (end of list)
 347   end sublist local_localparts
 348 unknown in local_parts? yes (end of list)
 349 calling r1 router
 350 r1 router called for unknown@test.ex
 351   domain = test.ex
 352 route_item = never
 353 test.ex in "never"?
 354  list element: never
 355 test.ex in "never"? no (end of list)
 356 r1 router declined for unknown@test.ex
 357 --------> r2 router <--------
 358 local_part=unknown domain=test.ex
 359 checking local_parts
 360 unknown in local_parts?
 361  list element: +never_localparts
 362   start sublist never_localparts
 363  cached no match for +never_localparts
 364  cached lookup data = NULL
 365  list element: +n2_localparts
 366   start sublist n2_localparts
 367    list separator: ';'
 368    unknown in " never2 ; +n1_localparts"?
 369    ╎list element: never2
 370    ╎list element: +n1_localparts
 371    ╎ start sublist n1_localparts
 372    ╎cached no match for +n1_localparts
 373    ╎cached lookup data = NULL
 374    unknown in " never2 ; +n1_localparts"? no (end of list)
 375   end sublist n2_localparts
 376  list element: !+local_localparts
 377   start sublist local_localparts
 378  cached no match for +local_localparts
 379  cached lookup data = NULL
 380 unknown in local_parts? yes (end of list)
 381 calling r2 router
 382 r2 router called for unknown@test.ex
 383   domain = test.ex
 384 main lookup for domain
 385  check dnssec require list
 386   test.ex in dnssec_require_domains? no (option unset)
 387  check dnssec request list
 388   test.ex in dnssec_request_domains?
 389    list element: *
 390    test.ex in dnssec_request_domains? yes (matched "*")
 391  DNS lookup of test.ex (MX) using fakens
 392  DNS lookup of test.ex (MX) gave NO_DATA
 393  returning DNS_NODATA
 394  faking res_search(MX) response length as 65535
 395   writing neg-cache entry for test.ex-MX-xxxx, ttl 3000
 396  test.ex (MX resp) DNSSEC
 397  DNS lookup of test.ex (A) using fakens
 398  DNS lookup of test.ex (A) gave NO_DATA
 399  returning DNS_NODATA
 400  faking res_search(A) response length as 65535
 401   writing neg-cache entry for test.ex-A-xxxx, ttl 3000
 402 r2 router declined for unknown@test.ex
 403 --------> r3 router <--------
 404 local_part=unknown domain=test.ex
 405 checking local_parts
 406 unknown in local_parts?
 407  list element: +local_localparts
 408   start sublist local_localparts
 409  cached no match for +local_localparts
 410  cached lookup data = NULL
 411 unknown in local_parts? no (end of list)
 412 r3 router skipped: local_parts mismatch
 413 --------> r4 router <--------
 414 local_part=unknown domain=test.ex
 415 checking local_parts
 416 unknown in local_parts?
 417  list element: +local_localparts
 418   start sublist local_localparts
 419  cached no match for +local_localparts
 420  cached lookup data = NULL
 421  list element: +expanded
 422   start sublist expanded
 423    unknown in "test.ex"?
 424    ╎list element: test.ex
 425    unknown in "test.ex"? no (end of list)
 426   end sublist expanded
 427  list element: +unexpanded
 428   start sublist unexpanded
 429    unknown in "unexpanded"?
 430    ╎list element: unexpanded
 431    unknown in "unexpanded"? no (end of list)
 432   end sublist unexpanded
 433 unknown in local_parts? no (end of list)
 434 r4 router skipped: local_parts mismatch
 435 --------> r5 router <--------
 436 local_part=unknown domain=test.ex
 437 checking local_parts
 438 unknown in local_parts?
 439  list element: +local_localparts
 440   start sublist local_localparts
 441  cached no match for +local_localparts
 442  cached lookup data = NULL
 443  list element: +expanded
 444   start sublist expanded
 445    unknown in "test.ex"?
 446    ╎list element: test.ex
 447    unknown in "test.ex"? no (end of list)
 448   end sublist expanded
 449  list element: +unexpanded
 450   start sublist unexpanded
 451  cached no match for +unexpanded
 452  cached lookup data = NULL
 453 unknown in local_parts? no (end of list)
 454 r5 router skipped: local_parts mismatch
 455 no more routers
 456 search_tidyup called
 457 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>