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