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