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 localpart_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: Write SPOOL: -dsn_envid NULL
  98 DSN: Write SPOOL  :-dsn_ret 0
  99 DSN: Flags: 0x0
 100 DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 101 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 102 Size of headers = sss
 103 LOG: MAIN
 104   <= CALLER@test.ex U=CALLER P=local S=sss
 105 created log directory TESTSUITE/spool/log
 106 search_tidyup called
 107 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 108 Exim version x.yz ....
 109 changed uid/gid: forcing real = effective
 110   uid=uuuu gid=EXIM_GID pid=pppp
 111 configuration file is TESTSUITE/test-config
 112 trusted user
 113 admin user
 114 dropping to exim gid; retaining priv uid
 115 seeking password data for user "CALLER": cache not available
 116 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 117 set_process_info: pppp delivering specified messages
 118 set_process_info: pppp delivering 10HmaX-0005vi-00
 119 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 120 reading spool file 10HmaX-0005vi-00-H
 121 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 122 sender_local=1 ident=CALLER
 123 Non-recipients:
 124 Empty Tree
 125 ---- End of tree ----
 126 recipients_count=1
 127 **** SPOOL_IN - No additional fields
 128 body_linecount=0 message_linecount=7
 129 DSN: set orcpt:   flags: 0x0
 130 Delivery address list:
 131   CALLER@test.ex
 132  locking TESTSUITE/spool/db/retry.lockfile
 133  locked  TESTSUITE/spool/db/retry.lockfile
 134  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 135  returned from EXIM_DBOPEN: (nil)
 136  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 137  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
 138 no retry data available
 139 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 140 Considering: CALLER@test.ex
 141 unique = CALLER@test.ex
 142 no   domain  retry record
 143 no   address retry record
 144 CALLER@test.ex: queued for routing
 145 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 146 routing CALLER@test.ex
 147 --------> r1 router <--------
 148 local_part=CALLER domain=test.ex
 149 checking local_parts
 150 CALLER in "never"? no (end of list)
 151 CALLER in "never1"? no (end of list)
 152 CALLER in "CALLER"? yes (matched "CALLER")
 153 data from lookup saved for cache for +local_localparts: key 'CALLER' value 'CALLER'
 154 CALLER in "+never_localparts : +n1_localparts : ! +local_localparts"? no (matched "! +local_localparts")
 155 r1 router skipped: local_parts mismatch
 156 --------> r2 router <--------
 157 local_part=CALLER domain=test.ex
 158 checking local_parts
 159 cached no match for +never_localparts
 160 cached lookup data = NULL
 161 cached no match for +n1_localparts
 162 cached lookup data = NULL
 163 CALLER in "<; never2 ; +n1_localparts"? no (end of list)
 164 cached yes match for +local_localparts
 165 cached lookup data = CALLER
 166 CALLER in "+never_localparts : +n2_localparts : !+local_localparts"? no (matched "!+local_localparts" - cached)
 167 r2 router skipped: local_parts mismatch
 168 --------> r3 router <--------
 169 local_part=CALLER domain=test.ex
 170 checking local_parts
 171 cached yes match for +local_localparts
 172 cached lookup data = CALLER
 173 CALLER in "+local_localparts"? yes (matched "+local_localparts" - cached)
 174 checking for local user
 175 seeking password data for user "CALLER": using cached result
 176 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 177 calling r3 router
 178 r3 router called for CALLER@test.ex
 179   domain = test.ex
 180 set transport t1
 181 queued for t1 transport: local_part = CALLER
 182 domain = test.ex
 183   errors_to=NULL
 184   domain_data=NULL localpart_data=CALLER
 185 routed by r3 router
 186   envelope to: CALLER@test.ex
 187   transport: t1
 188 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 189 After routing:
 190   Local deliveries:
 191     CALLER@test.ex
 192   Remote deliveries:
 193   Failed addresses:
 194   Deferred addresses:
 195 search_tidyup called
 196 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 197 --------> CALLER@test.ex <--------
 198  locking TESTSUITE/spool/db/retry.lockfile
 199  locked  TESTSUITE/spool/db/retry.lockfile
 200  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 201  returned from EXIM_DBOPEN: (nil)
 202  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 203 no retry data available
 204 search_tidyup called
 205 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 206   uid=CALLER_UID gid=CALLER_GID pid=pppp
 207   home=CALLER_HOME current=CALLER_HOME
 208 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 209 appendfile transport entered
 210 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 211   file=/dev/null format=unix
 212   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 213   message_suffix=\n
 214   maildir_use_size_file=no
 215   locking by lockfile fcntl
 216 search_tidyup called
 217 journalling CALLER@test.ex
 218 t1 transport returned OK for CALLER@test.ex
 219 post-process CALLER@test.ex (0)
 220 CALLER@test.ex delivered
 221 LOG: MAIN
 222   => CALLER <CALLER@test.ex> R=r3 T=t1
 223 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 224 changed uid/gid: post-delivery tidying
 225   uid=EXIM_UID gid=EXIM_GID pid=pppp
 226 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 227 Processing retry items
 228 Succeeded addresses:
 229  CALLER@test.ex: no retry items
 230 Failed addresses:
 231 Deferred addresses:
 232 end of retry processing
 233 DSN: processing router : r3
 234 DSN: processing successful delivery address: CALLER@test.ex
 235 DSN: Sender_address: CALLER@test.ex
 236 DSN: orcpt: NULL  flags: 0x0
 237 DSN: envid: NULL  ret: 0
 238 DSN: Final recipient: CALLER@test.ex
 239 DSN: Remote SMTP server supports DSN: 0
 240 DSN: not sending DSN success message
 241 LOG: MAIN
 242   Completed
 243 end delivery of 10HmaX-0005vi-00
 244 search_tidyup called
 245 search_tidyup called
 246 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 247 search_tidyup called
 248 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 249 Exim version x.yz ....
 250 changed uid/gid: forcing real = effective
 251   uid=uuuu gid=CALLER_GID pid=pppp
 252 configuration file is TESTSUITE/test-config
 253 admin user
 254 dropping to exim gid; retaining priv uid
 255 seeking password data for user "CALLER": cache not available
 256 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 257 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 258 sender address = CALLER@test.ex
 259 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 260 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 261 Testing unknown@test.ex
 262 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 263 Considering unknown@test.ex
 264 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 265 routing unknown@test.ex
 266 --------> r1 router <--------
 267 local_part=unknown domain=test.ex
 268 checking local_parts
 269 unknown in "never"? no (end of list)
 270 unknown in "never1"? no (end of list)
 271 unknown in "CALLER"? no (end of list)
 272 unknown in "+never_localparts : +n1_localparts : ! +local_localparts"? yes (end of list)
 273 calling r1 router
 274 r1 router called for unknown@test.ex
 275   domain = test.ex
 276 route_item = never
 277 test.ex in "never"? no (end of list)
 278 r1 router declined for unknown@test.ex
 279 --------> r2 router <--------
 280 local_part=unknown domain=test.ex
 281 checking local_parts
 282 cached no match for +never_localparts
 283 cached lookup data = NULL
 284 cached no match for +n1_localparts
 285 cached lookup data = NULL
 286 unknown in "<; never2 ; +n1_localparts"? no (end of list)
 287 cached no match for +local_localparts
 288 cached lookup data = NULL
 289 unknown in "+never_localparts : +n2_localparts : !+local_localparts"? yes (end of list)
 290 calling r2 router
 291 r2 router called for unknown@test.ex
 292   domain = test.ex
 293 test.ex in "*"? yes (matched "*")
 294 DNS lookup of test.ex (MX) using fakens
 295 DNS lookup of test.ex (MX) gave NO_DATA
 296 returning DNS_NODATA
 297 faking res_search(MX) response length as 65535
 298  writing neg-cache entry for test.ex-MX-xxxx, ttl 3000
 299 test.ex (MX resp) DNSSEC
 300 DNS lookup of test.ex (A) using fakens
 301 DNS lookup of test.ex (A) gave NO_DATA
 302 returning DNS_NODATA
 303 faking res_search(A) response length as 65535
 304  writing neg-cache entry for test.ex-A-xxxx, ttl 3000
 305 r2 router declined for unknown@test.ex
 306 --------> r3 router <--------
 307 local_part=unknown domain=test.ex
 308 checking local_parts
 309 cached no match for +local_localparts
 310 cached lookup data = NULL
 311 unknown in "+local_localparts"? no (end of list)
 312 r3 router skipped: local_parts mismatch
 313 --------> r4 router <--------
 314 local_part=unknown domain=test.ex
 315 checking local_parts
 316 cached no match for +local_localparts
 317 cached lookup data = NULL
 318 unknown in "test.ex"? no (end of list)
 319 unknown in "unexpanded"? no (end of list)
 320 unknown in "+local_localparts : +expanded : +unexpanded"? no (end of list)
 321 r4 router skipped: local_parts mismatch
 322 --------> r5 router <--------
 323 local_part=unknown domain=test.ex
 324 checking local_parts
 325 cached no match for +local_localparts
 326 cached lookup data = NULL
 327 unknown in "test.ex"? no (end of list)
 328 cached no match for +unexpanded
 329 cached lookup data = NULL
 330 unknown in "+local_localparts : +expanded : +unexpanded"? no (end of list)
 331 r5 router skipped: local_parts mismatch
 332 no more routers
 333 search_tidyup called
 334 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=2 >>>>>>>>>>>>>>>>