[Dovecot] Stalled imaptest10 process

[Steffen Kaiser]

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On Wed, 21 Mar 2007, Timo Sirainen wrote:

> bug in imaptest.. Do you get the hangs (without the patch) without
> making imaptest do copying?

Not that I noticed them. Because imaptest is working on the same mailbox 
simultaneously, it hard to simulate. In normal work I found no stalls at 
all.

>> Info: dvtest: Mailbox is in inconsistent state, please relogin.
>
> This indicates an error.
>
> But none of these log lines were errors. Try "grep Error|tail -20", what
> does it show?

Some "internal errors". nothing else (also without the tail).

In the server log I have this:

In total, during the 2 houres the following errors got logged:
347x Corrupted transaction log file 
/var/cache/dovecot/31045/index/.box1/dovecot.index.log: Append with UID 
83684, but next_uid = 83685
50x Corrupted transaction log file 
/var/cache/dovecot/31045/index/.box1/dovecot.index.log: invalid new 
transaction log sequence (291 >= 291)
2x orrupted transaction log file 
/var/cache/dovecot/31045/index/.box1/dovecot.index.log: start_offset (500) 
> file size (24)
216x View synchronization from transaction log for index 
/var/cache/dovecot/31045/index/.box0/dovecot.index failed: Append with UID 
116034, but next_uid = 116035
1x broken sync positions in index file 
/var/cache/dovecot/31045/index/.box1/dovecot.index
1x Fixed index file /var/cache/dovecot/31045/index/.box1/dovecot.index: 
log file sync pos 185,388 -> 186, 24
21x Unexpected transaction log desync with index 
/var/cache/dovecot/31045/index/.box1/dovecot.index

I used: "grep Error: dovecot.log" to get the errors and tested with "sort 
- -u", that there is no duplicate line.

==============

dovecot: Mar 21 15:37:08 Error: IMAP(dvtest) [10591]: Fixed index file 
/var/cache/dovecot/31045/index/.Trash/dovecot.index: first_recent_u
id_lowwater 121713 -> 121271
dovecot: Mar 21 15:37:08 Error: IMAP(dvtest) [10591]: Corrupted index file 
/var/cache/dovecot/31045/index/.Trash/dovecot.index: Record fie
ld keywords points outside record size (12 < 12+2)
dovecot: Mar 21 15:37:08 Error: IMAP(dvtest) [10591]: Corrupted index file 
/var/cache/dovecot/31045/index/.Trash/dovecot.index: Record fie
ld keywords points outside record size (12 < 12+2)
dovecot: Mar 21 15:37:11 Error: IMAP(dvtest) [10558]: Corrupted index file 
/var/cache/dovecot/31045/index/.Trash/dovecot.index: Record fie
ld keywords points outside record size (12 < 12+2)
dovecot: Mar 21 15:37:11 Error: IMAP(dvtest) [10558]: Corrupted index file 
/var/cache/dovecot/31045/index/.Trash/dovecot.index: Record fie
ld keywords points outside record size (12 < 12+2)
dovecot: Mar 21 15:37:12 Error: IMAP(dvtest) [10558]: Corrupted index 
cache file /var/cache/dovecot/31045/index/.Trash/dovecot.index.cache
: indexid changed


dovecot: Mar 20 17:53:12 Error: IMAP(dvtest) [1704]: View synchronization 
from transaction log for index /var/cache/dovecot/31045/index/.b
ox0/dovecot.index failed: Append with UID 115022, but next_uid = 115024
dovecot: Mar 20 17:53:43 Error: IMAP(dvtest) [2332]: View synchronization 
from transaction log for index /var/cache/dovecot/31045/index/.b
ox0/dovecot.index failed: Append with UID 115310, but next_uid = 115311
dovecot: Mar 20 17:53:45 Error: IMAP(dvtest) [2379]: Corrupted transaction 
log file /var/cache/dovecot/31045/index/.box1/dovecot.index.log
: Append with UID 83595, but next_uid = 83596
dovecot: Mar 20 17:53:45 Error: IMAP(dvtest) [2330]: Corrupted transaction 
log file /var/cache/dovecot/31045/index/.box1/dovecot.index.log
: Append with UID 83595, but next_uid = 83596
dovecot: Mar 20 17:54:00 Error: IMAP(dvtest) [2508]: View synchronization 
from transaction log for index /var/cache/dovecot/31045/index/.b
ox0/dovecot.index failed: Append with UID 115458, but next_uid = 115459
dovecot: Mar 20 17:54:04 Error: IMAP(dvtest) [2736]: Corrupted transaction 
log file /var/cache/dovecot/31045/index/.box1/dovecot.index.log
: Append with UID 83684, but next_uid = 83685
dovecot: Mar 20 17:54:04 Error: IMAP(dvtest) [2703]: Corrupted transaction 
log file /var/cache/dovecot/31045/index/.box1/dovecot.index.log
: Append with UID 83684, but next_uid = 83685
dovecot: Mar 20 17:54:05 Error: IMAP(dvtest) [2478]: Corrupted transaction 
log file /var/cache/dovecot/31045/index/.box1/dovecot.index.log
: Append with UID 83684, but next_uid = 83685
dovecot: Mar 20 17:54:06 Error: IMAP(dvtest) [2595]: Corrupted transaction 
log file /var/cache/dovecot/31045/index/.box1/dovecot.index.log
: Append with UID 83684, but next_uid = 83685
dovecot: Mar 20 17:55:05 Error: IMAP(dvtest) [3724]: View synchronization 
from transaction log for index /var/cache/dovecot/31045/index/.b
ox0/dovecot.index failed: Append with UID 116034, but next_uid = 116035

Bye,

- -- 
Steffen Kaiser
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.6 (GNU/Linux)

iQEVAwUBRgI1ti9SORjhbDpvAQKo4gf+M1RjxtUSmpuZnf6X9m9Dp88K8lR46Dhz
IwclcC1H31YazpW5yK5oeT448SRzeSdw5NZZDcFVXLnCJRMAMkcT1bAuy7+1NvpX
mvqxgR5dj/vRorA01Hd1JyctwegGCR8phyhcSIJdf6w92r8NGqp+wJ7MiGH6vDBH
ch/gjb8YqwU6CHX8gAsy+KkXq7KB9NvnWnySRw6DMWmovqo80yt4/s9XDcFuj+sZ
mG5uFvkU4S2qu1RbGN5Ww8N/ogohGnwzzM7eB/Qd1i3wpYaF4D8UoAkXcWR9HJu4
nhcnfuHtZUFnWmteNF9ItNE8t15x4RMNuVyDvjRf8kz3ngBxmp343w==
=DTTq
-----END PGP SIGNATURE-----