| 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 | DSN: r0 propagating DSN |
| 7 | DSN: r1 propagating DSN |
| 8 | set_process_info: pppp delivering specified messages |
| 9 | set_process_info: pppp delivering 10HmaX-0005vi-00 |
| 10 | locking TESTSUITE/spool/db/retry.lockfile |
| 11 | locked TESTSUITE/spool/db/retry.lockfile |
| 12 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
| 13 | returned from EXIM_DBOPEN |
| 14 | ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim |
| 15 | no retry data available |
| 16 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 17 | Considering: x@y |
| 18 | unique = x@y |
| 19 | no domain retry record |
| 20 | no address retry record |
| 21 | x@y: queued for routing |
| 22 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 23 | routing x@y |
| 24 | --------> r0 router <-------- |
| 25 | local_part=x domain=y |
| 26 | checking local_parts |
| 27 | x in "CALLER"? no (end of list) |
| 28 | r0 router skipped: local_parts mismatch |
| 29 | --------> r1 router <-------- |
| 30 | local_part=x domain=y |
| 31 | calling r1 router |
| 32 | r1 router called for x@y |
| 33 | domain = y |
| 34 | route_item = * "127.0.0.1 : V4NET.0.0.0" |
| 35 | y in "*"? yes (matched "*") |
| 36 | original list of hosts = "127.0.0.1 : V4NET.0.0.0" options = |
| 37 | expanded list of hosts = "127.0.0.1 : V4NET.0.0.0" options = |
| 38 | set transport smtp |
| 39 | finding IP address for 127.0.0.1 |
| 40 | calling host_find_byname |
| 41 | using host_fake_gethostbyname for 127.0.0.1 (IPv4) |
| 42 | local host found for non-MX address |
| 43 | fully qualified name = 127.0.0.1 |
| 44 | MUNGED: ::1 will be omitted in what follows |
| 45 | get[host|ipnode]byname[2] looked up these IP addresses: |
| 46 | name=127.0.0.1 address=127.0.0.1 |
| 47 | remote host address is the local host: y: configured to try delivery anyway |
| 48 | finding IP address for V4NET.0.0.0 |
| 49 | calling host_find_byname |
| 50 | using host_fake_gethostbyname for V4NET.0.0.0 (IPv4) |
| 51 | fully qualified name = V4NET.0.0.0 |
| 52 | MUNGED: ::1 will be omitted in what follows |
| 53 | get[host|ipnode]byname[2] looked up these IP addresses: |
| 54 | name=V4NET.0.0.0 address=V4NET.0.0.0 |
| 55 | queued for smtp transport: local_part = x |
| 56 | domain = y |
| 57 | errors_to=NULL |
| 58 | domain_data=NULL localpart_data=NULL |
| 59 | routed by r1 router |
| 60 | envelope to: x@y |
| 61 | transport: smtp |
| 62 | host 127.0.0.1 [127.0.0.1] |
| 63 | host V4NET.0.0.0 [V4NET.0.0.0] |
| 64 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 65 | After routing: |
| 66 | Local deliveries: |
| 67 | Remote deliveries: |
| 68 | x@y |
| 69 | Failed addresses: |
| 70 | Deferred addresses: |
| 71 | search_tidyup called |
| 72 | search_tidyup called |
| 73 | changed uid/gid: remote delivery to x@y with transport=smtp |
| 74 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 75 | set_process_info: pppp delivering 10HmaX-0005vi-00 using smtp |
| 76 | checking status of 127.0.0.1 |
| 77 | locking TESTSUITE/spool/db/retry.lockfile |
| 78 | locked TESTSUITE/spool/db/retry.lockfile |
| 79 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
| 80 | returned from EXIM_DBOPEN |
| 81 | no retry data available |
| 82 | 127.0.0.1 in serialize_hosts? no (option unset) |
| 83 | set_process_info: pppp delivering 10HmaX-0005vi-00 to 127.0.0.1 [127.0.0.1] (x@y) |
| 84 | Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected |
| 85 | SMTP<< 220 Server ready |
| 86 | 127.0.0.1 in hosts_avoid_esmtp? no (option unset) |
| 87 | SMTP>> EHLO myhost.test.ex |
| 88 | cmd buf flush ddd bytes |
| 89 | SMTP<< 250 OK |
| 90 | 127.0.0.1 in hosts_require_auth? no (option unset) |
| 91 | SMTP>> MAIL FROM:<CALLER@myhost.test.ex> |
| 92 | cmd buf flush ddd bytes |
| 93 | SMTP<< 250 OK |
| 94 | SMTP>> RCPT TO:<x@y> |
| 95 | cmd buf flush ddd bytes |
| 96 | SMTP<< 451 Temporary error |
| 97 | LOG: MAIN |
| 98 | H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error |
| 99 | added retry item for R:x@y: errno=-44 more_errno=dd,A flags=0 |
| 100 | SMTP>> QUIT |
| 101 | cmd buf flush ddd bytes |
| 102 | SMTP(close)>> |
| 103 | set_process_info: pppp delivering 10HmaX-0005vi-00: just tried 127.0.0.1 [127.0.0.1] for x@y: result OK |
| 104 | address match test: subject=*@127.0.0.1 pattern=* |
| 105 | 127.0.0.1 in "*"? yes (matched "*") |
| 106 | *@127.0.0.1 in "*"? yes (matched "*") |
| 107 | checking status of V4NET.0.0.0 |
| 108 | locking TESTSUITE/spool/db/retry.lockfile |
| 109 | locked TESTSUITE/spool/db/retry.lockfile |
| 110 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
| 111 | returned from EXIM_DBOPEN |
| 112 | no retry data available |
| 113 | V4NET.0.0.0 in serialize_hosts? no (option unset) |
| 114 | set_process_info: pppp delivering 10HmaX-0005vi-00 to V4NET.0.0.0 [V4NET.0.0.0] (x@y) |
| 115 | Connecting to V4NET.0.0.0 [V4NET.0.0.0]:1224 ... failed: Network Error |
| 116 | LOG: MAIN |
| 117 | H=V4NET.0.0.0 [V4NET.0.0.0] Network Error |
| 118 | set_process_info: pppp delivering 10HmaX-0005vi-00: just tried V4NET.0.0.0 [V4NET.0.0.0] for x@y: result DEFER |
| 119 | added retry item for T:V4NET.0.0.0:V4NET.0.0.0:1224: errno=dd more_errno=dd,A flags=2 |
| 120 | set_process_info: pppp delivering 10HmaX-0005vi-00 (just run smtp for x@y in subprocess) |
| 121 | search_tidyup called |
| 122 | set_process_info: pppp delivering 10HmaX-0005vi-00: waiting for a remote delivery subprocess to finish |
| 123 | set_process_info: pppp delivering 10HmaX-0005vi-00 |
| 124 | reading retry information for T:V4NET.0.0.0:V4NET.0.0.0:1224 from subprocess |
| 125 | added retry item |
| 126 | reading retry information for R:x@y from subprocess |
| 127 | added retry item |
| 128 | LOG: MAIN |
| 129 | == x@y R=r1 T=smtp defer (dd): Network Error H=127.0.0.1 [127.0.0.1] |
| 130 | changed uid/gid: post-delivery tidying |
| 131 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 132 | set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00 |
| 133 | Processing retry items |
| 134 | Succeeded addresses: |
| 135 | Failed addresses: |
| 136 | Deferred addresses: |
| 137 | x@y |
| 138 | locking TESTSUITE/spool/db/retry.lockfile |
| 139 | locked TESTSUITE/spool/db/retry.lockfile |
| 140 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
| 141 | returned from EXIM_DBOPEN |
| 142 | opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR |
| 143 | address match test: subject=x@y pattern=* |
| 144 | y in "*"? yes (matched "*") |
| 145 | x@y in "*"? yes (matched "*") |
| 146 | retry for R:x@y = * 0 0 |
| 147 | dbfn_read: key=R:x@y |
| 148 | failing_interval=ttt message_age=ttt |
| 149 | Writing retry data for R:x@y |
| 150 | first failed=dddd last try=dddd next try=+1 expired=1 |
| 151 | errno=-44 more_errno=dd,A H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error |
| 152 | dbfn_write: key=R:x@y |
| 153 | address match test: subject=*@V4NET.0.0.0 pattern=* |
| 154 | V4NET.0.0.0 in "*"? yes (matched "*") |
| 155 | *@V4NET.0.0.0 in "*"? yes (matched "*") |
| 156 | retry for T:V4NET.0.0.0:V4NET.0.0.0:1224 (y) = * 0 0 |
| 157 | dbfn_read: key=T:V4NET.0.0.0:V4NET.0.0.0:1224 |
| 158 | failing_interval=ttt message_age=ttt |
| 159 | on queue longer than maximum retry |
| 160 | Writing retry data for T:V4NET.0.0.0:V4NET.0.0.0:1224 |
| 161 | first failed=dddd last try=dddd next try=+0 expired=0 |
| 162 | errno=dd more_errno=dd,A Network Error |
| 163 | dbfn_write: key=T:V4NET.0.0.0:V4NET.0.0.0:1224 |
| 164 | timed out: all retries expired |
| 165 | LOG: MAIN |
| 166 | ** x@y: retry timeout exceeded |
| 167 | end of retry processing |
| 168 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xebb95ced -odi -odi -t -oem -oi -f <> -E10HmaX-0005vi-00 |
| 169 | Exim version x.yz .... |
| 170 | changed uid/gid: forcing real = effective |
| 171 | uid=uuuu gid=EXIM_GID pid=pppp |
| 172 | configuration file is TESTSUITE/test-config |
| 173 | trusted user |
| 174 | admin user |
| 175 | changed uid/gid: privilege not needed |
| 176 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 177 | DSN: r0 propagating DSN |
| 178 | DSN: r1 propagating DSN |
| 179 | originator: uid=EXIM_UID gid=EXIM_GID login=EXIMUSER name= |
| 180 | sender address = |
| 181 | set_process_info: pppp accepting a local non-SMTP message from <> |
| 182 | Sender: |
| 183 | search_tidyup called |
| 184 | >>Headers received: |
| 185 | X-Failed-Recipients: x@y |
| 186 | Auto-Submitted: auto-replied |
| 187 | From: Mail Delivery System <Mailer-Daemon@myhost.test.ex> |
| 188 | To: CALLER@myhost.test.ex |
| 189 | Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM |
| 190 | MIME-Version: 1.0 |
| 191 | Subject: Mail delivery failed: returning message to sender |
| 192 | |
| 193 | rewrite_one_header: type=F: |
| 194 | From: Mail Delivery System <Mailer-Daemon@myhost.test.ex> |
| 195 | rewrite_one_header: type=T: |
| 196 | To: CALLER@myhost.test.ex |
| 197 | search_tidyup called |
| 198 | >>Headers after rewriting and local additions: |
| 199 | X-Failed-Recipients: x@y |
| 200 | Auto-Submitted: auto-replied |
| 201 | F From: Mail Delivery System <Mailer-Daemon@myhost.test.ex> |
| 202 | T To: CALLER@myhost.test.ex |
| 203 | Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM |
| 204 | MIME-Version: 1.0 |
| 205 | Subject: Mail delivery failed: returning message to sender |
| 206 | I Message-Id: <E10HmaY-0005vi-00@myhost.test.ex> |
| 207 | Date: Tue, 2 Mar 1999 09:44:33 +0000 |
| 208 | |
| 209 | Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D |
| 210 | Data file written for message 10HmaY-0005vi-00 |
| 211 | >>Generated Received: header line |
| 212 | P Received: from EXIMUSER by myhost.test.ex with local (Exim x.yz) |
| 213 | id 10HmaY-0005vi-00 |
| 214 | for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000 |
| 215 | calling local_scan(); timeout=300 |
| 216 | local_scan() returned 0 NULL |
| 217 | Writing spool header file: TESTSUITE/spool//input//hdr.pppp |
| 218 | Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H |
| 219 | Size of headers = sss |
| 220 | LOG: MAIN |
| 221 | <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss |
| 222 | search_tidyup called |
| 223 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xebb95ced -odi -Mc 10HmaY-0005vi-00 |
| 224 | Exim version x.yz .... |
| 225 | changed uid/gid: forcing real = effective |
| 226 | uid=uuuu gid=EXIM_GID pid=pppp |
| 227 | configuration file is TESTSUITE/test-config |
| 228 | trusted user |
| 229 | admin user |
| 230 | DSN: r0 propagating DSN |
| 231 | DSN: r1 propagating DSN |
| 232 | set_process_info: pppp delivering specified messages |
| 233 | set_process_info: pppp delivering 10HmaY-0005vi-00 |
| 234 | locking TESTSUITE/spool/db/retry.lockfile |
| 235 | locked TESTSUITE/spool/db/retry.lockfile |
| 236 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
| 237 | returned from EXIM_DBOPEN |
| 238 | opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY |
| 239 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 240 | Considering: CALLER@myhost.test.ex |
| 241 | unique = CALLER@myhost.test.ex |
| 242 | dbfn_read: key=R:myhost.test.ex |
| 243 | dbfn_read: key=R:CALLER@myhost.test.ex |
| 244 | dbfn_read: key=R:CALLER@myhost.test.ex:<> |
| 245 | no domain retry record |
| 246 | no address retry record |
| 247 | CALLER@myhost.test.ex: queued for routing |
| 248 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 249 | routing CALLER@myhost.test.ex |
| 250 | --------> r0 router <-------- |
| 251 | local_part=CALLER domain=myhost.test.ex |
| 252 | checking local_parts |
| 253 | CALLER in "CALLER"? yes (matched "CALLER") |
| 254 | checking senders |
| 255 | address match test: subject= pattern= |
| 256 | in ":"? yes (matched "") |
| 257 | calling r0 router |
| 258 | rda_interpret (string): :blackhole: |
| 259 | expanded: :blackhole: |
| 260 | file is not a filter file |
| 261 | parse_forward_list: :blackhole: |
| 262 | extract item: :blackhole: |
| 263 | address :blackhole:d |
| 264 | LOG: MAIN |
| 265 | => :blackhole: <CALLER@myhost.test.ex> R=r0 |
| 266 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 267 | After routing: |
| 268 | Local deliveries: |
| 269 | Remote deliveries: |
| 270 | Failed addresses: |
| 271 | Deferred addresses: |
| 272 | search_tidyup called |
| 273 | changed uid/gid: post-delivery tidying |
| 274 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 275 | set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00 |
| 276 | Processing retry items |
| 277 | Succeeded addresses: |
| 278 | CALLER@myhost.test.ex: no retry items |
| 279 | Failed addresses: |
| 280 | Deferred addresses: |
| 281 | end of retry processing |
| 282 | LOG: MAIN |
| 283 | Completed |
| 284 | search_tidyup called |
| 285 | search_tidyup called |
| 286 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> |
| 287 | search_tidyup called |
| 288 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> |
| 289 | Writing spool header file: TESTSUITE/spool//input//hdr.pppp |
| 290 | Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H |
| 291 | Size of headers = sss |
| 292 | LOG: MAIN |
| 293 | Completed |
| 294 | search_tidyup called |
| 295 | search_tidyup called |
| 296 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> |