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