test/stderr/0361

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