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 receive_msg: tidyup
 142 search_tidyup called
 143 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
 144 Exim version x.yz ....
 145 Hints DB:
 146 changed uid/gid: forcing real = effective
 147   uid=uuuu gid=EXIM_GID pid=p1236
 148 configuration file is TESTSUITE/test-config
 149 trusted user
 150 admin user
 151 dropping to exim gid; retaining priv uid
 152 seeking password data for user "CALLER": cache not available
 153 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 154 set_process_info: pppp delivering specified messages
 155 set_process_info: pppp delivering 10HmaX-000000005vi-0000
 156 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 157 reading spool file 10HmaX-000000005vi-0000-H
 158 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 159 sender_local=1 ident=CALLER
 160 Non-recipients:
 161  Empty Tree
 162 ---- End of tree ----
 163 recipients_count=1
 164 **** SPOOL_IN - No additional fields
 165 body_linecount=0 message_linecount=8
 166 DSN: set orcpt:   flags: 0x0
 167 Delivery address list:
 168   CALLER@test.ex
 169 no retry data available
 170 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 171 Considering: CALLER@test.ex
 172 unique = CALLER@test.ex
 173 checking router retry status
 174  no   domain  retry record
 175  no   address retry record
 176 CALLER@test.ex: queued for routing
 177 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 178 routing CALLER@test.ex
 179 --------> r1 router <--------
 180 local_part=CALLER domain=test.ex
 181 checking local_parts
 182 CALLER in local_parts?
 183  list element: +never_localparts
 184   start sublist never_localparts
 185    CALLER in "never"?
 186    ╎list element: never
 187    CALLER in "never"? no (end of list)
 188   end sublist never_localparts
 189  list element: +n1_localparts
 190   start sublist n1_localparts
 191    CALLER in "never1"?
 192    ╎list element: never1
 193    CALLER in "never1"? no (end of list)
 194   end sublist n1_localparts
 195  list element: !░+local_localparts
 196   start sublist local_localparts
 197    CALLER in "CALLER"?
 198    ╎list element: CALLER
 199    ╎CALLER in "CALLER"? yes (matched "CALLER")
 200   end sublist local_localparts
 201  data from lookup saved for cache for +local_localparts: key 'CALLER' value 'CALLER'
 202  CALLER in local_parts? no (matched "! +local_localparts")
 203 r1 router skipped: local_parts mismatch
 204 --------> r2 router <--------
 205 local_part=CALLER domain=test.ex
 206 checking local_parts
 207 CALLER in local_parts?
 208  list element: +never_localparts
 209   start sublist never_localparts
 210  cached no match for +never_localparts
 211  cached lookup data = NULL
 212  list element: +n2_localparts
 213   start sublist n2_localparts
 214    list separator: ';'
 215    CALLER in " never2 ; +n1_localparts"?
 216    ╎list element: never2
 217    ╎list element: +n1_localparts
 218    ╎ start sublist n1_localparts
 219    ╎cached no match for +n1_localparts
 220    ╎cached lookup data = NULL
 221    CALLER in " never2 ; +n1_localparts"? no (end of list)
 222   end sublist n2_localparts
 223  list element: !+local_localparts
 224   start sublist local_localparts
 225  cached yes match for +local_localparts
 226  cached lookup data = CALLER
 227  CALLER in local_parts? no (matched "!+local_localparts" - cached)
 228 r2 router skipped: local_parts mismatch
 229 --------> r3 router <--------
 230 local_part=CALLER domain=test.ex
 231 checking local_parts
 232 CALLER in local_parts?
 233  list element: +local_localparts
 234   start sublist local_localparts
 235  cached yes match for +local_localparts
 236  cached lookup data = CALLER
 237  CALLER in local_parts? yes (matched "+local_localparts" - cached)
 238 checking for local user
 239 seeking password data for user "CALLER": using cached result
 240 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 241 calling r3 router
 242 r3 router called for CALLER@test.ex
 243   domain = test.ex
 244 set transport t1
 245 queued for t1 transport: local_part = CALLER
 246 domain = test.ex
 247   errors_to=NULL
 248   domain_data=NULL local_part_data=CALLER
 249 routed by r3 router
 250   envelope to:  CALLER@test.ex
 251   transport:    t1
 252 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 253 After routing:
 254   Local deliveries:
 255     CALLER@test.ex
 256   Remote deliveries:
 257   Failed addresses:
 258   Deferred addresses:
 259 search_tidyup called
 260 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 261 --------> CALLER@test.ex <--------
 262 no retry data available
 263 search_tidyup called
 264 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 265   uid=CALLER_UID gid=CALLER_GID pid=p1237
 266   home=CALLER_HOME current=CALLER_HOME
 267 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to CALLER using t1
 268 appendfile transport entered
 269 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 270   file=/dev/null format=unix
 271   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 272   message_suffix=\n
 273   maildir_use_size_file=no
 274   locking by lockfile fcntl
 275 search_tidyup called
 276 >>>>>>>>>>>>>>>> Exim pid=p1237 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
 277 journalling CALLER@test.ex
 278 t1 transport returned OK for CALLER@test.ex
 279 post-process CALLER@test.ex (0)
 280 CALLER@test.ex delivered
 281 LOG: MAIN
 282   => CALLER <CALLER@test.ex> R=r3 T=t1
 283 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 284 changed uid/gid: post-delivery tidying
 285   uid=EXIM_UID gid=EXIM_GID pid=p1236
 286 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
 287 Processing retry items
 288  Succeeded addresses:
 289   CALLER@test.ex: no retry items
 290  Failed addresses:
 291  Deferred addresses:
 292 end of retry processing
 293 DSN: processing router : r3
 294 DSN: processing successful delivery address: CALLER@test.ex
 295 DSN: Sender_address: CALLER@test.ex
 296 DSN: orcpt: NULL  flags: 0x0
 297 DSN: envid: NULL  ret: 0
 298 DSN: Final recipient: CALLER@test.ex
 299 DSN: Remote SMTP server supports DSN: 0
 300 DSN: not sending DSN success message
 301 LOG: MAIN
 302   Completed
 303 end delivery of 10HmaX-000000005vi-0000
 304 search_tidyup called
 305 search_tidyup called
 306 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 307 search_tidyup called
 308 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 309 Exim version x.yz ....
 310 Hints DB:
 311 changed uid/gid: forcing real = effective
 312   uid=uuuu gid=CALLER_GID pid=p1238
 313 configuration file is TESTSUITE/test-config
 314 admin user
 315 dropping to exim gid; retaining priv uid
 316 seeking password data for user "CALLER": cache not available
 317 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 318 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 319 sender address = CALLER@test.ex
 320 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 321 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 322 Testing unknown@test.ex
 323 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 324 Considering unknown@test.ex
 325 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 326 routing unknown@test.ex
 327 --------> r1 router <--------
 328 local_part=unknown domain=test.ex
 329 checking local_parts
 330 unknown in local_parts?
 331  list element: +never_localparts
 332   start sublist never_localparts
 333    unknown in "never"?
 334    ╎list element: never
 335    unknown in "never"? no (end of list)
 336   end sublist never_localparts
 337  list element: +n1_localparts
 338   start sublist n1_localparts
 339    unknown in "never1"?
 340    ╎list element: never1
 341    unknown in "never1"? no (end of list)
 342   end sublist n1_localparts
 343  list element: !░+local_localparts
 344   start sublist local_localparts
 345    unknown in "CALLER"?
 346    ╎list element: CALLER
 347    unknown in "CALLER"? no (end of list)
 348   end sublist local_localparts
 349 unknown in local_parts? yes (end of list)
 350 calling r1 router
 351 r1 router called for unknown@test.ex
 352   domain = test.ex
 353 route_item = never
 354 test.ex in "never"?
 355  list element: never
 356 test.ex in "never"? no (end of list)
 357 r1 router declined for unknown@test.ex
 358 --------> r2 router <--------
 359 local_part=unknown domain=test.ex
 360 checking local_parts
 361 unknown in local_parts?
 362  list element: +never_localparts
 363   start sublist never_localparts
 364  cached no match for +never_localparts
 365  cached lookup data = NULL
 366  list element: +n2_localparts
 367   start sublist n2_localparts
 368    list separator: ';'
 369    unknown in " never2 ; +n1_localparts"?
 370    ╎list element: never2
 371    ╎list element: +n1_localparts
 372    ╎ start sublist n1_localparts
 373    ╎cached no match for +n1_localparts
 374    ╎cached lookup data = NULL
 375    unknown in " never2 ; +n1_localparts"? no (end of list)
 376   end sublist n2_localparts
 377  list element: !+local_localparts
 378   start sublist local_localparts
 379  cached no match for +local_localparts
 380  cached lookup data = NULL
 381 unknown in local_parts? yes (end of list)
 382 calling r2 router
 383 r2 router called for unknown@test.ex
 384   domain = test.ex
 385 main lookup for domain
 386  check dnssec require list
 387   test.ex in dnssec_require_domains? no (option unset)
 388  check dnssec request list
 389   test.ex in dnssec_request_domains?
 390    list element: *
 391    test.ex in dnssec_request_domains? yes (matched "*")
 392  DNS lookup of test.ex (MX) using fakens
 393  DNS lookup of test.ex (MX) gave NO_DATA
 394  returning DNS_NODATA
 395  faking res_search(MX) response length as 65535
 396   writing neg-cache entry for test.ex-MX-xxxx, ttl 3000
 397  test.ex (MX resp) DNSSEC
 398  DNS lookup of test.ex (A) using fakens
 399  DNS lookup of test.ex (A) gave NO_DATA
 400  returning DNS_NODATA
 401  faking res_search(A) response length as 65535
 402   writing neg-cache entry for test.ex-A-xxxx, ttl 3000
 403 r2 router declined for unknown@test.ex
 404 --------> r3 router <--------
 405 local_part=unknown domain=test.ex
 406 checking local_parts
 407 unknown in local_parts?
 408  list element: +local_localparts
 409   start sublist local_localparts
 410  cached no match for +local_localparts
 411  cached lookup data = NULL
 412 unknown in local_parts? no (end of list)
 413 r3 router skipped: local_parts mismatch
 414 --------> r4 router <--------
 415 local_part=unknown domain=test.ex
 416 checking local_parts
 417 unknown in local_parts?
 418  list element: +local_localparts
 419   start sublist local_localparts
 420  cached no match for +local_localparts
 421  cached lookup data = NULL
 422  list element: +expanded
 423   start sublist expanded
 424    unknown in "test.ex"?
 425    ╎list element: test.ex
 426    unknown in "test.ex"? no (end of list)
 427   end sublist expanded
 428  list element: +unexpanded
 429   start sublist unexpanded
 430    unknown in "unexpanded"?
 431    ╎list element: unexpanded
 432    unknown in "unexpanded"? no (end of list)
 433   end sublist unexpanded
 434 unknown in local_parts? no (end of list)
 435 r4 router skipped: local_parts mismatch
 436 --------> r5 router <--------
 437 local_part=unknown domain=test.ex
 438 checking local_parts
 439 unknown in local_parts?
 440  list element: +local_localparts
 441   start sublist local_localparts
 442  cached no match for +local_localparts
 443  cached lookup data = NULL
 444  list element: +expanded
 445   start sublist expanded
 446    unknown in "test.ex"?
 447    ╎list element: test.ex
 448    unknown in "test.ex"? no (end of list)
 449   end sublist expanded
 450  list element: +unexpanded
 451   start sublist unexpanded
 452  cached no match for +unexpanded
 453  cached lookup data = NULL
 454 unknown in local_parts? no (end of list)
 455 r5 router skipped: local_parts mismatch
 456 no more routers
 457 search_tidyup called
 458 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>