test/stderr/0278

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=pppp
   4 configuration file is TESTSUITE/test-config
   5 admin user
   6 dropping to exim gid; retaining priv uid
   7 seeking password data for user "CALLER": cache not available
   8 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
   9 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  10 sender address = CALLER@test.ex
  11 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
  12 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  13 Testing CALLER@test.ex
  14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  15 Considering CALLER@test.ex
  16 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  17 routing CALLER@test.ex
  18 --------> r1 router <--------
  19 local_part=CALLER domain=test.ex
  20 checking local_parts
  21 CALLER in "never"? no (end of list)
  22 CALLER in "never1"? no (end of list)
  23 CALLER in "CALLER"? yes (matched "CALLER")
  24 data from lookup saved for cache for +local_localparts: key 'CALLER' value 'CALLER'
  25 CALLER in "+never_localparts : +n1_localparts : ! +local_localparts"? no (matched "! +local_localparts")
  26 r1 router skipped: local_parts mismatch
  27 --------> r2 router <--------
  28 local_part=CALLER domain=test.ex
  29 checking local_parts
  30 cached no match for +never_localparts
  31 cached lookup data = NULL
  32 cached no match for +n1_localparts
  33 cached lookup data = NULL
  34 CALLER in "<; never2 ; +n1_localparts"? no (end of list)
  35 cached yes match for +local_localparts
  36 cached lookup data = CALLER
  37 CALLER in "+never_localparts : +n2_localparts : !+local_localparts"? no (matched "!+local_localparts" - cached)
  38 r2 router skipped: local_parts mismatch
  39 --------> r3 router <--------
  40 local_part=CALLER domain=test.ex
  41 checking local_parts
  42 cached yes match for +local_localparts
  43 cached lookup data = CALLER
  44 CALLER in "+local_localparts"? yes (matched "+local_localparts" - cached)
  45 checking for local user
  46 seeking password data for user "CALLER": using cached result
  47 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  48 calling r3 router
  49 r3 router called for CALLER@test.ex
  50   domain = test.ex
  51 set transport t1
  52 queued for t1 transport: local_part = CALLER
  53 domain = test.ex
  54   errors_to=NULL
  55   domain_data=NULL local_part_data=CALLER
  56 routed by r3 router
  57   envelope to: CALLER@test.ex
  58   transport: t1
  59 search_tidyup called
  60 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
  61 Exim version x.yz ....
  62 changed uid/gid: forcing real = effective
  63   uid=uuuu gid=CALLER_GID pid=pppp
  64 configuration file is TESTSUITE/test-config
  65 admin user
  66 changed uid/gid: privilege not needed
  67   uid=EXIM_UID gid=EXIM_GID pid=pppp
  68 seeking password data for user "CALLER": cache not available
  69 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  70 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  71 sender address = CALLER@test.ex
  72 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
  73 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  74 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  75 Sender: CALLER@test.ex
  76 Recipients:
  77   CALLER@test.ex
  78 search_tidyup called
  79 >>Headers received:
  80
  81 rewrite_one_header: type=F:
  82   From: CALLER_NAME <CALLER@test.ex>
  83 search_tidyup called
  84 >>Headers after rewriting and local additions:
  85 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
  86 F From: CALLER_NAME <CALLER@test.ex>
  87   Date: Tue, 2 Mar 1999 09:44:33 +0000
  88
  89 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
  90 Data file written for message 10HmaX-0005vi-00
  91 >>Generated Received: header line
  92 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
  93         (envelope-from <CALLER@test.ex>)
  94         id 10HmaX-0005vi-00
  95         for CALLER@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
  96 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
  97 DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
  98 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
  99 Size of headers = sss
 100 LOG: MAIN
 101   <= CALLER@test.ex U=CALLER P=local S=sss
 102 created log directory TESTSUITE/spool/log
 103 search_tidyup called
 104 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 105 Exim version x.yz ....
 106 changed uid/gid: forcing real = effective
 107   uid=uuuu gid=EXIM_GID pid=pppp
 108 configuration file is TESTSUITE/test-config
 109 trusted user
 110 admin user
 111 dropping to exim gid; retaining priv uid
 112 seeking password data for user "CALLER": cache not available
 113 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 114 set_process_info: pppp delivering specified messages
 115 set_process_info: pppp delivering 10HmaX-0005vi-00
 116 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 117 reading spool file 10HmaX-0005vi-00-H
 118 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 119 sender_local=1 ident=CALLER
 120 Non-recipients:
 121 Empty Tree
 122 ---- End of tree ----
 123 recipients_count=1
 124 **** SPOOL_IN - No additional fields
 125 body_linecount=0 message_linecount=7
 126 DSN: set orcpt:   flags: 0x0
 127 Delivery address list:
 128   CALLER@test.ex
 129  locking TESTSUITE/spool/db/retry.lockfile
 130  locked  TESTSUITE/spool/db/retry.lockfile
 131  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 132  returned from EXIM_DBOPEN: (nil)
 133  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 134 no retry data available
 135 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 136 Considering: CALLER@test.ex
 137 unique = CALLER@test.ex
 138 no   domain  retry record
 139 no   address retry record
 140 CALLER@test.ex: queued for routing
 141 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 142 routing CALLER@test.ex
 143 --------> r1 router <--------
 144 local_part=CALLER domain=test.ex
 145 checking local_parts
 146 CALLER in "never"? no (end of list)
 147 CALLER in "never1"? no (end of list)
 148 CALLER in "CALLER"? yes (matched "CALLER")
 149 data from lookup saved for cache for +local_localparts: key 'CALLER' value 'CALLER'
 150 CALLER in "+never_localparts : +n1_localparts : ! +local_localparts"? no (matched "! +local_localparts")
 151 r1 router skipped: local_parts mismatch
 152 --------> r2 router <--------
 153 local_part=CALLER domain=test.ex
 154 checking local_parts
 155 cached no match for +never_localparts
 156 cached lookup data = NULL
 157 cached no match for +n1_localparts
 158 cached lookup data = NULL
 159 CALLER in "<; never2 ; +n1_localparts"? no (end of list)
 160 cached yes match for +local_localparts
 161 cached lookup data = CALLER
 162 CALLER in "+never_localparts : +n2_localparts : !+local_localparts"? no (matched "!+local_localparts" - cached)
 163 r2 router skipped: local_parts mismatch
 164 --------> r3 router <--------
 165 local_part=CALLER domain=test.ex
 166 checking local_parts
 167 cached yes match for +local_localparts
 168 cached lookup data = CALLER
 169 CALLER in "+local_localparts"? yes (matched "+local_localparts" - cached)
 170 checking for local user
 171 seeking password data for user "CALLER": using cached result
 172 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 173 calling r3 router
 174 r3 router called for CALLER@test.ex
 175   domain = test.ex
 176 set transport t1
 177 queued for t1 transport: local_part = CALLER
 178 domain = test.ex
 179   errors_to=NULL
 180   domain_data=NULL local_part_data=CALLER
 181 routed by r3 router
 182   envelope to: CALLER@test.ex
 183   transport: t1
 184 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 185 After routing:
 186   Local deliveries:
 187     CALLER@test.ex
 188   Remote deliveries:
 189   Failed addresses:
 190   Deferred addresses:
 191 search_tidyup called
 192 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 193 --------> CALLER@test.ex <--------
 194  locking TESTSUITE/spool/db/retry.lockfile
 195  locked  TESTSUITE/spool/db/retry.lockfile
 196  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 197  returned from EXIM_DBOPEN: (nil)
 198  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 199 no retry data available
 200 search_tidyup called
 201 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 202   uid=CALLER_UID gid=CALLER_GID pid=pppp
 203   home=CALLER_HOME current=CALLER_HOME
 204 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 205 appendfile transport entered
 206 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 207   file=/dev/null format=unix
 208   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 209   message_suffix=\n
 210   maildir_use_size_file=no
 211   locking by lockfile fcntl
 212 search_tidyup called
 213 journalling CALLER@test.ex
 214 t1 transport returned OK for CALLER@test.ex
 215 post-process CALLER@test.ex (0)
 216 CALLER@test.ex delivered
 217 LOG: MAIN
 218   => CALLER <CALLER@test.ex> R=r3 T=t1
 219 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 220 changed uid/gid: post-delivery tidying
 221   uid=EXIM_UID gid=EXIM_GID pid=pppp
 222 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 223 Processing retry items
 224 Succeeded addresses:
 225  CALLER@test.ex: no retry items
 226 Failed addresses:
 227 Deferred addresses:
 228 end of retry processing
 229 DSN: processing router : r3
 230 DSN: processing successful delivery address: CALLER@test.ex
 231 DSN: Sender_address: CALLER@test.ex
 232 DSN: orcpt: NULL  flags: 0x0
 233 DSN: envid: NULL  ret: 0
 234 DSN: Final recipient: CALLER@test.ex
 235 DSN: Remote SMTP server supports DSN: 0
 236 DSN: not sending DSN success message
 237 LOG: MAIN
 238   Completed
 239 end delivery of 10HmaX-0005vi-00
 240 search_tidyup called
 241 search_tidyup called
 242 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 243 search_tidyup called
 244 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 245 Exim version x.yz ....
 246 changed uid/gid: forcing real = effective
 247   uid=uuuu gid=CALLER_GID pid=pppp
 248 configuration file is TESTSUITE/test-config
 249 admin user
 250 dropping to exim gid; retaining priv uid
 251 seeking password data for user "CALLER": cache not available
 252 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 253 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 254 sender address = CALLER@test.ex
 255 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 256 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 257 Testing unknown@test.ex
 258 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 259 Considering unknown@test.ex
 260 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 261 routing unknown@test.ex
 262 --------> r1 router <--------
 263 local_part=unknown domain=test.ex
 264 checking local_parts
 265 unknown in "never"? no (end of list)
 266 unknown in "never1"? no (end of list)
 267 unknown in "CALLER"? no (end of list)
 268 unknown in "+never_localparts : +n1_localparts : ! +local_localparts"? yes (end of list)
 269 calling r1 router
 270 r1 router called for unknown@test.ex
 271   domain = test.ex
 272 route_item = never
 273 test.ex in "never"? no (end of list)
 274 r1 router declined for unknown@test.ex
 275 --------> r2 router <--------
 276 local_part=unknown domain=test.ex
 277 checking local_parts
 278 cached no match for +never_localparts
 279 cached lookup data = NULL
 280 cached no match for +n1_localparts
 281 cached lookup data = NULL
 282 unknown in "<; never2 ; +n1_localparts"? no (end of list)
 283 cached no match for +local_localparts
 284 cached lookup data = NULL
 285 unknown in "+never_localparts : +n2_localparts : !+local_localparts"? yes (end of list)
 286 calling r2 router
 287 r2 router called for unknown@test.ex
 288   domain = test.ex
 289 test.ex in "*"? yes (matched "*")
 290 DNS lookup of test.ex (MX) using fakens
 291 DNS lookup of test.ex (MX) gave NO_DATA
 292 returning DNS_NODATA
 293 faking res_search(MX) response length as 65535
 294  writing neg-cache entry for test.ex-MX-xxxx, ttl 3000
 295 test.ex (MX resp) DNSSEC
 296 DNS lookup of test.ex (A) using fakens
 297 DNS lookup of test.ex (A) gave NO_DATA
 298 returning DNS_NODATA
 299 faking res_search(A) response length as 65535
 300  writing neg-cache entry for test.ex-A-xxxx, ttl 3000
 301 r2 router declined for unknown@test.ex
 302 --------> r3 router <--------
 303 local_part=unknown domain=test.ex
 304 checking local_parts
 305 cached no match for +local_localparts
 306 cached lookup data = NULL
 307 unknown in "+local_localparts"? no (end of list)
 308 r3 router skipped: local_parts mismatch
 309 --------> r4 router <--------
 310 local_part=unknown domain=test.ex
 311 checking local_parts
 312 cached no match for +local_localparts
 313 cached lookup data = NULL
 314 unknown in "test.ex"? no (end of list)
 315 unknown in "unexpanded"? no (end of list)
 316 unknown in "+local_localparts : +expanded : +unexpanded"? no (end of list)
 317 r4 router skipped: local_parts mismatch
 318 --------> r5 router <--------
 319 local_part=unknown domain=test.ex
 320 checking local_parts
 321 cached no match for +local_localparts
 322 cached lookup data = NULL
 323 unknown in "test.ex"? no (end of list)
 324 cached no match for +unexpanded
 325 cached lookup data = NULL
 326 unknown in "+local_localparts : +expanded : +unexpanded"? no (end of list)
 327 r5 router skipped: local_parts mismatch
 328 no more routers
 329 search_tidyup called
 330 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>