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