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