test/stderr/5005

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