| 1 | Exim version x.yz .... |
| 2 | changed uid/gid: forcing real = effective |
| 3 | uid=uuuu gid=CALLER_GID pid=pppp |
| 4 | adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys |
| 5 | configuration file is TESTSUITE/test-config |
| 6 | admin user |
| 7 | changed uid/gid: privilege not needed |
| 8 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 9 | seeking password data for user "CALLER": cache not available |
| 10 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 11 | seeking password data for user "CALLER": using cached result |
| 12 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 13 | seeking password data for user "CALLER": using cached result |
| 14 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 15 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
| 16 | sender address = CALLER@test.ex |
| 17 | set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex> |
| 18 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
| 19 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
| 20 | Sender: CALLER@test.ex |
| 21 | Recipients: |
| 22 | CALLER@test.ex |
| 23 | usery@test.ex |
| 24 | userz |
| 25 | rd+CALLER |
| 26 | rd+usery |
| 27 | search_tidyup called |
| 28 | >>Headers received: |
| 29 | |
| 30 | rewrite_one_header: type=F: |
| 31 | From: CALLER_NAME <CALLER@test.ex> |
| 32 | search_tidyup called |
| 33 | >>Headers after rewriting and local additions: |
| 34 | I Message-Id: <E10HmaX-0005vi-00@mail.test.ex> |
| 35 | F From: CALLER_NAME <CALLER@test.ex> |
| 36 | Date: Tue, 2 Mar 1999 09:44:33 +0000 |
| 37 | |
| 38 | Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D |
| 39 | Data file written for message 10HmaX-0005vi-00 |
| 40 | ╭considering: ${tod_full} |
| 41 | ├──expanding: ${tod_full} |
| 42 | ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000 |
| 43 | ╭considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost |
| 44 | }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 45 | }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 46 | }}(Exim $version_number) |
| 47 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 48 | }}id $message_exim_id${if def:received_for { |
| 49 | for $received_for}} |
| 50 | ├──condition: def:sender_rcvhost |
| 51 | ├─────result: false |
| 52 | ╭───scanning: from $sender_rcvhost |
| 53 | }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 54 | }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 55 | }}(Exim $version_number) |
| 56 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 57 | }}id $message_exim_id${if def:received_for { |
| 58 | for $received_for}} |
| 59 | ├──expanding: from $sender_rcvhost |
| 60 | |
| 61 | ├─────result: from |
| 62 | |
| 63 | ╰───skipping: result is not used |
| 64 | ╭considering: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 65 | }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 66 | }}(Exim $version_number) |
| 67 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 68 | }}id $message_exim_id${if def:received_for { |
| 69 | for $received_for}} |
| 70 | ├──condition: def:sender_ident |
| 71 | ├─────result: true |
| 72 | ╭considering: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 73 | }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 74 | }}(Exim $version_number) |
| 75 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 76 | }}id $message_exim_id${if def:received_for { |
| 77 | for $received_for}} |
| 78 | ╎╭considering: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 79 | ╎ }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 80 | ╎ }}(Exim $version_number) |
| 81 | ╎ ${if def:sender_address {(envelope-from <$sender_address>) |
| 82 | ╎ }}id $message_exim_id${if def:received_for { |
| 83 | ╎ for $received_for}} |
| 84 | ╎├──expanding: $sender_ident |
| 85 | ╎╰─────result: CALLER |
| 86 | ├──expanding: from ${quote_local_part:$sender_ident} |
| 87 | ╰─────result: from CALLER |
| 88 | ├──condition: def:sender_helo_name |
| 89 | ├─────result: false |
| 90 | ╭───scanning: (helo=$sender_helo_name) |
| 91 | }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 92 | }}(Exim $version_number) |
| 93 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 94 | }}id $message_exim_id${if def:received_for { |
| 95 | for $received_for}} |
| 96 | ├──expanding: (helo=$sender_helo_name) |
| 97 | |
| 98 | ├─────result: (helo=) |
| 99 | |
| 100 | ╰───skipping: result is not used |
| 101 | ├──expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 102 | }} |
| 103 | ╰─────result: from CALLER |
| 104 | ├──condition: def:received_protocol |
| 105 | ├─────result: true |
| 106 | ╭considering: with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 107 | }}(Exim $version_number) |
| 108 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 109 | }}id $message_exim_id${if def:received_for { |
| 110 | for $received_for}} |
| 111 | ├──expanding: with $received_protocol |
| 112 | ╰─────result: with local |
| 113 | ├──condition: def:tls_in_ver |
| 114 | ├─────result: false |
| 115 | ╭───scanning: ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 116 | }}(Exim $version_number) |
| 117 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 118 | }}id $message_exim_id${if def:received_for { |
| 119 | for $received_for}} |
| 120 | ├──expanding: ($tls_in_ver) |
| 121 | ├─────result: () |
| 122 | ╰───skipping: result is not used |
| 123 | ├──condition: def:sender_address |
| 124 | ├─────result: true |
| 125 | ╭considering: (envelope-from <$sender_address>) |
| 126 | }}id $message_exim_id${if def:received_for { |
| 127 | for $received_for}} |
| 128 | ├──expanding: (envelope-from <$sender_address>) |
| 129 | |
| 130 | ╰─────result: (envelope-from <CALLER@test.ex>) |
| 131 | |
| 132 | ├──condition: def:received_for |
| 133 | ├─────result: false |
| 134 | ╭───scanning: |
| 135 | for $received_for}} |
| 136 | ├──expanding: |
| 137 | for $received_for |
| 138 | ├─────result: |
| 139 | for |
| 140 | ╰───skipping: result is not used |
| 141 | ├──expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost |
| 142 | }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name) |
| 143 | }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_ver { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std |
| 144 | }}(Exim $version_number) |
| 145 | ${if def:sender_address {(envelope-from <$sender_address>) |
| 146 | }}id $message_exim_id${if def:received_for { |
| 147 | for $received_for}} |
| 148 | ╰─────result: Received: from CALLER by mail.test.ex with local (Exim x.yz) |
| 149 | (envelope-from <CALLER@test.ex>) |
| 150 | id 10HmaX-0005vi-00 |
| 151 | >>Generated Received: header line |
| 152 | P Received: from CALLER by mail.test.ex with local (Exim x.yz) |
| 153 | (envelope-from <CALLER@test.ex>) |
| 154 | id 10HmaX-0005vi-00; Tue, 2 Mar 1999 09:44:33 +0000 |
| 155 | ╭considering: ${tod_full} |
| 156 | ├──expanding: ${tod_full} |
| 157 | ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000 |
| 158 | Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00 |
| 159 | DSN: Write SPOOL: -dsn_envid NULL |
| 160 | DSN: Write SPOOL :-dsn_ret 0 |
| 161 | DSN: Flags: 0x0 |
| 162 | DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 163 | DSN: Flags: 0x0 |
| 164 | DSN: **** SPOOL_OUT - address: <usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 165 | DSN: Flags: 0x0 |
| 166 | DSN: **** SPOOL_OUT - address: <userz@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 167 | DSN: Flags: 0x0 |
| 168 | DSN: **** SPOOL_OUT - address: <rd+CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 169 | DSN: Flags: 0x0 |
| 170 | DSN: **** SPOOL_OUT - address: <rd+usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0 |
| 171 | Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H |
| 172 | Size of headers = sss |
| 173 | LOG: MAIN |
| 174 | <= CALLER@test.ex U=CALLER P=local S=sss |
| 175 | created log directory TESTSUITE/spool/log |
| 176 | search_tidyup called |
| 177 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715dfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00 |
| 178 | Exim version x.yz .... |
| 179 | changed uid/gid: forcing real = effective |
| 180 | uid=uuuu gid=EXIM_GID pid=pppp |
| 181 | adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys |
| 182 | configuration file is TESTSUITE/test-config |
| 183 | trusted user |
| 184 | admin user |
| 185 | dropping to exim gid; retaining priv uid |
| 186 | seeking password data for user "CALLER": cache not available |
| 187 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 188 | seeking password data for user "CALLER": using cached result |
| 189 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 190 | seeking password data for user "CALLER": using cached result |
| 191 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID |
| 192 | set_process_info: pppp delivering specified messages |
| 193 | set_process_info: pppp delivering 10HmaX-0005vi-00 |
| 194 | Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D |
| 195 | reading spool file 10HmaX-0005vi-00-H |
| 196 | user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex |
| 197 | sender_local=1 ident=CALLER |
| 198 | Non-recipients: |
| 199 | Empty Tree |
| 200 | ---- End of tree ---- |
| 201 | recipients_count=5 |
| 202 | **** SPOOL_IN - No additional fields |
| 203 | **** SPOOL_IN - No additional fields |
| 204 | **** SPOOL_IN - No additional fields |
| 205 | **** SPOOL_IN - No additional fields |
| 206 | **** SPOOL_IN - No additional fields |
| 207 | body_linecount=0 message_linecount=6 |
| 208 | DSN: set orcpt: flags: 0x0 |
| 209 | DSN: set orcpt: flags: 0x0 |
| 210 | DSN: set orcpt: flags: 0x0 |
| 211 | DSN: set orcpt: flags: 0x0 |
| 212 | DSN: set orcpt: flags: 0x0 |
| 213 | Delivery address list: |
| 214 | CALLER@test.ex |
| 215 | usery@test.ex |
| 216 | userz@test.ex |
| 217 | rd+CALLER@test.ex |
| 218 | rd+usery@test.ex |
| 219 | locking TESTSUITE/spool/db/retry.lockfile |
| 220 | locked TESTSUITE/spool/db/retry.lockfile |
| 221 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 222 | returned from EXIM_DBOPEN: (nil) |
| 223 | ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim |
| 224 | failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory |
| 225 | no retry data available |
| 226 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 227 | Considering: CALLER@test.ex |
| 228 | unique = CALLER@test.ex |
| 229 | no domain retry record |
| 230 | no address retry record |
| 231 | CALLER@test.ex: queued for routing |
| 232 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 233 | Considering: usery@test.ex |
| 234 | unique = usery@test.ex |
| 235 | no domain retry record |
| 236 | no address retry record |
| 237 | usery@test.ex: queued for routing |
| 238 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 239 | Considering: userz@test.ex |
| 240 | unique = userz@test.ex |
| 241 | no domain retry record |
| 242 | no address retry record |
| 243 | userz@test.ex: queued for routing |
| 244 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 245 | Considering: rd+CALLER@test.ex |
| 246 | unique = rd+CALLER@test.ex |
| 247 | no domain retry record |
| 248 | no address retry record |
| 249 | rd+CALLER@test.ex: queued for routing |
| 250 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 251 | Considering: rd+usery@test.ex |
| 252 | unique = rd+usery@test.ex |
| 253 | no domain retry record |
| 254 | no address retry record |
| 255 | rd+usery@test.ex: queued for routing |
| 256 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 257 | routing rd+usery@test.ex |
| 258 | --------> r1 router <-------- |
| 259 | local_part=rd+usery domain=test.ex |
| 260 | checking local_parts |
| 261 | rd+usery in "CALLER"? no (end of list) |
| 262 | r1 router skipped: local_parts mismatch |
| 263 | --------> r2 router <-------- |
| 264 | local_part=rd+usery domain=test.ex |
| 265 | checking local_parts |
| 266 | rd+usery in "usery"? no (end of list) |
| 267 | r2 router skipped: local_parts mismatch |
| 268 | --------> r3 router <-------- |
| 269 | local_part=rd+usery domain=test.ex |
| 270 | checking local_parts |
| 271 | rd+usery in "userz"? no (end of list) |
| 272 | r3 router skipped: local_parts mismatch |
| 273 | --------> r4 router <-------- |
| 274 | local_part=rd+usery domain=test.ex |
| 275 | stripped prefix rd+ |
| 276 | checking local_parts |
| 277 | usery in "CALLER"? no (end of list) |
| 278 | r4 router skipped: local_parts mismatch |
| 279 | --------> r5 router <-------- |
| 280 | local_part=rd+usery domain=test.ex |
| 281 | stripped prefix rd+ |
| 282 | checking local_parts |
| 283 | usery in "usery"? yes (matched "usery") |
| 284 | ╭considering: /non-exist/$domain |
| 285 | ├──expanding: /non-exist/$domain |
| 286 | ╰─────result: /non-exist/test.ex |
| 287 | ╰──(tainted) |
| 288 | calling r5 router |
| 289 | rda_interpret (string): 'TESTSUITE/test-mail/junk' |
| 290 | expanded: 'TESTSUITE/test-mail/junk' |
| 291 | file is not a filter file |
| 292 | parse_forward_list: TESTSUITE/test-mail/junk |
| 293 | extract item: TESTSUITE/test-mail/junk |
| 294 | set transport ft1 |
| 295 | r5 router generated TESTSUITE/test-mail/junk |
| 296 | pipe, file, or autoreply |
| 297 | errors_to=NULL transport=ft1 |
| 298 | uid=unset gid=unset home=/non-exist/$local_part |
| 299 | routed by r5 router |
| 300 | envelope to: rd+usery@test.ex |
| 301 | transport: <none> |
| 302 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 303 | routing rd+CALLER@test.ex |
| 304 | --------> r1 router <-------- |
| 305 | local_part=rd+CALLER domain=test.ex |
| 306 | checking local_parts |
| 307 | rd+CALLER in "CALLER"? no (end of list) |
| 308 | r1 router skipped: local_parts mismatch |
| 309 | --------> r2 router <-------- |
| 310 | local_part=rd+CALLER domain=test.ex |
| 311 | checking local_parts |
| 312 | rd+CALLER in "usery"? no (end of list) |
| 313 | r2 router skipped: local_parts mismatch |
| 314 | --------> r3 router <-------- |
| 315 | local_part=rd+CALLER domain=test.ex |
| 316 | checking local_parts |
| 317 | rd+CALLER in "userz"? no (end of list) |
| 318 | r3 router skipped: local_parts mismatch |
| 319 | --------> r4 router <-------- |
| 320 | local_part=rd+CALLER domain=test.ex |
| 321 | stripped prefix rd+ |
| 322 | checking local_parts |
| 323 | CALLER in "CALLER"? yes (matched "CALLER") |
| 324 | ╭considering: /non-exist/$local_part |
| 325 | ├──expanding: /non-exist/$local_part |
| 326 | ╰─────result: /non-exist/CALLER |
| 327 | ╰──(tainted) |
| 328 | calling r4 router |
| 329 | rda_interpret (string): 'TESTSUITE/test-mail/junk' |
| 330 | expanded: 'TESTSUITE/test-mail/junk' |
| 331 | file is not a filter file |
| 332 | parse_forward_list: TESTSUITE/test-mail/junk |
| 333 | extract item: TESTSUITE/test-mail/junk |
| 334 | set transport ft1 |
| 335 | r4 router generated TESTSUITE/test-mail/junk |
| 336 | pipe, file, or autoreply |
| 337 | errors_to=NULL transport=ft1 |
| 338 | uid=unset gid=unset home=/non-exist/CALLER |
| 339 | routed by r4 router |
| 340 | envelope to: rd+CALLER@test.ex |
| 341 | transport: <none> |
| 342 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 343 | routing userz@test.ex |
| 344 | --------> r1 router <-------- |
| 345 | local_part=userz domain=test.ex |
| 346 | checking local_parts |
| 347 | userz in "CALLER"? no (end of list) |
| 348 | r1 router skipped: local_parts mismatch |
| 349 | --------> r2 router <-------- |
| 350 | local_part=userz domain=test.ex |
| 351 | checking local_parts |
| 352 | userz in "usery"? no (end of list) |
| 353 | r2 router skipped: local_parts mismatch |
| 354 | --------> r3 router <-------- |
| 355 | local_part=userz domain=test.ex |
| 356 | checking local_parts |
| 357 | userz in "userz"? yes (matched "userz") |
| 358 | ╭considering: /non-exist/$domain |
| 359 | ├──expanding: /non-exist/$domain |
| 360 | ╰─────result: /non-exist/test.ex |
| 361 | ╰──(tainted) |
| 362 | calling r3 router |
| 363 | r3 router called for userz@test.ex |
| 364 | domain = test.ex |
| 365 | set transport t2 |
| 366 | queued for t2 transport: local_part = userz |
| 367 | domain = test.ex |
| 368 | errors_to=NULL |
| 369 | domain_data=NULL localpart_data=NULL |
| 370 | routed by r3 router |
| 371 | envelope to: userz@test.ex |
| 372 | transport: t2 |
| 373 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 374 | routing usery@test.ex |
| 375 | --------> r1 router <-------- |
| 376 | local_part=usery domain=test.ex |
| 377 | checking local_parts |
| 378 | usery in "CALLER"? no (end of list) |
| 379 | r1 router skipped: local_parts mismatch |
| 380 | --------> r2 router <-------- |
| 381 | local_part=usery domain=test.ex |
| 382 | checking local_parts |
| 383 | usery in "usery"? yes (matched "usery") |
| 384 | ╭considering: /non-exist/$domain |
| 385 | ├──expanding: /non-exist/$domain |
| 386 | ╰─────result: /non-exist/test.ex |
| 387 | ╰──(tainted) |
| 388 | calling r2 router |
| 389 | r2 router called for usery@test.ex |
| 390 | domain = test.ex |
| 391 | set transport t1 |
| 392 | queued for t1 transport: local_part = usery |
| 393 | domain = test.ex |
| 394 | errors_to=NULL |
| 395 | domain_data=NULL localpart_data=NULL |
| 396 | routed by r2 router |
| 397 | envelope to: usery@test.ex |
| 398 | transport: t1 |
| 399 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 400 | routing CALLER@test.ex |
| 401 | --------> r1 router <-------- |
| 402 | local_part=CALLER domain=test.ex |
| 403 | checking local_parts |
| 404 | CALLER in "CALLER"? yes (matched "CALLER") |
| 405 | ╭considering: /non-exist/$local_part |
| 406 | ├──expanding: /non-exist/$local_part |
| 407 | ╰─────result: /non-exist/CALLER |
| 408 | ╰──(tainted) |
| 409 | calling r1 router |
| 410 | r1 router called for CALLER@test.ex |
| 411 | domain = test.ex |
| 412 | set transport t1 |
| 413 | queued for t1 transport: local_part = CALLER |
| 414 | domain = test.ex |
| 415 | errors_to=NULL |
| 416 | domain_data=NULL localpart_data=NULL |
| 417 | routed by r1 router |
| 418 | envelope to: CALLER@test.ex |
| 419 | transport: t1 |
| 420 | locking TESTSUITE/spool/db/retry.lockfile |
| 421 | locked TESTSUITE/spool/db/retry.lockfile |
| 422 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 423 | returned from EXIM_DBOPEN: (nil) |
| 424 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 425 | no retry data available |
| 426 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 427 | Considering: TESTSUITE/test-mail/junk |
| 428 | unique = TESTSUITE/test-mail/junk:rd+CALLER@test.ex |
| 429 | queued for ft1 transport |
| 430 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 431 | Considering: TESTSUITE/test-mail/junk |
| 432 | unique = TESTSUITE/test-mail/junk:rd+usery@test.ex |
| 433 | queued for ft1 transport |
| 434 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> |
| 435 | After routing: |
| 436 | Local deliveries: |
| 437 | TESTSUITE/test-mail/junk |
| 438 | TESTSUITE/test-mail/junk |
| 439 | CALLER@test.ex |
| 440 | usery@test.ex |
| 441 | userz@test.ex |
| 442 | Remote deliveries: |
| 443 | Failed addresses: |
| 444 | Deferred addresses: |
| 445 | search_tidyup called |
| 446 | >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> |
| 447 | --------> TESTSUITE/test-mail/junk <-------- |
| 448 | locking TESTSUITE/spool/db/retry.lockfile |
| 449 | locked TESTSUITE/spool/db/retry.lockfile |
| 450 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 451 | returned from EXIM_DBOPEN: (nil) |
| 452 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 453 | no retry data available |
| 454 | ╭considering: /non-exist/$local_part |
| 455 | ├──expanding: /non-exist/$local_part |
| 456 | ╰─────result: /non-exist/usery |
| 457 | ╰──(tainted) |
| 458 | search_tidyup called |
| 459 | changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1 |
| 460 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 461 | home=/non-exist/usery current=/ |
| 462 | set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1 |
| 463 | appendfile transport entered |
| 464 | ╭considering: $address_file |
| 465 | ├──expanding: $address_file |
| 466 | ╰─────result: TESTSUITE/test-mail/junk |
| 467 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 468 | file=TESTSUITE/test-mail/junk format=unix |
| 469 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 470 | message_suffix=\n |
| 471 | maildir_use_size_file=no |
| 472 | locking by lockfile fcntl |
| 473 | lock name: TESTSUITE/test-mail/junk.lock |
| 474 | hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp |
| 475 | lock file created |
| 476 | mailbox TESTSUITE/test-mail/junk is locked |
| 477 | writing to file TESTSUITE/test-mail/junk |
| 478 | ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox} |
| 479 | |
| 480 | ├──condition: def:return_path |
| 481 | ├─────result: true |
| 482 | ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox} |
| 483 | |
| 484 | ├──expanding: $return_path |
| 485 | ╰─────result: CALLER@test.ex |
| 486 | ╰──(tainted) |
| 487 | ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox} |
| 488 | |
| 489 | ├──expanding: MAILER-DAEMON |
| 490 | ├─────result: MAILER-DAEMON |
| 491 | ╰───skipping: result is not used |
| 492 | ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox} |
| 493 | |
| 494 | ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999 |
| 495 | |
| 496 | ╰──(tainted) |
| 497 | writing data block fd=dddd size=sss timeout=0 |
| 498 | cannot use sendfile for body: spoolfile not wireformat |
| 499 | writing data block fd=dddd size=sss timeout=0 |
| 500 | writing data block fd=dddd size=sss timeout=0 |
| 501 | appendfile yields 0 with errno=dd more_errno=dd |
| 502 | search_tidyup called |
| 503 | journalling TESTSUITE/test-mail/junk:rd+usery@test.ex |
| 504 | ft1 transport returned OK for TESTSUITE/test-mail/junk |
| 505 | post-process TESTSUITE/test-mail/junk (0) |
| 506 | TESTSUITE/test-mail/junk delivered |
| 507 | rd+usery@test.ex: children all complete |
| 508 | LOG: MAIN |
| 509 | => TESTSUITE/test-mail/junk <rd+usery@test.ex> R=r5 T=ft1 |
| 510 | --------> TESTSUITE/test-mail/junk <-------- |
| 511 | locking TESTSUITE/spool/db/retry.lockfile |
| 512 | locked TESTSUITE/spool/db/retry.lockfile |
| 513 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 514 | returned from EXIM_DBOPEN: (nil) |
| 515 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 516 | no retry data available |
| 517 | search_tidyup called |
| 518 | changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1 |
| 519 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 520 | home=/non-exist/CALLER current=/ |
| 521 | set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1 |
| 522 | appendfile transport entered |
| 523 | ╭considering: $address_file |
| 524 | ├──expanding: $address_file |
| 525 | ╰─────result: TESTSUITE/test-mail/junk |
| 526 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 527 | file=TESTSUITE/test-mail/junk format=unix |
| 528 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 529 | message_suffix=\n |
| 530 | maildir_use_size_file=no |
| 531 | locking by lockfile fcntl |
| 532 | lock name: TESTSUITE/test-mail/junk.lock |
| 533 | hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp |
| 534 | lock file created |
| 535 | mailbox TESTSUITE/test-mail/junk is locked |
| 536 | writing to file TESTSUITE/test-mail/junk |
| 537 | ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox} |
| 538 | |
| 539 | ├──condition: def:return_path |
| 540 | ├─────result: true |
| 541 | ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox} |
| 542 | |
| 543 | ├──expanding: $return_path |
| 544 | ╰─────result: CALLER@test.ex |
| 545 | ╰──(tainted) |
| 546 | ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox} |
| 547 | |
| 548 | ├──expanding: MAILER-DAEMON |
| 549 | ├─────result: MAILER-DAEMON |
| 550 | ╰───skipping: result is not used |
| 551 | ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox} |
| 552 | |
| 553 | ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999 |
| 554 | |
| 555 | ╰──(tainted) |
| 556 | writing data block fd=dddd size=sss timeout=0 |
| 557 | cannot use sendfile for body: spoolfile not wireformat |
| 558 | writing data block fd=dddd size=sss timeout=0 |
| 559 | writing data block fd=dddd size=sss timeout=0 |
| 560 | appendfile yields 0 with errno=dd more_errno=dd |
| 561 | search_tidyup called |
| 562 | journalling TESTSUITE/test-mail/junk:rd+CALLER@test.ex |
| 563 | ft1 transport returned OK for TESTSUITE/test-mail/junk |
| 564 | post-process TESTSUITE/test-mail/junk (0) |
| 565 | TESTSUITE/test-mail/junk delivered |
| 566 | rd+CALLER@test.ex: children all complete |
| 567 | LOG: MAIN |
| 568 | => TESTSUITE/test-mail/junk <rd+CALLER@test.ex> R=r4 T=ft1 |
| 569 | --------> CALLER@test.ex <-------- |
| 570 | locking TESTSUITE/spool/db/retry.lockfile |
| 571 | locked TESTSUITE/spool/db/retry.lockfile |
| 572 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 573 | returned from EXIM_DBOPEN: (nil) |
| 574 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 575 | no retry data available |
| 576 | search_tidyup called |
| 577 | changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1 |
| 578 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 579 | home=/non-exist/CALLER current=/ |
| 580 | set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1 |
| 581 | appendfile transport entered |
| 582 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 583 | file=/dev/null format=unix |
| 584 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 585 | message_suffix=\n |
| 586 | maildir_use_size_file=no |
| 587 | locking by lockfile fcntl |
| 588 | search_tidyup called |
| 589 | journalling CALLER@test.ex |
| 590 | t1 transport returned OK for CALLER@test.ex |
| 591 | post-process CALLER@test.ex (0) |
| 592 | CALLER@test.ex delivered |
| 593 | LOG: MAIN |
| 594 | => CALLER <CALLER@test.ex> R=r1 T=t1 |
| 595 | --------> usery@test.ex <-------- |
| 596 | locking TESTSUITE/spool/db/retry.lockfile |
| 597 | locked TESTSUITE/spool/db/retry.lockfile |
| 598 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 599 | returned from EXIM_DBOPEN: (nil) |
| 600 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 601 | no retry data available |
| 602 | ╭considering: /non-exist/$local_part |
| 603 | ├──expanding: /non-exist/$local_part |
| 604 | ╰─────result: /non-exist/usery |
| 605 | ╰──(tainted) |
| 606 | search_tidyup called |
| 607 | changed uid/gid: local delivery to usery <usery@test.ex> transport=t1 |
| 608 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 609 | home=/non-exist/usery current=/ |
| 610 | set_process_info: pppp delivering 10HmaX-0005vi-00 to usery using t1 |
| 611 | appendfile transport entered |
| 612 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 613 | file=/dev/null format=unix |
| 614 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 615 | message_suffix=\n |
| 616 | maildir_use_size_file=no |
| 617 | locking by lockfile fcntl |
| 618 | search_tidyup called |
| 619 | journalling usery@test.ex |
| 620 | t1 transport returned OK for usery@test.ex |
| 621 | post-process usery@test.ex (0) |
| 622 | usery@test.ex delivered |
| 623 | LOG: MAIN |
| 624 | => usery <usery@test.ex> R=r2 T=t1 |
| 625 | --------> userz@test.ex <-------- |
| 626 | locking TESTSUITE/spool/db/retry.lockfile |
| 627 | locked TESTSUITE/spool/db/retry.lockfile |
| 628 | EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY |
| 629 | returned from EXIM_DBOPEN: (nil) |
| 630 | failed to open DB file TESTSUITE/spool/db/retry: No such file or directory |
| 631 | no retry data available |
| 632 | ╭considering: /$local_part |
| 633 | ├──expanding: /$local_part |
| 634 | ╰─────result: /userz |
| 635 | ╰──(tainted) |
| 636 | search_tidyup called |
| 637 | changed uid/gid: local delivery to userz <userz@test.ex> transport=t2 |
| 638 | uid=CALLER_UID gid=CALLER_GID pid=pppp |
| 639 | home=/userz current=/ |
| 640 | set_process_info: pppp delivering 10HmaX-0005vi-00 to userz using t2 |
| 641 | appendfile transport entered |
| 642 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 |
| 643 | file=/dev/null format=unix |
| 644 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n |
| 645 | message_suffix=\n |
| 646 | maildir_use_size_file=no |
| 647 | locking by lockfile fcntl |
| 648 | search_tidyup called |
| 649 | journalling userz@test.ex |
| 650 | t2 transport returned OK for userz@test.ex |
| 651 | post-process userz@test.ex (0) |
| 652 | userz@test.ex delivered |
| 653 | LOG: MAIN |
| 654 | => userz <userz@test.ex> R=r3 T=t2 |
| 655 | >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> |
| 656 | changed uid/gid: post-delivery tidying |
| 657 | uid=EXIM_UID gid=EXIM_GID pid=pppp |
| 658 | set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00 |
| 659 | Processing retry items |
| 660 | Succeeded addresses: |
| 661 | userz@test.ex: no retry items |
| 662 | usery@test.ex: no retry items |
| 663 | CALLER@test.ex: no retry items |
| 664 | TESTSUITE/test-mail/junk: no retry items |
| 665 | rd+CALLER@test.ex: no retry items |
| 666 | TESTSUITE/test-mail/junk: no retry items |
| 667 | rd+usery@test.ex: no retry items |
| 668 | rd+CALLER@test.ex: no retry items |
| 669 | rd+usery@test.ex: no retry items |
| 670 | Failed addresses: |
| 671 | Deferred addresses: |
| 672 | end of retry processing |
| 673 | DSN: processing router : r3 |
| 674 | DSN: processing successful delivery address: userz@test.ex |
| 675 | DSN: Sender_address: CALLER@test.ex |
| 676 | DSN: orcpt: NULL flags: 0x0 |
| 677 | DSN: envid: NULL ret: 0 |
| 678 | DSN: Final recipient: userz@test.ex |
| 679 | DSN: Remote SMTP server supports DSN: 0 |
| 680 | DSN: not sending DSN success message |
| 681 | DSN: processing router : r2 |
| 682 | DSN: processing successful delivery address: usery@test.ex |
| 683 | DSN: Sender_address: CALLER@test.ex |
| 684 | DSN: orcpt: NULL flags: 0x0 |
| 685 | DSN: envid: NULL ret: 0 |
| 686 | DSN: Final recipient: usery@test.ex |
| 687 | DSN: Remote SMTP server supports DSN: 0 |
| 688 | DSN: not sending DSN success message |
| 689 | DSN: processing router : r1 |
| 690 | DSN: processing successful delivery address: CALLER@test.ex |
| 691 | DSN: Sender_address: CALLER@test.ex |
| 692 | DSN: orcpt: NULL flags: 0x0 |
| 693 | DSN: envid: NULL ret: 0 |
| 694 | DSN: Final recipient: CALLER@test.ex |
| 695 | DSN: Remote SMTP server supports DSN: 0 |
| 696 | DSN: not sending DSN success message |
| 697 | DSN: processing router : r4 |
| 698 | DSN: processing successful delivery address: TESTSUITE/test-mail/junk |
| 699 | DSN: Sender_address: CALLER@test.ex |
| 700 | DSN: orcpt: NULL flags: 0x0 |
| 701 | DSN: envid: NULL ret: 0 |
| 702 | DSN: Final recipient: TESTSUITE/test-mail/junk |
| 703 | DSN: Remote SMTP server supports DSN: 0 |
| 704 | DSN: not sending DSN success message |
| 705 | DSN: processing router : r5 |
| 706 | DSN: processing successful delivery address: TESTSUITE/test-mail/junk |
| 707 | DSN: Sender_address: CALLER@test.ex |
| 708 | DSN: orcpt: NULL flags: 0x0 |
| 709 | DSN: envid: NULL ret: 0 |
| 710 | DSN: Final recipient: TESTSUITE/test-mail/junk |
| 711 | DSN: Remote SMTP server supports DSN: 0 |
| 712 | DSN: not sending DSN success message |
| 713 | DSN: processing router : r4 |
| 714 | DSN: processing successful delivery address: rd+CALLER@test.ex |
| 715 | DSN: Sender_address: CALLER@test.ex |
| 716 | DSN: orcpt: NULL flags: 0x0 |
| 717 | DSN: envid: NULL ret: 0 |
| 718 | DSN: Final recipient: rd+CALLER@test.ex |
| 719 | DSN: Remote SMTP server supports DSN: 0 |
| 720 | DSN: not sending DSN success message |
| 721 | DSN: processing router : r5 |
| 722 | DSN: processing successful delivery address: rd+usery@test.ex |
| 723 | DSN: Sender_address: CALLER@test.ex |
| 724 | DSN: orcpt: NULL flags: 0x0 |
| 725 | DSN: envid: NULL ret: 0 |
| 726 | DSN: Final recipient: rd+usery@test.ex |
| 727 | DSN: Remote SMTP server supports DSN: 0 |
| 728 | DSN: not sending DSN success message |
| 729 | LOG: MAIN |
| 730 | Completed |
| 731 | end delivery of 10HmaX-0005vi-00 |
| 732 | search_tidyup called |
| 733 | search_tidyup called |
| 734 | >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>> |
| 735 | search_tidyup called |
| 736 | >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>> |