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