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  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 168  returned from EXIM_DBOPEN: (nil)
 169  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 170 no retry data available
 171 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 172 Considering: CALLER@test.ex
 173 unique = CALLER@test.ex
 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    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  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 262  returned from EXIM_DBOPEN: (nil)
 263  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 264 no retry data available
 265 search_tidyup called
 266 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 267   uid=CALLER_UID gid=CALLER_GID pid=p1237
 268   home=CALLER_HOME current=CALLER_HOME
 269 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to CALLER using t1
 270 appendfile transport entered
 271 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 272   file=/dev/null format=unix
 273   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 274   message_suffix=\n
 275   maildir_use_size_file=no
 276   locking by lockfile fcntl
 277 search_tidyup called
 278 >>>>>>>>>>>>>>>> Exim pid=p1237 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
 279 journalling CALLER@test.ex
 280 t1 transport returned OK for CALLER@test.ex
 281 post-process CALLER@test.ex (0)
 282 CALLER@test.ex delivered
 283 LOG: MAIN
 284   => CALLER <CALLER@test.ex> R=r3 T=t1
 285 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 286 changed uid/gid: post-delivery tidying
 287   uid=EXIM_UID gid=EXIM_GID pid=p1236
 288 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
 289 Processing retry items
 290 Succeeded addresses:
 291  CALLER@test.ex: no retry items
 292 Failed addresses:
 293 Deferred addresses:
 294 end of retry processing
 295 DSN: processing router : r3
 296 DSN: processing successful delivery address: CALLER@test.ex
 297 DSN: Sender_address: CALLER@test.ex
 298 DSN: orcpt: NULL  flags: 0x0
 299 DSN: envid: NULL  ret: 0
 300 DSN: Final recipient: CALLER@test.ex
 301 DSN: Remote SMTP server supports DSN: 0
 302 DSN: not sending DSN success message
 303 LOG: MAIN
 304   Completed
 305 end delivery of 10HmaX-000000005vi-0000
 306 search_tidyup called
 307 search_tidyup called
 308 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 309 search_tidyup called
 310 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 311 Exim version x.yz ....
 312 Hints DB:
 313 changed uid/gid: forcing real = effective
 314   uid=uuuu gid=CALLER_GID pid=p1238
 315 configuration file is TESTSUITE/test-config
 316 admin user
 317 dropping to exim gid; retaining priv uid
 318 seeking password data for user "CALLER": cache not available
 319 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 320 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 321 sender address = CALLER@test.ex
 322 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 323 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 324 Testing unknown@test.ex
 325 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 326 Considering unknown@test.ex
 327 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 328 routing unknown@test.ex
 329 --------> r1 router <--------
 330 local_part=unknown domain=test.ex
 331 checking local_parts
 332 unknown in local_parts?
 333  list element: +never_localparts
 334   start sublist never_localparts
 335    unknown in "never"?
 336    ╎list element: never
 337    unknown in "never"? no (end of list)
 338   end sublist never_localparts
 339  list element: +n1_localparts
 340   start sublist n1_localparts
 341    unknown in "never1"?
 342    ╎list element: never1
 343    unknown in "never1"? no (end of list)
 344   end sublist n1_localparts
 345  list element: !░+local_localparts
 346   start sublist local_localparts
 347    unknown in "CALLER"?
 348    ╎list element: CALLER
 349    unknown in "CALLER"? no (end of list)
 350   end sublist local_localparts
 351 unknown in local_parts? yes (end of list)
 352 calling r1 router
 353 r1 router called for unknown@test.ex
 354   domain = test.ex
 355 route_item = never
 356 test.ex in "never"?
 357  list element: never
 358 test.ex in "never"? no (end of list)
 359 r1 router declined for unknown@test.ex
 360 --------> r2 router <--------
 361 local_part=unknown domain=test.ex
 362 checking local_parts
 363 unknown in local_parts?
 364  list element: +never_localparts
 365   start sublist never_localparts
 366  cached no match for +never_localparts
 367  cached lookup data = NULL
 368  list element: +n2_localparts
 369   start sublist n2_localparts
 370    unknown in "<; never2 ; +n1_localparts"?
 371    ╎list element: never2
 372    ╎list element: +n1_localparts
 373    ╎ start sublist n1_localparts
 374    ╎cached no match for +n1_localparts
 375    ╎cached lookup data = NULL
 376    unknown in "<; never2 ; +n1_localparts"? no (end of list)
 377   end sublist n2_localparts
 378  list element: !+local_localparts
 379   start sublist local_localparts
 380  cached no match for +local_localparts
 381  cached lookup data = NULL
 382 unknown in local_parts? yes (end of list)
 383 calling r2 router
 384 r2 router called for unknown@test.ex
 385   domain = test.ex
 386 main lookup for domain
 387  check dnssec require list
 388   test.ex in dnssec_require_domains? no (option unset)
 389  check dnssec request list
 390   test.ex in dnssec_request_domains?
 391    list element: *
 392    test.ex in dnssec_request_domains? yes (matched "*")
 393  DNS lookup of test.ex (MX) using fakens
 394  DNS lookup of test.ex (MX) gave NO_DATA
 395  returning DNS_NODATA
 396  faking res_search(MX) response length as 65535
 397   writing neg-cache entry for test.ex-MX-xxxx, ttl 3000
 398  test.ex (MX resp) DNSSEC
 399  DNS lookup of test.ex (A) using fakens
 400  DNS lookup of test.ex (A) gave NO_DATA
 401  returning DNS_NODATA
 402  faking res_search(A) response length as 65535
 403   writing neg-cache entry for test.ex-A-xxxx, ttl 3000
 404 r2 router declined for unknown@test.ex
 405 --------> r3 router <--------
 406 local_part=unknown domain=test.ex
 407 checking local_parts
 408 unknown in local_parts?
 409  list element: +local_localparts
 410   start sublist local_localparts
 411  cached no match for +local_localparts
 412  cached lookup data = NULL
 413 unknown in local_parts? no (end of list)
 414 r3 router skipped: local_parts mismatch
 415 --------> r4 router <--------
 416 local_part=unknown domain=test.ex
 417 checking local_parts
 418 unknown in local_parts?
 419  list element: +local_localparts
 420   start sublist local_localparts
 421  cached no match for +local_localparts
 422  cached lookup data = NULL
 423  list element: +expanded
 424   start sublist expanded
 425    unknown in "test.ex"?
 426    ╎list element: test.ex
 427    unknown in "test.ex"? no (end of list)
 428   end sublist expanded
 429  list element: +unexpanded
 430   start sublist unexpanded
 431    unknown in "unexpanded"?
 432    ╎list element: unexpanded
 433    unknown in "unexpanded"? no (end of list)
 434   end sublist unexpanded
 435 unknown in local_parts? no (end of list)
 436 r4 router skipped: local_parts mismatch
 437 --------> r5 router <--------
 438 local_part=unknown domain=test.ex
 439 checking local_parts
 440 unknown in local_parts?
 441  list element: +local_localparts
 442   start sublist local_localparts
 443  cached no match for +local_localparts
 444  cached lookup data = NULL
 445  list element: +expanded
 446   start sublist expanded
 447    unknown in "test.ex"?
 448    ╎list element: test.ex
 449    unknown in "test.ex"? no (end of list)
 450   end sublist expanded
 451  list element: +unexpanded
 452   start sublist unexpanded
 453  cached no match for +unexpanded
 454  cached lookup data = NULL
 455 unknown in local_parts? no (end of list)
 456 r5 router skipped: local_parts mismatch
 457 no more routers
 458 search_tidyup called
 459 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>