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 journalling CALLER@test.ex
 279 t1 transport returned OK for CALLER@test.ex
 280 post-process CALLER@test.ex (0)
 281 CALLER@test.ex delivered
 282 LOG: MAIN
 283   => CALLER <CALLER@test.ex> R=r3 T=t1
 284 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 285 changed uid/gid: post-delivery tidying
 286   uid=EXIM_UID gid=EXIM_GID pid=p1236
 287 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
 288 Processing retry items
 289 Succeeded addresses:
 290  CALLER@test.ex: no retry items
 291 Failed addresses:
 292 Deferred addresses:
 293 end of retry processing
 294 DSN: processing router : r3
 295 DSN: processing successful delivery address: CALLER@test.ex
 296 DSN: Sender_address: CALLER@test.ex
 297 DSN: orcpt: NULL  flags: 0x0
 298 DSN: envid: NULL  ret: 0
 299 DSN: Final recipient: CALLER@test.ex
 300 DSN: Remote SMTP server supports DSN: 0
 301 DSN: not sending DSN success message
 302 LOG: MAIN
 303   Completed
 304 end delivery of 10HmaX-000000005vi-0000
 305 search_tidyup called
 306 search_tidyup called
 307 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 308 search_tidyup called
 309 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 310 Exim version x.yz ....
 311 Hints DB:
 312 changed uid/gid: forcing real = effective
 313   uid=uuuu gid=CALLER_GID pid=p1238
 314 configuration file is TESTSUITE/test-config
 315 admin user
 316 dropping to exim gid; retaining priv uid
 317 seeking password data for user "CALLER": cache not available
 318 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 319 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 320 sender address = CALLER@test.ex
 321 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 322 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 323 Testing unknown@test.ex
 324 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 325 Considering unknown@test.ex
 326 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 327 routing unknown@test.ex
 328 --------> r1 router <--------
 329 local_part=unknown domain=test.ex
 330 checking local_parts
 331 unknown in local_parts?
 332  list element: +never_localparts
 333   start sublist never_localparts
 334    unknown in "never"?
 335    ╎list element: never
 336    unknown in "never"? no (end of list)
 337   end sublist never_localparts
 338  list element: +n1_localparts
 339   start sublist n1_localparts
 340    unknown in "never1"?
 341    ╎list element: never1
 342    unknown in "never1"? no (end of list)
 343   end sublist n1_localparts
 344  list element: !░+local_localparts
 345   start sublist local_localparts
 346    unknown in "CALLER"?
 347    ╎list element: CALLER
 348    unknown in "CALLER"? no (end of list)
 349   end sublist local_localparts
 350 unknown in local_parts? yes (end of list)
 351 calling r1 router
 352 r1 router called for unknown@test.ex
 353   domain = test.ex
 354 route_item = never
 355 test.ex in "never"?
 356  list element: never
 357 test.ex in "never"? no (end of list)
 358 r1 router declined for unknown@test.ex
 359 --------> r2 router <--------
 360 local_part=unknown domain=test.ex
 361 checking local_parts
 362 unknown in local_parts?
 363  list element: +never_localparts
 364   start sublist never_localparts
 365  cached no match for +never_localparts
 366  cached lookup data = NULL
 367  list element: +n2_localparts
 368   start sublist n2_localparts
 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 >>>>>>>>>>>>>>>>