| 1 | Exim version x.yz .... |
| 2 | changed uid/gid: forcing real = effective |
| 3 | uid=uuuu gid=CALLER_GID pid=pppp |
| 4 | seeking password data for user "CALLER": cache not available |
| 5 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 6 | configuration file is TESTSUITE/test-config |
| 7 | trusted user |
| 8 | admin user |
| 9 | changed uid/gid: privilege not needed |
| 10 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 11 | seeking password data for user "CALLER": using cached result |
| 12 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 13 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
| 14 | sender address = CALLER@test.ex |
| 15 | sender_fullhost = [V4NET.9.8.7] |
| 16 | sender_rcvhost = [V4NET.9.8.7] |
| 17 | host in hosts_connection_nolog? no (option unset) |
| 18 | LOG: smtp_connection MAIN |
| 19 | SMTP connection from [V4NET.9.8.7] |
| 20 | host in host_lookup? no (option unset) |
| 21 | set_process_info: pppp handling incoming connection from [V4NET.9.8.7] |
| 22 | host in host_reject_connection? no (option unset) |
| 23 | host in sender_unqualified_hosts? no (option unset) |
| 24 | host in recipient_unqualified_hosts? no (option unset) |
| 25 | host in helo_verify_hosts? no (option unset) |
| 26 | host in helo_try_verify_hosts? no (option unset) |
| 27 | host in helo_accept_junk_hosts? no (option unset) |
| 28 | SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 |
| 29 | smtp_setup_msg entered |
| 30 | SMTP<< mail from:<x@y> |
| 31 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 32 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 33 | SMTP>> 250 OK |
| 34 | SMTP<< rcpt to:<1@b> |
| 35 | using ACL "chk_rcpt" |
| 36 | processing "accept" (TESTSUITE/test-config 18) |
| 37 | check local_parts = 1 |
| 38 | 1 in "1"? yes (matched "1") |
| 39 | check acl = TESTSUITE/aux-fixed/0386.acl1 |
| 40 | read ACL from file TESTSUITE/aux-fixed/0386.acl1 |
| 41 | processing "accept" (TESTSUITE/test-config 43) |
| 42 | check hosts = : |
| 43 | host in ":"? no (end of list) |
| 44 | accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 45 | processing "deny" (TESTSUITE/test-config 43) |
| 46 | check local_parts = ^.*[@%!/|] |
| 47 | 1 in "^.*[@%!/|]"? no (end of list) |
| 48 | deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 49 | processing "require" (TESTSUITE/test-config 43) |
| 50 | l_message: Invalid sender |
| 51 | message: Couldn't verify the sender |
| 52 | check verify = sender/defer_ok |
| 53 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 54 | Verifying x@y |
| 55 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 56 | Considering x@y |
| 57 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 58 | routing x@y |
| 59 | --------> r1 router <-------- |
| 60 | local_part=x domain=y |
| 61 | calling r1 router |
| 62 | r1 router called for x@y |
| 63 | domain = y |
| 64 | set transport t1 |
| 65 | queued for t1 transport: local_part = x |
| 66 | domain = y |
| 67 | errors_to=NULL |
| 68 | domain_data=NULL localpart_data=NULL |
| 69 | routed by r1 router |
| 70 | envelope to: x@y |
| 71 | transport: t1 |
| 72 | ----------- end verify ------------ |
| 73 | sender x@y verified ok |
| 74 | require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 75 | processing "deny" (TESTSUITE/test-config 43) |
| 76 | message: No such user here |
| 77 | deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 78 | end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY |
| 79 | accept: condition test failed in ACL "chk_rcpt" |
| 80 | accept: endpass encountered - denying access |
| 81 | SMTP>> 550 No such user here |
| 82 | LOG: MAIN REJECT |
| 83 | H=[V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here |
| 84 | SMTP<< rset |
| 85 | SMTP>> 250 Reset OK |
| 86 | SMTP<< mail from:<x@y> |
| 87 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 88 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 89 | SMTP>> 250 OK |
| 90 | SMTP<< rcpt to:<1@b> |
| 91 | using ACL "chk_rcpt" |
| 92 | processing "accept" (TESTSUITE/test-config 18) |
| 93 | check local_parts = 1 |
| 94 | 1 in "1"? yes (matched "1") |
| 95 | check acl = TESTSUITE/aux-fixed/0386.acl1 |
| 96 | using ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 97 | processing "accept" (TESTSUITE/test-config 43) |
| 98 | check hosts = : |
| 99 | host in ":"? no (end of list) |
| 100 | accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 101 | processing "deny" (TESTSUITE/test-config 43) |
| 102 | check local_parts = ^.*[@%!/|] |
| 103 | 1 in "^.*[@%!/|]"? no (end of list) |
| 104 | deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 105 | processing "require" (TESTSUITE/test-config 43) |
| 106 | l_message: Invalid sender |
| 107 | message: Couldn't verify the sender |
| 108 | check verify = sender/defer_ok |
| 109 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 110 | Verifying x@y |
| 111 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 112 | Considering x@y |
| 113 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 114 | routing x@y |
| 115 | --------> r1 router <-------- |
| 116 | local_part=x domain=y |
| 117 | calling r1 router |
| 118 | r1 router called for x@y |
| 119 | domain = y |
| 120 | queued for t1 transport: local_part = x |
| 121 | domain = y |
| 122 | errors_to=NULL |
| 123 | domain_data=NULL localpart_data=NULL |
| 124 | routed by r1 router |
| 125 | envelope to: x@y |
| 126 | transport: t1 |
| 127 | ----------- end verify ------------ |
| 128 | sender x@y verified ok |
| 129 | require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 130 | processing "deny" (TESTSUITE/test-config 43) |
| 131 | message: No such user here |
| 132 | deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
| 133 | end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY |
| 134 | accept: condition test failed in ACL "chk_rcpt" |
| 135 | accept: endpass encountered - denying access |
| 136 | SMTP>> 550 No such user here |
| 137 | LOG: MAIN REJECT |
| 138 | H=[V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here |
| 139 | SMTP<< quit |
| 140 | SMTP>> 221 myhost.test.ex closing connection |
| 141 | LOG: smtp_connection MAIN |
| 142 | SMTP connection from [V4NET.9.8.7] closed by QUIT |
| 143 | search_tidyup called |
| 144 | >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 145 | Exim version x.yz .... |
| 146 | changed uid/gid: forcing real = effective |
| 147 | uid=uuuu gid=CALLER_GID pid=pppp |
| 148 | seeking password data for user "CALLER": cache not available |
| 149 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 150 | configuration file is TESTSUITE/test-config |
| 151 | trusted user |
| 152 | admin user |
| 153 | changed uid/gid: privilege not needed |
| 154 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 155 | seeking password data for user "CALLER": using cached result |
| 156 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 157 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
| 158 | sender address = NULL |
| 159 | sender_fullhost = [V4NET.11.12.13] |
| 160 | sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) |
| 161 | set_process_info: pppp handling incoming connection from [V4NET.11.12.13] via -oMa |
| 162 | set_process_info: pppp accepting a local SMTP message from <CALLER> |
| 163 | host in hosts_connection_nolog? no (option unset) |
| 164 | LOG: smtp_connection MAIN |
| 165 | SMTP connection from CALLER |
| 166 | host in host_lookup? no (option unset) |
| 167 | set_process_info: pppp handling incoming connection from [V4NET.11.12.13] U=CALLER |
| 168 | host in host_reject_connection? no (option unset) |
| 169 | host in sender_unqualified_hosts? no (option unset) |
| 170 | host in recipient_unqualified_hosts? no (option unset) |
| 171 | host in helo_verify_hosts? no (option unset) |
| 172 | host in helo_try_verify_hosts? no (option unset) |
| 173 | host in helo_accept_junk_hosts? no (option unset) |
| 174 | SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 |
| 175 | smtp_setup_msg entered |
| 176 | SMTP<< mail from:<x@y> |
| 177 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 178 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 179 | SMTP>> 250 OK |
| 180 | SMTP<< rcpt to:<2@b> |
| 181 | using ACL "chk_rcpt" |
| 182 | processing "accept" (TESTSUITE/test-config 18) |
| 183 | check local_parts = 1 |
| 184 | 2 in "1"? no (end of list) |
| 185 | accept: condition test failed in ACL "chk_rcpt" |
| 186 | processing "accept" (TESTSUITE/test-config 21) |
| 187 | check local_parts = 2 |
| 188 | 2 in "2"? yes (matched "2") |
| 189 | check acl = TESTSUITE/aux-fixed/0386.acl2 |
| 190 | read ACL from file TESTSUITE/aux-fixed/0386.acl2 |
| 191 | processing "warn" (TESTSUITE/test-config 43) |
| 192 | message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text |
| 193 | l_message: found in $dnslist_domain: $dnslist_text |
| 194 | check dnslists = rbl.test.ex |
| 195 | dnslists check: rbl.test.ex |
| 196 | new DNS lookup for 13.12.11.V4NET.rbl.test.ex |
| 197 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens |
| 198 | fresh-exec forking for fakens-search |
| 199 | postfork: fakens-search |
| 200 | fresh-exec forked for fakens-search: npppp |
| 201 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded |
| 202 | dnslists: wrote cache entry, ttl=3 |
| 203 | DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2) |
| 204 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens |
| 205 | fresh-exec forking for fakens-search |
| 206 | postfork: fakens-search |
| 207 | fresh-exec forked for fakens-search: npppp |
| 208 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded |
| 209 | => that means V4NET.11.12.13 is listed at rbl.test.ex |
| 210 | warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
| 211 | LOG: MAIN |
| 212 | H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message |
| 213 | created log directory TESTSUITE/spool/log |
| 214 | processing "accept" (TESTSUITE/test-config 43) |
| 215 | accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
| 216 | end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT |
| 217 | accept: condition test succeeded in ACL "chk_rcpt" |
| 218 | end of ACL "chk_rcpt": ACCEPT |
| 219 | SMTP>> 250 Accepted |
| 220 | DSN: orcpt: NULL flags: 0 |
| 221 | SMTP<< data |
| 222 | SMTP>> 354 Enter message, ending with "." on a line by itself |
| 223 | search_tidyup called |
| 224 | >>Headers received: |
| 225 | |
| 226 | search_tidyup called |
| 227 | >>Headers after rewriting and local additions: |
| 228 | |
| 229 | Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D |
| 230 | Data file written for message 10HmaX-0005vi-00 |
| 231 | >>Generated Received: header line |
| 232 | P Received: from [V4NET.11.12.13] (ident=CALLER) |
| 233 | by myhost.test.ex with smtp (Exim x.yz) |
| 234 | (envelope-from <x@y>) |
| 235 | id 10HmaX-0005vi-00 |
| 236 | for 2@b; Tue, 2 Mar 1999 09:44:33 +0000 |
| 237 | >>Headers added by MAIL or RCPT ACL: |
| 238 | X-Warning: V4NET.11.12.13 is listed at rbl.test.ex |
| 239 | X-Warning: This is a test blacklisting message |
| 240 | >> |
| 241 | Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00 |
| 242 | DSN: Write SPOOL: -dsn_envid NULL |
| 243 | DSN: Write SPOOL :-dsn_ret 0 |
| 244 | DSN: Flags: 0x0 |
| 245 | DSN: **** SPOOL_OUT - address: <2@b> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 246 | Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H |
| 247 | Size of headers = sss |
| 248 | LOG: MAIN |
| 249 | <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss |
| 250 | SMTP>> 250 OK id=10HmaX-0005vi-00 |
| 251 | search_tidyup called |
| 252 | fresh-exec forking for local-accept-delivery |
| 253 | postfork: local-accept-delivery |
| 254 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00 |
| 255 | fresh-exec forked for local-accept-delivery: npppp |
| 256 | Exim version x.yz .... |
| 257 | changed uid/gid: forcing real = effective |
| 258 | uid=uuuu gid=EXIM_GID pid=pppp |
| 259 | seeking password data for user "CALLER": cache not available |
| 260 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 261 | configuration file is TESTSUITE/test-config |
| 262 | trusted user |
| 263 | admin user |
| 264 | dropping to exim gid; retaining priv uid |
| 265 | seeking password data for user "CALLER": using cached result |
| 266 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 267 | set_process_info: pppp delivering specified messages |
| 268 | set_process_info: pppp delivering 10HmaX-0005vi-00 |
| 269 | Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D |
| 270 | reading spool file 10HmaX-0005vi-00-H |
| 271 | user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y |
| 272 | sender_fullhost = [V4NET.11.12.13] |
| 273 | sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) |
| 274 | sender_local=0 ident=CALLER |
| 275 | Non-recipients: |
| 276 | Empty Tree |
| 277 | ---- End of tree ---- |
| 278 | recipients_count=1 |
| 279 | **** SPOOL_IN - No additional fields |
| 280 | body_linecount=1 message_linecount=7 |
| 281 | DSN: set orcpt: flags: 0x0 |
| 282 | Delivery address list: |
| 283 | 2@b |
| 284 | locking TESTSUITE/spool/db/retry.lockfile |
| 285 | locked TESTSUITE/spool/db/retry.lockfile |
| 286 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 287 | returned from EXIM_DBOPEN: (nil) |
| 288 | ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim |
| 289 | failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory |
| 290 | no retry data available |
| 291 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 292 | Considering: 2@b |
| 293 | unique = 2@b |
| 294 | no domain retry record |
| 295 | no address retry record |
| 296 | 2@b: queued for routing |
| 297 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 298 | routing 2@b |
| 299 | --------> r1 router <-------- |
| 300 | local_part=2 domain=b |
| 301 | calling r1 router |
| 302 | r1 router called for 2@b |
| 303 | domain = b |
| 304 | set transport t1 |
| 305 | queued for t1 transport: local_part = 2 |
| 306 | domain = b |
| 307 | errors_to=NULL |
| 308 | domain_data=NULL localpart_data=NULL |
| 309 | routed by r1 router |
| 310 | envelope to: 2@b |
| 311 | transport: t1 |
| 312 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 313 | After routing: |
| 314 | Local deliveries: |
| 315 | 2@b |
| 316 | Remote deliveries: |
| 317 | Failed addresses: |
| 318 | Deferred addresses: |
| 319 | search_tidyup called |
| 320 | >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> |
| 321 | --------> 2@b <-------- |
| 322 | locking TESTSUITE/spool/db/retry.lockfile |
| 323 | locked TESTSUITE/spool/db/retry.lockfile |
| 324 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 325 | returned from EXIM_DBOPEN: (nil) |
| 326 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 327 | no retry data available |
| 328 | search_tidyup called |
| 329 | local-accept-delivery forking for delivery-local |
| 330 | postfork: delivery-local |
| 331 | changed uid/gid: local delivery to 2 <2@b> transport=t1 |
| 332 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 333 | home=NULL current=/ |
| 334 | set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1 |
| 335 | appendfile transport entered |
| 336 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 337 | file=TESTSUITE/test-mail/2 format=unix |
| 338 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 339 | message_suffix=\n |
| 340 | maildir_use_size_file=no |
| 341 | locking by lockfile fcntl |
| 342 | lock name: TESTSUITE/test-mail/2.lock |
| 343 | hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp |
| 344 | lock file created |
| 345 | mailbox TESTSUITE/test-mail/2 is locked |
| 346 | writing to file TESTSUITE/test-mail/2 |
| 347 | writing data block fd=dddd size=sss timeout=0 |
| 348 | cannot use sendfile for body: spoolfile not wireformat |
| 349 | writing data block fd=dddd size=sss timeout=0 |
| 350 | writing data block fd=dddd size=sss timeout=0 |
| 351 | appendfile yields 0 with errno=dd more_errno=dd |
| 352 | search_tidyup called |
| 353 | local-accept-delivery forked for delivery-local: npppp |
| 354 | journalling 2@b |
| 355 | t1 transport returned OK for 2@b |
| 356 | post-process 2@b (0) |
| 357 | 2@b delivered |
| 358 | LOG: MAIN |
| 359 | => 2 <2@b> R=r1 T=t1 |
| 360 | >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> |
| 361 | changed uid/gid: post-delivery tidying |
| 362 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 363 | set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00 |
| 364 | Processing retry items |
| 365 | Succeeded addresses: |
| 366 | 2@b: no retry items |
| 367 | Failed addresses: |
| 368 | Deferred addresses: |
| 369 | end of retry processing |
| 370 | DSN: processing router : r1 |
| 371 | DSN: processing successful delivery address: 2@b |
| 372 | DSN: Sender_address: x@y |
| 373 | DSN: orcpt: NULL flags: 0x0 |
| 374 | DSN: envid: NULL ret: 0 |
| 375 | DSN: Final recipient: 2@b |
| 376 | DSN: Remote SMTP server supports DSN: 0 |
| 377 | DSN: not sending DSN success message |
| 378 | LOG: MAIN |
| 379 | Completed |
| 380 | end delivery of 10HmaX-0005vi-00 |
| 381 | search_tidyup called |
| 382 | search_tidyup called |
| 383 | >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 384 | smtp_setup_msg entered |
| 385 | SMTP<< rset |
| 386 | SMTP>> 250 Reset OK |
| 387 | SMTP<< mail from:<x@y> |
| 388 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 389 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 390 | SMTP>> 250 OK |
| 391 | SMTP<< rcpt to:<2@b> |
| 392 | using ACL "chk_rcpt" |
| 393 | processing "accept" (TESTSUITE/test-config 18) |
| 394 | check local_parts = 1 |
| 395 | 2 in "1"? no (end of list) |
| 396 | accept: condition test failed in ACL "chk_rcpt" |
| 397 | processing "accept" (TESTSUITE/test-config 21) |
| 398 | check local_parts = 2 |
| 399 | 2 in "2"? yes (matched "2") |
| 400 | check acl = TESTSUITE/aux-fixed/0386.acl2 |
| 401 | using ACL "TESTSUITE/aux-fixed/0386.acl2" |
| 402 | processing "warn" (TESTSUITE/test-config 43) |
| 403 | message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text |
| 404 | l_message: found in $dnslist_domain: $dnslist_text |
| 405 | check dnslists = rbl.test.ex |
| 406 | dnslists check: rbl.test.ex |
| 407 | dnslists: using result of previous lookup |
| 408 | DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2) |
| 409 | => that means V4NET.11.12.13 is listed at rbl.test.ex |
| 410 | warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
| 411 | LOG: MAIN |
| 412 | H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message |
| 413 | processing "accept" (TESTSUITE/test-config 43) |
| 414 | accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
| 415 | end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT |
| 416 | accept: condition test succeeded in ACL "chk_rcpt" |
| 417 | end of ACL "chk_rcpt": ACCEPT |
| 418 | SMTP>> 250 Accepted |
| 419 | DSN: orcpt: NULL flags: 0 |
| 420 | SMTP<< data |
| 421 | SMTP>> 354 Enter message, ending with "." on a line by itself |
| 422 | search_tidyup called |
| 423 | >>Headers received: |
| 424 | |
| 425 | search_tidyup called |
| 426 | >>Headers after rewriting and local additions: |
| 427 | |
| 428 | Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D |
| 429 | Data file written for message 10HmaY-0005vi-00 |
| 430 | >>Generated Received: header line |
| 431 | P Received: from [V4NET.11.12.13] (ident=CALLER) |
| 432 | by myhost.test.ex with smtp (Exim x.yz) |
| 433 | (envelope-from <x@y>) |
| 434 | id 10HmaY-0005vi-00 |
| 435 | for 2@b; Tue, 2 Mar 1999 09:44:33 +0000 |
| 436 | >>Headers added by MAIL or RCPT ACL: |
| 437 | X-Warning: V4NET.11.12.13 is listed at rbl.test.ex |
| 438 | X-Warning: This is a test blacklisting message |
| 439 | >> |
| 440 | Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-0005vi-00 |
| 441 | DSN: Write SPOOL: -dsn_envid NULL |
| 442 | DSN: Write SPOOL :-dsn_ret 0 |
| 443 | DSN: Flags: 0x0 |
| 444 | DSN: **** SPOOL_OUT - address: <2@b> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 445 | Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H |
| 446 | Size of headers = sss |
| 447 | LOG: MAIN |
| 448 | <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss |
| 449 | SMTP>> 250 OK id=10HmaY-0005vi-00 |
| 450 | search_tidyup called |
| 451 | fresh-exec forking for local-accept-delivery |
| 452 | postfork: local-accept-delivery |
| 453 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaY-0005vi-00 |
| 454 | fresh-exec forked for local-accept-delivery: npppp |
| 455 | Exim version x.yz .... |
| 456 | changed uid/gid: forcing real = effective |
| 457 | uid=uuuu gid=EXIM_GID pid=pppp |
| 458 | seeking password data for user "CALLER": cache not available |
| 459 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 460 | configuration file is TESTSUITE/test-config |
| 461 | trusted user |
| 462 | admin user |
| 463 | dropping to exim gid; retaining priv uid |
| 464 | seeking password data for user "CALLER": using cached result |
| 465 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 466 | set_process_info: pppp delivering specified messages |
| 467 | set_process_info: pppp delivering 10HmaY-0005vi-00 |
| 468 | Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D |
| 469 | reading spool file 10HmaY-0005vi-00-H |
| 470 | user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y |
| 471 | sender_fullhost = [V4NET.11.12.13] |
| 472 | sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) |
| 473 | sender_local=0 ident=CALLER |
| 474 | Non-recipients: |
| 475 | Empty Tree |
| 476 | ---- End of tree ---- |
| 477 | recipients_count=1 |
| 478 | **** SPOOL_IN - No additional fields |
| 479 | body_linecount=1 message_linecount=7 |
| 480 | DSN: set orcpt: flags: 0x0 |
| 481 | Delivery address list: |
| 482 | 2@b |
| 483 | locking TESTSUITE/spool/db/retry.lockfile |
| 484 | locked TESTSUITE/spool/db/retry.lockfile |
| 485 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 486 | returned from EXIM_DBOPEN: (nil) |
| 487 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 488 | no retry data available |
| 489 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 490 | Considering: 2@b |
| 491 | unique = 2@b |
| 492 | no domain retry record |
| 493 | no address retry record |
| 494 | 2@b: queued for routing |
| 495 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 496 | routing 2@b |
| 497 | --------> r1 router <-------- |
| 498 | local_part=2 domain=b |
| 499 | calling r1 router |
| 500 | r1 router called for 2@b |
| 501 | domain = b |
| 502 | set transport t1 |
| 503 | queued for t1 transport: local_part = 2 |
| 504 | domain = b |
| 505 | errors_to=NULL |
| 506 | domain_data=NULL localpart_data=NULL |
| 507 | routed by r1 router |
| 508 | envelope to: 2@b |
| 509 | transport: t1 |
| 510 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 511 | After routing: |
| 512 | Local deliveries: |
| 513 | 2@b |
| 514 | Remote deliveries: |
| 515 | Failed addresses: |
| 516 | Deferred addresses: |
| 517 | search_tidyup called |
| 518 | >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> |
| 519 | --------> 2@b <-------- |
| 520 | locking TESTSUITE/spool/db/retry.lockfile |
| 521 | locked TESTSUITE/spool/db/retry.lockfile |
| 522 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 523 | returned from EXIM_DBOPEN: (nil) |
| 524 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 525 | no retry data available |
| 526 | search_tidyup called |
| 527 | local-accept-delivery forking for delivery-local |
| 528 | postfork: delivery-local |
| 529 | changed uid/gid: local delivery to 2 <2@b> transport=t1 |
| 530 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 531 | home=NULL current=/ |
| 532 | set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1 |
| 533 | appendfile transport entered |
| 534 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 535 | file=TESTSUITE/test-mail/2 format=unix |
| 536 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 537 | message_suffix=\n |
| 538 | maildir_use_size_file=no |
| 539 | locking by lockfile fcntl |
| 540 | lock name: TESTSUITE/test-mail/2.lock |
| 541 | hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp |
| 542 | lock file created |
| 543 | mailbox TESTSUITE/test-mail/2 is locked |
| 544 | writing to file TESTSUITE/test-mail/2 |
| 545 | writing data block fd=dddd size=sss timeout=0 |
| 546 | cannot use sendfile for body: spoolfile not wireformat |
| 547 | writing data block fd=dddd size=sss timeout=0 |
| 548 | writing data block fd=dddd size=sss timeout=0 |
| 549 | appendfile yields 0 with errno=dd more_errno=dd |
| 550 | search_tidyup called |
| 551 | local-accept-delivery forked for delivery-local: npppp |
| 552 | journalling 2@b |
| 553 | t1 transport returned OK for 2@b |
| 554 | post-process 2@b (0) |
| 555 | 2@b delivered |
| 556 | LOG: MAIN |
| 557 | => 2 <2@b> R=r1 T=t1 |
| 558 | >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> |
| 559 | changed uid/gid: post-delivery tidying |
| 560 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 561 | set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00 |
| 562 | Processing retry items |
| 563 | Succeeded addresses: |
| 564 | 2@b: no retry items |
| 565 | Failed addresses: |
| 566 | Deferred addresses: |
| 567 | end of retry processing |
| 568 | DSN: processing router : r1 |
| 569 | DSN: processing successful delivery address: 2@b |
| 570 | DSN: Sender_address: x@y |
| 571 | DSN: orcpt: NULL flags: 0x0 |
| 572 | DSN: envid: NULL ret: 0 |
| 573 | DSN: Final recipient: 2@b |
| 574 | DSN: Remote SMTP server supports DSN: 0 |
| 575 | DSN: not sending DSN success message |
| 576 | LOG: MAIN |
| 577 | Completed |
| 578 | end delivery of 10HmaY-0005vi-00 |
| 579 | search_tidyup called |
| 580 | search_tidyup called |
| 581 | >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 582 | smtp_setup_msg entered |
| 583 | SMTP<< quit |
| 584 | SMTP>> 221 myhost.test.ex closing connection |
| 585 | LOG: smtp_connection MAIN |
| 586 | SMTP connection from CALLER closed by QUIT |
| 587 | search_tidyup called |
| 588 | >>>>>>>>>>>>>>>> Exim pid=pppp (msg setup toplevel) terminating with rc=0 >>>>>>>>>>>>>>>> |