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