dovecot - Feb 2011 - Problem with expire plugin and expunge

Hello,

I am running dovecot 2.0.9 with expire plugin, using the sqlite backend. The 
following command is executed every day by cron:

doveadm expunge -A mailbox Trash savedbefore 30d


I started to use the expire plugin about one month ago. Since then, 
using "doveadm search -A mailbox Trash savedbefore <days>" with
different
values for <days>, I could see every day how the messages were getting
older.
Yesterday was the first time that the expression "savedbefore 30d"
became
true for the oldest messages in the Trash mailbox and these messages were 
actually deleted from Trash. But today, all messages that were not deleted 
yesterday are new to "doveadm search", that means that "doveadm
search -A
mailbox Trash savedbefore 0d" shows all messages from Trash, but
"doveadm
search -A mailbox Trash savedbefore 1d" shows no messages from Trash,
though
during the last month I moved messages to Trash every day.

Best Regards
  Christoph

On Sat, 2011-02-19 at 10:10 +0100, Christoph Pleger wrote:
> I started to use the expire plugin about one month ago. Since then, 
> using "doveadm search -A mailbox Trash savedbefore <days>"
with different
> values for <days>, I could see every day how the messages were
getting older.
> Yesterday was the first time that the expression "savedbefore
30d" became
> true for the oldest messages in the Trash mailbox and these messages were 
> actually deleted from Trash. But today, all messages that were not deleted 
> yesterday are new to "doveadm search", that means that
"doveadm search -A
> mailbox Trash savedbefore 0d" shows all messages from Trash, but
"doveadm
> search -A mailbox Trash savedbefore 1d" shows no messages from Trash,
though
> during the last month I moved messages to Trash every day.
Are the timestamps wrong in the database? They should contain the
timestamp of the oldest save-date of message in the mailbox.

Also check that the save-dates are as expected in the mailbox itself:

doveadm fetch -u user at domain date.saved mailbox Trash 1

And finally, I added some more helpful error and debug logging (which
you'll get with doveadm -D):
http://hg.dovecot.org/dovecot-2.0/raw-rev/9862e8388a1c

Hello,

Timo Sirainen wrote:
> Are the timestamps wrong in the database? They should contain the
> timestamp of the oldest save-date of message in the mailbox.
Today, messages from Trash have been expunged again.

The only timestamp in the database is  Sun Mar  6 08:37:55 UTC 2011. This is 
obviously not the time from when the oldest message in the Trash mailbox was 
moved into that mailbox; I am moving messages to Trash every day.

Does anyone have an idea what is going wrong with the timestamps?

Regards
  Christoph

Hello,
> > Big surprise: Something bad must have happened after the messages were
> > deleted from Trash; now all messages are zero days old.
>
> Are you using maildir? Did you run with those patches + mail_debug=yes?
Today, the daily cronjob expired some messages. I have attached the output of 
the job.

It seems that the error occurs not when expunging, but when deleting the first 
message after expunging. Before deleting a message, "doveadm search"
showed
me messages that were between zero and four days old, but directly after 
deleting a message, all messages were zero days old. Here is my log entry 
from when I deleted that message:

Mar 18 09:50:28 joseph dovecot: imap(christoph): Debug: expire: Saving first 
message to Trash, updating timestamp to 1300438228

Regards
  Christoph


-------------- next part --------------
/etc/cron.daily/dovecot:
doveadm: Debug: Loading modules from directory: /usr/lib/dovecot/modules
doveadm: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_expire_plugin.so
doveadm: Debug: Loading modules from directory: /usr/lib/dovecot/modules/doveadm
doveadm: Debug: Skipping module doveadm_acl_plugin, because dlopen() failed:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_acl_plugin.so: undefined symbol:
acl_user_module (this is usually intentional, so just ignore this message)
doveadm: Debug: Module loaded:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_expire_plugin.so
doveadm: Debug: Skipping module doveadm_quota_plugin, because dlopen() failed:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_quota_plugin.so: undefined
symbol: quota_user_module (this is usually intentional, so just ignore this
message)
doveadm: Debug: Skipping module doveadm_zlib_plugin, because dlopen() failed:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_zlib_plugin.so: undefined symbol:
i_stream_create_deflate (this is usually intentional, so just ignore this
message)
doveadm: Debug: expire: Searching only users listed in expire database

doveadm: Debug: Loading modules from directory: /usr/lib/dovecot/modules
doveadm: Debug: Module loaded: /usr/lib/dovecot/modules/lib20_expire_plugin.so
doveadm: Debug: Loading modules from directory: /usr/lib/dovecot/modules/doveadm
doveadm: Debug: Skipping module doveadm_acl_plugin, because dlopen() failed:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_acl_plugin.so: undefined symbol:
acl_user_module (this is usually intentional, so just ignore this message)
doveadm: Debug: Module loaded:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_expire_plugin.so
doveadm: Debug: Skipping module doveadm_quota_plugin, because dlopen() failed:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_quota_plugin.so: undefined
symbol: quota_user_module (this is usually intentional, so just ignore this
message)
doveadm: Debug: Skipping module doveadm_zlib_plugin, because dlopen() failed:
/usr/lib/dovecot/modules/doveadm/lib10_doveadm_zlib_plugin.so: undefined symbol:
i_stream_create_deflate (this is usually intentional, so just ignore this
message)
doveadm: Debug: expire: Searching only users listed in expire database
doveadm(christoph): Debug: auth input: christoph home=/mailhome/vmail/christoph
doveadm(christoph): Debug: Effective uid=110, gid=114,
home=/mailhome/vmail/christoph
doveadm(christoph): Debug: fs: root=/mailhome/vmail/christoph/mdbox, index=,
control=, inboxdoveadm(christoph): Debug: Namespace : Using permissions from
/mailhome/vmail/christoph/mdbox: mode=0700 gid=-1
doveadm(christoph): Debug: expunge: box=Trash uid=3248
doveadm(christoph): Debug: expunge: box=Trash uid=3249
doveadm(christoph): Debug: expunge: box=Trash uid=3250
doveadm(christoph): Debug: expunge: box=Trash uid=3251
doveadm(christoph): Debug: expunge: box=Trash uid=3252
doveadm(christoph): Debug: expunge: box=Trash uid=3253
doveadm(christoph): Debug: expunge: box=Trash uid=3254
doveadm(christoph): Debug: expunge: box=Trash uid=3255
doveadm(christoph): Debug: expunge: box=Trash uid=3256
doveadm(christoph): Debug: expunge: box=Trash uid=3257
doveadm(christoph): Debug: expunge: box=Trash uid=3258
doveadm(christoph): Debug: expunge: box=Trash uid=3259
doveadm(christoph): Debug: expunge: box=Trash uid=3260
doveadm(christoph): Debug: expunge: box=Trash uid=3261
doveadm(christoph): Debug: expunge: box=Trash uid=3262
doveadm(christoph): Debug: expunge: box=Trash uid=3263
doveadm(christoph): Debug: expunge: box=Trash uid=3264
doveadm(christoph): Debug: expunge: box=Trash uid=3265
doveadm(christoph): Debug: expunge: box=Trash uid=3266
doveadm(christoph): Debug: expunge: box=Trash uid=3267
doveadm(christoph): Debug: expunge: box=Trash uid=3268
doveadm(christoph): Debug: expunge: box=Trash uid=3269
doveadm(christoph): Debug: expunge: box=Trash uid=3270
doveadm(christoph): Debug: expunge: box=Trash uid=3271
doveadm(christoph): Debug: expunge: box=Trash uid=3272
doveadm(christoph): Debug: expunge: box=Trash uid=3273
doveadm(christoph): Debug: expunge: box=Trash uid=3274
doveadm(christoph): Debug: expunge: box=Trash uid=3275
doveadm(christoph): Debug: expunge: box=Trash uid=3276
doveadm(christoph): Debug: expunge: box=Trash uid=3277
doveadm(christoph): Debug: expunge: box=Trash uid=3278
doveadm(christoph): Debug: expunge: box=Trash uid=3279
doveadm(christoph): Debug: expunge: box=Trash uid=3280
doveadm(christoph): Debug: expunge: box=Trash uid=3281
doveadm(christoph): Debug: expunge: box=Trash uid=3282
doveadm(christoph): Debug: expunge: box=Trash uid=3283
doveadm(christoph): Debug: expunge: box=Trash uid=3284
doveadm(christoph): Debug: expunge: box=Trash uid=3285
doveadm(christoph): Debug: expunge: box=Trash uid=3286
doveadm(christoph): Debug: expunge: box=Trash uid=3287
doveadm(christoph): Debug: expunge: box=Trash uid=3288
doveadm(christoph): Debug: expunge: box=Trash uid=3289
doveadm(christoph): Debug: expunge: box=Trash uid=3290
doveadm(christoph): Debug: expunge: box=Trash uid=3291
doveadm(christoph): Debug: expunge: box=Trash uid=3292
doveadm(christoph): Debug: expunge: box=Trash uid=3293
doveadm(christoph): Debug: expunge: box=Trash uid=3294
doveadm(christoph): Debug: expunge: box=Trash uid=3295
doveadm(christoph): Debug: expunge: box=Trash uid=3296
doveadm(christoph): Debug: expunge: box=Trash uid=3297
doveadm(christoph): Debug: expunge: box=Trash uid=3298
doveadm(christoph): Debug: expunge: box=Trash uid=3299
doveadm(christoph): Debug: expunge: box=Trash uid=3300
doveadm(christoph): Debug: expunge: box=Trash uid=3301
doveadm(christoph): Debug: expunge: box=Trash uid=3302
doveadm(christoph): Debug: expunge: box=Trash uid=3303
doveadm(christoph): Debug: expunge: box=Trash uid=3304
doveadm(christoph): Debug: expunge: box=Trash uid=3305
doveadm(christoph): Debug: expunge: box=Trash uid=3306
doveadm(christoph): Debug: expunge: box=Trash uid=3307
doveadm(christoph): Debug: expunge: box=Trash uid=3308
doveadm(christoph): Debug: expunge: box=Trash uid=3309
doveadm(christoph): Debug: expunge: box=Trash uid=3310
doveadm(christoph): Debug: expunge: box=Trash uid=3311
doveadm(christoph): Debug: expunge: box=Trash uid=3312
doveadm(christoph): Debug: expunge: box=Trash uid=3313
doveadm(christoph): Debug: expunge: box=Trash uid=3314
doveadm(christoph): Debug: expunge: box=Trash uid=3315
doveadm(christoph): Debug: expunge: box=Trash uid=3316
doveadm(christoph): Debug: expunge: box=Trash uid=3317
doveadm(christoph): Debug: expunge: box=Trash uid=3318
doveadm(christoph): Debug: expunge: box=Trash uid=3319
doveadm(christoph): Debug: expunge: box=Trash uid=3320
doveadm(christoph): Debug: expunge: box=Trash uid=3321
doveadm(christoph): Debug: expunge: box=Trash uid=3322
doveadm(christoph): Debug: expunge: box=Trash uid=3323
doveadm(christoph): Debug: expunge: box=Trash uid=3324
doveadm(christoph): Debug: expunge: box=Trash uid=3325
doveadm(christoph): Debug: expunge: box=Trash uid=3326
doveadm(christoph): Debug: expunge: box=Trash uid=3327
doveadm(christoph): Debug: expunge: box=Trash uid=3328
doveadm(christoph): Debug: expunge: box=Trash uid=3329
doveadm(christoph): Debug: expunge: box=Trash uid=3330
doveadm(christoph): Debug: expunge: box=Trash uid=3331
doveadm(christoph): Debug: expunge: box=Trash uid=3332
doveadm(christoph): Debug: expunge: box=Trash uid=3333
doveadm(christoph): Debug: expunge: box=Trash uid=3334
doveadm(christoph): Debug: expunge: box=Trash uid=3335
doveadm(christoph): Debug: expunge: box=Trash uid=3336
doveadm(christoph): Debug: expunge: box=Trash uid=3337
doveadm(christoph): Debug: expunge: box=Trash uid=3338
doveadm(christoph): Debug: expunge: box=Trash uid=3339
doveadm(christoph): Debug: expunge: box=Trash uid=3340
doveadm(christoph): Debug: expunge: box=Trash uid=3341
doveadm(christoph): Debug: expunge: box=Trash uid=3342
doveadm(christoph): Debug: expunge: box=Trash uid=3343
doveadm(christoph): Debug: expunge: box=Trash uid=3344
doveadm(christoph): Debug: expunge: box=Trash uid=3345
doveadm(christoph): Debug: expunge: box=Trash uid=3346
doveadm(christoph): Debug: expunge: box=Trash uid=3347
doveadm(christoph): Debug: expunge: box=Trash uid=3348
doveadm(christoph): Debug: expunge: box=Trash uid=3349
doveadm(christoph): Debug: expunge: box=Trash uid=3350
doveadm(christoph): Debug: expunge: box=Trash uid=3351
doveadm(christoph): Debug: expunge: box=Trash uid=3352
doveadm(christoph): Debug: expunge: box=Trash uid=3353
doveadm(christoph): Debug: expunge: box=Trash uid=3354
doveadm(christoph): Debug: expunge: box=Trash uid=3355
doveadm(christoph): Debug: expunge: box=Trash uid=3356
doveadm(christoph): Debug: expunge: box=Trash uid=3357
doveadm(christoph): Debug: expunge: box=Trash uid=3358
doveadm(christoph): Debug: expunge: box=Trash uid=3359
doveadm(christoph): Debug: expunge: box=Trash uid=3360
doveadm(christoph): Debug: expunge: box=Trash uid=3361
doveadm(christoph): Debug: expunge: box=Trash uid=3362
doveadm(christoph): Debug: expunge: box=Trash uid=3363
doveadm(christoph): Debug: expunge: box=Trash uid=3364
doveadm(christoph): Debug: expunge: box=Trash uid=3365
doveadm(christoph): Debug: expunge: box=Trash uid=3366
doveadm(christoph): Debug: expunge: box=Trash uid=3367
doveadm(christoph): Debug: expunge: box=Trash uid=3368
doveadm(christoph): Debug: expunge: box=Trash uid=3369
doveadm(christoph): Debug: expunge: box=Trash uid=3370
doveadm(christoph): Debug: expunge: box=Trash uid=3371
doveadm(christoph): Debug: expunge: box=Trash uid=3372
doveadm(christoph): Debug: expunge: box=Trash uid=3373
doveadm(christoph): Debug: expunge: box=Trash uid=3374
doveadm(christoph): Debug: expunge: box=Trash uid=3375
doveadm(christoph): Debug: expunge: box=Trash uid=3376
doveadm(christoph): Debug: expunge: box=Trash uid=3377
doveadm(christoph): Debug: expunge: box=Trash uid=3378
doveadm(christoph): Debug: expunge: box=Trash uid=3379
doveadm(christoph): Debug: expunge: box=Trash uid=3380
doveadm(christoph): Debug: expunge: box=Trash uid=3381
doveadm(christoph): Debug: expunge: box=Trash uid=3382
doveadm(christoph): Debug: expunge: box=Trash uid=3383
doveadm(christoph): Debug: expunge: box=Trash uid=3384
doveadm(christoph): Debug: expunge: box=Trash uid=3385
doveadm(christoph): Debug: expunge: box=Trash uid=3386
doveadm(christoph): Debug: expunge: box=Trash uid=3387
doveadm(christoph): Debug: expunge: box=Trash uid=3388
doveadm(christoph): Debug: expunge: box=Trash uid=3389
doveadm(christoph): Debug: expunge: box=Trash uid=3390
doveadm(christoph): Debug: expunge: box=Trash uid=3391
doveadm(christoph): Debug: expunge: box=Trash uid=3392
doveadm(christoph): Debug: expunge: box=Trash uid=3393
doveadm(christoph): Debug: expunge: box=Trash uid=3394
doveadm(christoph): Debug: expunge: box=Trash uid=3395
doveadm(christoph): Debug: expunge: box=Trash uid=3396
doveadm(christoph): Debug: expunge: box=Trash uid=3397
doveadm(christoph): Debug: expunge: box=Trash uid=3398
doveadm(christoph): Debug: expunge: box=Trash uid=3399
doveadm(christoph): Debug: expunge: box=Trash uid=3400
doveadm(christoph): Debug: expunge: box=Trash uid=3401
doveadm(christoph): Debug: expunge: box=Trash uid=3402
doveadm(christoph): Debug: expunge: box=Trash uid=3403
doveadm(christoph): Debug: expunge: box=Trash uid=3404
doveadm(christoph): Debug: expunge: box=Trash uid=3405
doveadm(christoph): Debug: expunge: box=Trash uid=3406
doveadm(christoph): Debug: expunge: box=Trash uid=3407
doveadm(christoph): Debug: expunge: box=Trash uid=3408
doveadm(christoph): Debug: expunge: box=Trash uid=3409
doveadm(christoph): Debug: expunge: box=Trash uid=3410
doveadm(christoph): Debug: expunge: box=Trash uid=3411
doveadm(christoph): Debug: expunge: box=Trash uid=3412
doveadm(christoph): Debug: expunge: box=Trash uid=3413
doveadm(christoph): Debug: expunge: box=Trash uid=3414
doveadm(christoph): Debug: expunge: box=Trash uid=3415
doveadm(christoph): Debug: expunge: box=Trash uid=3416
doveadm(christoph): Debug: expunge: box=Trash uid=3417
doveadm(christoph): Debug: expunge: box=Trash uid=3418
doveadm(christoph): Debug: expunge: box=Trash uid=3419
doveadm(christoph): Debug: expunge: box=Trash uid=3420
doveadm(christoph): Debug: expunge: box=Trash uid=3421
doveadm(christoph): Debug: expunge: box=Trash uid=3422
doveadm(christoph): Debug: expunge: box=Trash uid=3423
doveadm(christoph): Debug: expunge: box=Trash uid=3424
doveadm(christoph): Debug: expunge: box=Trash uid=3425
doveadm(christoph): Debug: expunge: box=Trash uid=3426
doveadm(christoph): Debug: expunge: box=Trash uid=3427
doveadm(christoph): Debug: expunge: box=Trash uid=3428
doveadm(christoph): Debug: expunge: box=Trash uid=3429
doveadm(christoph): Debug: expunge: box=Trash uid=3430
doveadm(christoph): Debug: expunge: box=Trash uid=3431
doveadm(christoph): Debug: expunge: box=Trash uid=3432
doveadm(christoph): Debug: expunge: box=Trash uid=3433
doveadm(christoph): Debug: expunge: box=Trash uid=3434
doveadm(christoph): Debug: expunge: box=Trash uid=3435
doveadm(christoph): Debug: expunge: box=Trash uid=3436
doveadm(christoph): Debug: expunge: box=Trash uid=3437
doveadm(christoph): Debug: expunge: box=Trash uid=3438
doveadm(christoph): Debug: expunge: box=Trash uid=3439
doveadm(christoph): Debug: expunge: box=Trash uid=3440
doveadm(christoph): Debug: expunge: box=Trash uid=3441
doveadm(christoph): Debug: expunge: box=Trash uid=3442
doveadm(christoph): Debug: expunge: box=Trash uid=3443
doveadm(christoph): Debug: expunge: box=Trash uid=3444
doveadm(christoph): Debug: expunge: box=Trash uid=3445
doveadm(christoph): Debug: expunge: box=Trash uid=3446
doveadm(christoph): Debug: expunge: box=Trash uid=3447
doveadm(christoph): Debug: expunge: box=Trash uid=3448
doveadm(christoph): Debug: expunge: box=Trash uid=3449
doveadm(christoph): Debug: expunge: box=Trash uid=3450
doveadm(christoph): Debug: expunge: box=Trash uid=3451
doveadm(christoph): Debug: expunge: box=Trash uid=3452
doveadm(christoph): Debug: expunge: box=Trash uid=3453
doveadm(christoph): Debug: expunge: box=Trash uid=3454
doveadm(christoph): Debug: expunge: box=Trash uid=3455
doveadm(christoph): Debug: expunge: box=Trash uid=3456
doveadm(christoph): Debug: expunge: box=Trash uid=3457
doveadm(christoph): Debug: expunge: box=Trash uid=3458
doveadm(christoph): Debug: expunge: box=Trash uid=3459
doveadm(christoph): Debug: expunge: box=Trash uid=3460
doveadm(christoph): Debug: expunge: box=Trash uid=3461
doveadm(christoph): Debug: expunge: box=Trash uid=3462
doveadm(christoph): Debug: expunge: box=Trash uid=3463
doveadm(christoph): Debug: expunge: box=Trash uid=3464
doveadm(christoph): Debug: expunge: box=Trash uid=3465
doveadm(christoph): Debug: expunge: box=Trash uid=3466
doveadm(christoph): Debug: expunge: box=Trash uid=3467
doveadm(christoph): Debug: expunge: box=Trash uid=3468
doveadm(christoph): Debug: expunge: box=Trash uid=3469
doveadm(christoph): Debug: expunge: box=Trash uid=3470
doveadm(christoph): Debug: expunge: box=Trash uid=3471
doveadm(christoph): Debug: expunge: box=Trash uid=3472
doveadm(christoph): Debug: expunge: box=Trash uid=3473
doveadm(christoph): Debug: expunge: box=Trash uid=3474
doveadm(christoph): Debug: expunge: box=Trash uid=3475
doveadm(christoph): Debug: expunge: box=Trash uid=3476
doveadm(christoph): Debug: expunge: box=Trash uid=3477
doveadm(christoph): Debug: expunge: box=Trash uid=3478
doveadm(christoph): Debug: expunge: box=Trash uid=3479
doveadm(christoph): Debug: expunge: box=Trash uid=3480
doveadm(christoph): Debug: expunge: box=Trash uid=3481
doveadm(christoph): Debug: expunge: box=Trash uid=3482
doveadm(christoph): Debug: expunge: box=Trash uid=3483
doveadm(christoph): Debug: expunge: box=Trash uid=3484
doveadm(christoph): Debug: expunge: box=Trash uid=3485
doveadm(christoph): Debug: expunge: box=Trash uid=3486
doveadm(christoph): Debug: expunge: box=Trash uid=3487
doveadm(christoph): Debug: expunge: box=Trash uid=3488
doveadm(christoph): Debug: expunge: box=Trash uid=3489
doveadm(christoph): Debug: expunge: box=Trash uid=3490
doveadm(christoph): Debug: expunge: box=Trash uid=3491
doveadm(christoph): Debug: expunge: box=Trash uid=3492
doveadm(christoph): Debug: expunge: box=Trash uid=3493
doveadm(christoph): Debug: expunge: box=Trash uid=3494
doveadm(christoph): Debug: expunge: box=Trash uid=3495
doveadm(christoph): Debug: expunge: box=Trash uid=3496
doveadm(christoph): Debug: expunge: box=Trash uid=3497
doveadm(christoph): Debug: expunge: box=Trash uid=3498
doveadm(christoph): Debug: expunge: box=Trash uid=3499
doveadm(christoph): Debug: expunge: box=Trash uid=3500
doveadm(christoph): Debug: expunge: box=Trash uid=3501
doveadm(christoph): Debug: expunge: box=Trash uid=3502
doveadm(christoph): Debug: expunge: box=Trash uid=3503
doveadm(christoph): Debug: expunge: box=Trash uid=3504
doveadm(christoph): Debug: expunge: box=Trash uid=3505
doveadm(christoph): Debug: expunge: box=Trash uid=3506
doveadm(christoph): Debug: expunge: box=Trash uid=3507
doveadm(christoph): Debug: expunge: box=Trash uid=3508
doveadm(christoph): Debug: expunge: box=Trash uid=3509
doveadm(christoph): Debug: expunge: box=Trash uid=3510
doveadm(christoph): Debug: expunge: box=Trash uid=3511
doveadm(christoph): Debug: expunge: box=Trash uid=3512
doveadm(christoph): Debug: expunge: box=Trash uid=3513
doveadm(christoph): Debug: expunge: box=Trash uid=3514
doveadm(christoph): Debug: expunge: box=Trash uid=3515
doveadm(christoph): Debug: expunge: box=Trash uid=3516
doveadm(christoph): Debug: expunge: box=Trash uid=3517
doveadm(christoph): Debug: expunge: box=Trash uid=3518
doveadm(christoph): Debug: expunge: box=Trash uid=3519
doveadm(christoph): Debug: expunge: box=Trash uid=3520
doveadm(christoph): Debug: expunge: box=Trash uid=3521
doveadm(christoph): Debug: expunge: box=Trash uid=3522
doveadm(christoph): Debug: expunge: box=Trash uid=3523
doveadm(christoph): Debug: expunge: box=Trash uid=3524
doveadm(christoph): Debug: expunge: box=Trash uid=3525
doveadm(christoph): Debug: expunge: box=Trash uid=3526
doveadm(christoph): Debug: expunge: box=Trash uid=3527
doveadm(christoph): Debug: expunge: box=Trash uid=3528
doveadm(christoph): Debug: expunge: box=Trash uid=3529
doveadm(christoph): Debug: expunge: box=Trash uid=3530
doveadm(christoph): Debug: expunge: box=Trash uid=3531
doveadm(christoph): Debug: expunge: box=Trash uid=3532
doveadm(christoph): Debug: expunge: box=Trash uid=3533
doveadm(christoph): Debug: expunge: box=Trash uid=3534
doveadm(christoph): Debug: expunge: box=Trash uid=3535
doveadm(christoph): Debug: expunge: box=Trash uid=3536
doveadm(christoph): Debug: expunge: box=Trash uid=3537
doveadm(christoph): Debug: expunge: box=Trash uid=3538
doveadm(christoph): Debug: expunge: box=Trash uid=3539
doveadm(christoph): Debug: expunge: box=Trash uid=3540
doveadm(christoph): Debug: expunge: box=Trash uid=3541
doveadm(christoph): Debug: expunge: box=Trash uid=3542
doveadm(christoph): Debug: expunge: box=Trash uid=3543
doveadm(christoph): Debug: expunge: box=Trash uid=3544
doveadm(christoph): Debug: expunge: box=Trash uid=3545
doveadm(christoph): Debug: expunge: box=Trash uid=3546
doveadm(christoph): Debug: expunge: box=Trash uid=3547
doveadm(christoph): Debug: expunge: box=Trash uid=3548
doveadm(christoph): Debug: expunge: box=Trash uid=3549
doveadm(christoph): Debug: expunge: box=Trash uid=3550
doveadm(christoph): Debug: expunge: box=Trash uid=3551
doveadm(christoph): Debug: expunge: box=Trash uid=3552
doveadm(christoph): Debug: expunge: box=Trash uid=3553
doveadm(christoph): Debug: expunge: box=Trash uid=3554
doveadm(christoph): Debug: expunge: box=Trash uid=3555
doveadm(christoph): Debug: expunge: box=Trash uid=3556
doveadm(christoph): Debug: expunge: box=Trash uid=3557
doveadm(christoph): Debug: expunge: box=Trash uid=3558
doveadm(christoph): Debug: expunge: box=Trash uid=3559
doveadm(christoph): Debug: expunge: box=Trash uid=3560
doveadm(christoph): Debug: expunge: box=Trash uid=3561
doveadm(christoph): Debug: expunge: box=Trash uid=3562
doveadm(christoph): Debug: expire: Expunging first message in Trash, updating
timestamp to 0

On Fri, 2011-03-18 at 10:02 +0100, Christoph Pleger wrote:
> Today, the daily cronjob expired some messages. I have attached the output
of
> the job.
It looks like it expunged all the messages from Trash. Did you check if
that happened? Did it expunge too many messages?
> Mar 18 09:50:28 joseph dovecot: imap(christoph): Debug: expire: Saving
first
> message to Trash, updating timestamp to 1300438228
This happens, because it thinks the Trash was empty..

Hello,
>> Today, the daily cronjob expired some messages. I have attached the
>> output of
>> the job.
> It looks like it expunged all the messages from Trash. Did you check if
> that happened? Did it expunge too many messages?
No, after expunging, there still were messages in the Trash folder. I am
sure that exactly those messages were removed from Trash which were older
than the number of days I had given in my "doveadm expunge" command.

Regards
  Christoph