| 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 userx@test.ex |
| 14 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 15 | Considering userx@test.ex |
| 16 | test.ex in "! *.ex"? no (matched "! *.ex") |
| 17 | test.ex in "test.ex"? yes (matched "test.ex") |
| 18 | test.ex in percent_hack_domains? yes (matched "+not_queue_domains") |
| 19 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 20 | routing userx@test.ex |
| 21 | --------> r00 router <-------- |
| 22 | local_part=userx domain=test.ex |
| 23 | checking domains |
| 24 | test.ex in "userx"? no (end of list) |
| 25 | test.ex in "+nocache"? no (end of list) |
| 26 | r00 router skipped: domains mismatch |
| 27 | --------> r01 router <-------- |
| 28 | local_part=userx domain=test.ex |
| 29 | checking domains |
| 30 | test.ex in "userx"? no (end of list) |
| 31 | test.ex in "+nocache"? no (end of list) |
| 32 | r01 router skipped: domains mismatch |
| 33 | --------> r02 router <-------- |
| 34 | local_part=userx domain=test.ex |
| 35 | checking domains |
| 36 | test.ex in "userx"? no (end of list) |
| 37 | test.ex in "+nocache"? no (end of list) |
| 38 | test.ex in "+nocache2"? no (end of list) |
| 39 | r02 router skipped: domains mismatch |
| 40 | --------> r03 router <-------- |
| 41 | local_part=userx domain=test.ex |
| 42 | checking domains |
| 43 | test.ex in "userx"? no (end of list) |
| 44 | test.ex in "+nocache"? no (end of list) |
| 45 | test.ex in "+nocache2"? no (end of list) |
| 46 | r03 router skipped: domains mismatch |
| 47 | --------> r04 router <-------- |
| 48 | local_part=userx domain=test.ex |
| 49 | checking domains |
| 50 | test.ex in "userx"? no (end of list) |
| 51 | test.ex in "+forcecache"? no (end of list) |
| 52 | r04 router skipped: domains mismatch |
| 53 | --------> r05 router <-------- |
| 54 | local_part=userx domain=test.ex |
| 55 | checking domains |
| 56 | cached no match for +forcecache |
| 57 | cached lookup data = NULL |
| 58 | test.ex in "+forcecache"? no (end of list) |
| 59 | r05 router skipped: domains mismatch |
| 60 | --------> r1 router <-------- |
| 61 | local_part=userx domain=test.ex |
| 62 | checking domains |
| 63 | test.ex in "never.ex"? no (end of list) |
| 64 | test.ex in "never1.ex"? no (end of list) |
| 65 | test.ex in "test.ex"? yes (matched "test.ex") |
| 66 | test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains") |
| 67 | r1 router skipped: domains mismatch |
| 68 | --------> r2 router <-------- |
| 69 | local_part=userx domain=test.ex |
| 70 | checking domains |
| 71 | cached no match for +never_domains |
| 72 | cached lookup data = NULL |
| 73 | cached no match for +n1_domains |
| 74 | cached lookup data = NULL |
| 75 | test.ex in "<; never2.ex ; +n1_domains"? no (end of list) |
| 76 | cached yes match for +local_domains |
| 77 | cached lookup data = NULL |
| 78 | test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached) |
| 79 | r2 router skipped: domains mismatch |
| 80 | --------> r3 router <-------- |
| 81 | local_part=userx domain=test.ex |
| 82 | checking domains |
| 83 | cached yes match for +local_domains |
| 84 | cached lookup data = NULL |
| 85 | test.ex in "+local_domains"? yes (matched "+local_domains" - cached) |
| 86 | checking local_parts |
| 87 | userx in "userx"? yes (matched "userx") |
| 88 | calling r3 router |
| 89 | r3 router called for userx@test.ex |
| 90 | domain = test.ex |
| 91 | set transport t1 |
| 92 | queued for t1 transport: local_part = userx |
| 93 | domain = test.ex |
| 94 | errors_to=NULL |
| 95 | domain_data=NULL localpart_data=NULL |
| 96 | routed by r3 router |
| 97 | envelope to: userx@test.ex |
| 98 | transport: t1 |
| 99 | search_tidyup called |
| 100 | >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 101 | Exim version x.yz .... |
| 102 | changed uid/gid: forcing real = effective |
| 103 | uid=uuuu gid=CALLER_GID pid=pppp |
| 104 | configuration file is TESTSUITE/test-config |
| 105 | admin user |
| 106 | changed uid/gid: privilege not needed |
| 107 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 108 | seeking password data for user "CALLER": cache not available |
| 109 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 110 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
| 111 | sender address = CALLER@test.ex |
| 112 | set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex> |
| 113 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 114 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 115 | Sender: CALLER@test.ex |
| 116 | Recipients: |
| 117 | userx@test.ex |
| 118 | search_tidyup called |
| 119 | >>Headers received: |
| 120 | |
| 121 | rewrite_one_header: type=F: |
| 122 | From: CALLER_NAME <CALLER@test.ex> |
| 123 | search_tidyup called |
| 124 | >>Headers after rewriting and local additions: |
| 125 | I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex> |
| 126 | F From: CALLER_NAME <CALLER@test.ex> |
| 127 | Date: Tue, 2 Mar 1999 09:44:33 +0000 |
| 128 | |
| 129 | Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D |
| 130 | Data file written for message 10HmaX-0005vi-00 |
| 131 | >>Generated Received: header line |
| 132 | P Received: from CALLER by myhost.test.ex with local (Exim x.yz) |
| 133 | (envelope-from <CALLER@test.ex>) |
| 134 | id 10HmaX-0005vi-00 |
| 135 | for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000 |
| 136 | Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00 |
| 137 | DSN: Write SPOOL: -dsn_envid NULL |
| 138 | DSN: Write SPOOL :-dsn_ret 0 |
| 139 | DSN: Flags: 0x0 |
| 140 | DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 141 | Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H |
| 142 | Size of headers = sss |
| 143 | LOG: MAIN |
| 144 | <= CALLER@test.ex U=CALLER P=local S=sss |
| 145 | created log directory TESTSUITE/spool/log |
| 146 | search_tidyup called |
| 147 | fresh-exec forking for local-accept-delivery |
| 148 | fresh-exec forked for local-accept-delivery: npppp |
| 149 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00 |
| 150 | Exim version x.yz .... |
| 151 | changed uid/gid: forcing real = effective |
| 152 | uid=uuuu gid=EXIM_GID pid=pppp |
| 153 | configuration file is TESTSUITE/test-config |
| 154 | trusted user |
| 155 | admin user |
| 156 | dropping to exim gid; retaining priv uid |
| 157 | seeking password data for user "CALLER": cache not available |
| 158 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 159 | set_process_info: pppp delivering specified messages |
| 160 | set_process_info: pppp delivering 10HmaX-0005vi-00 |
| 161 | Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D |
| 162 | reading spool file 10HmaX-0005vi-00-H |
| 163 | user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex |
| 164 | sender_local=1 ident=CALLER |
| 165 | Non-recipients: |
| 166 | Empty Tree |
| 167 | ---- End of tree ---- |
| 168 | recipients_count=1 |
| 169 | **** SPOOL_IN - No additional fields |
| 170 | body_linecount=0 message_linecount=7 |
| 171 | DSN: set orcpt: flags: 0x0 |
| 172 | Delivery address list: |
| 173 | userx@test.ex |
| 174 | locking TESTSUITE/spool/db/retry.lockfile |
| 175 | locked TESTSUITE/spool/db/retry.lockfile |
| 176 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 177 | returned from EXIM_DBOPEN: (nil) |
| 178 | ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim |
| 179 | failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory |
| 180 | no retry data available |
| 181 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 182 | Considering: userx@test.ex |
| 183 | test.ex in "! *.ex"? no (matched "! *.ex") |
| 184 | test.ex in "test.ex"? yes (matched "test.ex") |
| 185 | test.ex in percent_hack_domains? yes (matched "+not_queue_domains") |
| 186 | cached no match for +hold_domains |
| 187 | test.ex in hold_domains? no (end of list) |
| 188 | unique = userx@test.ex |
| 189 | no domain retry record |
| 190 | no address retry record |
| 191 | userx@test.ex: queued for routing |
| 192 | cached yes match for +not_queue_domains |
| 193 | test.ex in queue_domains? no (matched "!+not_queue_domains" - cached) |
| 194 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 195 | routing userx@test.ex |
| 196 | --------> r00 router <-------- |
| 197 | local_part=userx domain=test.ex |
| 198 | checking domains |
| 199 | test.ex in "userx"? no (end of list) |
| 200 | test.ex in "+nocache"? no (end of list) |
| 201 | r00 router skipped: domains mismatch |
| 202 | --------> r01 router <-------- |
| 203 | local_part=userx domain=test.ex |
| 204 | checking domains |
| 205 | test.ex in "userx"? no (end of list) |
| 206 | test.ex in "+nocache"? no (end of list) |
| 207 | r01 router skipped: domains mismatch |
| 208 | --------> r02 router <-------- |
| 209 | local_part=userx domain=test.ex |
| 210 | checking domains |
| 211 | test.ex in "userx"? no (end of list) |
| 212 | test.ex in "+nocache"? no (end of list) |
| 213 | test.ex in "+nocache2"? no (end of list) |
| 214 | r02 router skipped: domains mismatch |
| 215 | --------> r03 router <-------- |
| 216 | local_part=userx domain=test.ex |
| 217 | checking domains |
| 218 | test.ex in "userx"? no (end of list) |
| 219 | test.ex in "+nocache"? no (end of list) |
| 220 | test.ex in "+nocache2"? no (end of list) |
| 221 | r03 router skipped: domains mismatch |
| 222 | --------> r04 router <-------- |
| 223 | local_part=userx domain=test.ex |
| 224 | checking domains |
| 225 | test.ex in "userx"? no (end of list) |
| 226 | test.ex in "+forcecache"? no (end of list) |
| 227 | r04 router skipped: domains mismatch |
| 228 | --------> r05 router <-------- |
| 229 | local_part=userx domain=test.ex |
| 230 | checking domains |
| 231 | cached no match for +forcecache |
| 232 | cached lookup data = NULL |
| 233 | test.ex in "+forcecache"? no (end of list) |
| 234 | r05 router skipped: domains mismatch |
| 235 | --------> r1 router <-------- |
| 236 | local_part=userx domain=test.ex |
| 237 | checking domains |
| 238 | test.ex in "never.ex"? no (end of list) |
| 239 | test.ex in "never1.ex"? no (end of list) |
| 240 | test.ex in "test.ex"? yes (matched "test.ex") |
| 241 | test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains") |
| 242 | r1 router skipped: domains mismatch |
| 243 | --------> r2 router <-------- |
| 244 | local_part=userx domain=test.ex |
| 245 | checking domains |
| 246 | cached no match for +never_domains |
| 247 | cached lookup data = NULL |
| 248 | cached no match for +n1_domains |
| 249 | cached lookup data = NULL |
| 250 | test.ex in "<; never2.ex ; +n1_domains"? no (end of list) |
| 251 | cached yes match for +local_domains |
| 252 | cached lookup data = NULL |
| 253 | test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached) |
| 254 | r2 router skipped: domains mismatch |
| 255 | --------> r3 router <-------- |
| 256 | local_part=userx domain=test.ex |
| 257 | checking domains |
| 258 | cached yes match for +local_domains |
| 259 | cached lookup data = NULL |
| 260 | test.ex in "+local_domains"? yes (matched "+local_domains" - cached) |
| 261 | checking local_parts |
| 262 | userx in "userx"? yes (matched "userx") |
| 263 | calling r3 router |
| 264 | r3 router called for userx@test.ex |
| 265 | domain = test.ex |
| 266 | set transport t1 |
| 267 | queued for t1 transport: local_part = userx |
| 268 | domain = test.ex |
| 269 | errors_to=NULL |
| 270 | domain_data=NULL localpart_data=NULL |
| 271 | routed by r3 router |
| 272 | envelope to: userx@test.ex |
| 273 | transport: t1 |
| 274 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 275 | After routing: |
| 276 | Local deliveries: |
| 277 | userx@test.ex |
| 278 | Remote deliveries: |
| 279 | Failed addresses: |
| 280 | Deferred addresses: |
| 281 | search_tidyup called |
| 282 | >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> |
| 283 | --------> userx@test.ex <-------- |
| 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 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 289 | no retry data available |
| 290 | search_tidyup called |
| 291 | local-accept-delivery forking for delivery-local |
| 292 | local-accept-delivery forked for delivery-local: npppp |
| 293 | changed uid/gid: local delivery to userx <userx@test.ex> transport=t1 |
| 294 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 295 | home=NULL current=/ |
| 296 | set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1 |
| 297 | appendfile transport entered |
| 298 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 299 | file=/dev/null format=unix |
| 300 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 301 | message_suffix=\n |
| 302 | maildir_use_size_file=no |
| 303 | locking by lockfile fcntl |
| 304 | search_tidyup called |
| 305 | journalling userx@test.ex |
| 306 | t1 transport returned OK for userx@test.ex |
| 307 | post-process userx@test.ex (0) |
| 308 | userx@test.ex delivered |
| 309 | LOG: MAIN |
| 310 | => userx <userx@test.ex> R=r3 T=t1 |
| 311 | >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> |
| 312 | changed uid/gid: post-delivery tidying |
| 313 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 314 | set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00 |
| 315 | Processing retry items |
| 316 | Succeeded addresses: |
| 317 | userx@test.ex: no retry items |
| 318 | Failed addresses: |
| 319 | Deferred addresses: |
| 320 | end of retry processing |
| 321 | DSN: processing router : r3 |
| 322 | DSN: processing successful delivery address: userx@test.ex |
| 323 | DSN: Sender_address: CALLER@test.ex |
| 324 | DSN: orcpt: NULL flags: 0x0 |
| 325 | DSN: envid: NULL ret: 0 |
| 326 | DSN: Final recipient: userx@test.ex |
| 327 | DSN: Remote SMTP server supports DSN: 0 |
| 328 | DSN: not sending DSN success message |
| 329 | LOG: MAIN |
| 330 | Completed |
| 331 | end delivery of 10HmaX-0005vi-00 |
| 332 | search_tidyup called |
| 333 | search_tidyup called |
| 334 | >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 335 | search_tidyup called |
| 336 | >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 337 | Exim version x.yz .... |
| 338 | changed uid/gid: forcing real = effective |
| 339 | uid=uuuu gid=CALLER_GID pid=pppp |
| 340 | configuration file is TESTSUITE/test-config |
| 341 | admin user |
| 342 | changed uid/gid: privilege not needed |
| 343 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 344 | seeking password data for user "CALLER": cache not available |
| 345 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 346 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
| 347 | sender address = CALLER@test.ex |
| 348 | sender_fullhost = [127.0.0.1] |
| 349 | sender_rcvhost = [127.0.0.1] |
| 350 | host in hosts_connection_nolog? no (option unset) |
| 351 | LOG: smtp_connection MAIN |
| 352 | SMTP connection from [127.0.0.1] |
| 353 | host in host_lookup? no (option unset) |
| 354 | set_process_info: pppp handling incoming connection from [127.0.0.1] |
| 355 | host in host_reject_connection? no (option unset) |
| 356 | host in sender_unqualified_hosts? no (option unset) |
| 357 | host in recipient_unqualified_hosts? no (option unset) |
| 358 | host in helo_verify_hosts? no (option unset) |
| 359 | host in helo_try_verify_hosts? no (option unset) |
| 360 | host in helo_accept_junk_hosts? no (option unset) |
| 361 | SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 |
| 362 | smtp_setup_msg entered |
| 363 | SMTP<< HELO test |
| 364 | test in helo_lookup_domains? no (end of list) |
| 365 | sender_fullhost = (test) [127.0.0.1] |
| 366 | sender_rcvhost = [127.0.0.1] (helo=test) |
| 367 | set_process_info: pppp handling incoming connection from (test) [127.0.0.1] |
| 368 | SMTP>> 250 myhost.test.ex Hello test [127.0.0.1] |
| 369 | SMTP<< MAIL FROM:<test@test.ex> |
| 370 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 371 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 372 | SMTP>> 250 OK |
| 373 | SMTP<< RCPT TO:<error@test.ex> |
| 374 | test.ex in "! *.ex"? no (matched "! *.ex") |
| 375 | test.ex in "test.ex"? yes (matched "test.ex") |
| 376 | test.ex in percent_hack_domains? yes (matched "+not_queue_domains") |
| 377 | processing "accept" (TESTSUITE/test-config 102) |
| 378 | check verify = recipient |
| 379 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 380 | Verifying error@test.ex |
| 381 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 382 | Considering error@test.ex |
| 383 | cached no match for +hold_domains |
| 384 | cached yes match for +not_queue_domains |
| 385 | test.ex in percent_hack_domains? yes (matched "+not_queue_domains" - cached) |
| 386 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 387 | routing error@test.ex |
| 388 | --------> r0f router <-------- |
| 389 | local_part=error domain=test.ex |
| 390 | checking domains |
| 391 | LOG: MAIN PANIC |
| 392 | unknown named domain list "+no_such_list" |
| 393 | domains check lookup or other defer |
| 394 | ----------- end verify ------------ |
| 395 | accept: condition test deferred in inline ACL |
| 396 | SMTP>> 451 Temporary local problem - please try later |
| 397 | LOG: MAIN REJECT |
| 398 | H=(test) [127.0.0.1] F=<test@test.ex> temporarily rejected RCPT <error@test.ex>: domains check lookup or other defer |
| 399 | SMTP<< QUIT |
| 400 | SMTP>> 221 myhost.test.ex closing connection |
| 401 | LOG: smtp_connection MAIN |
| 402 | SMTP connection from (test) [127.0.0.1] closed by QUIT |
| 403 | search_tidyup called |
| 404 | >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>> |