Re: Error: Found malformed header in revision file

On Thu, Mar 22, 2007 at 11:16:22PM +0100, Florent Daignière (NextGen$) wrote:
> Today the repository got corrupted twice (no software update has been
> done in the meantime). I realized it too late to get the first strace
> log ... but I got the second one. Unfortunately I was unable to get
> the memory map from /proc (I'm using -grsec on my kernel and it hasn't
> helped).
>
> Each time it has been a "Found malformed header in revision file" error
> (like the first two ones I reported).
>

So I finally had a chance to look at this briefly last night. I've
attached the lines that refer directly to the rev-file; you can see that
there's an 'obvious' duplication of the noderev.

Unfortunately, there's also a whole load of suspicious stuff happening
between the first and second (duplicated) write, so I can't really be
sure what's going on yet.

I notice you're using PHP - I wonder if this is another case of PHP
"surely there can't be any other modules running" disease, and it's
doing something to trip us up? (I'm thinking of the SIGCHLD reaping that
it can carry out in some cases - this is obviously more serious).

Regards,
Malcolm

24340 20:51:05.332962 [b7f4e402] open("/var/freenet-svn/svn/db/transactions/12280-1.txn/rev", O_WRONLY|O_CREAT|O_EXCL|O_LARGEFILE, 0666) = 18
24340 20:51:05.333068 [b7f4e402] write(18, "", 0) = 0
24340 20:51:05.333132 [b7f4e402] close(18) = 0
24340 20:51:05.726820 [b7f4e402] open("/var/freenet-svn/svn/db/transactions/12280-1.txn/rev", O_WRONLY|O_LARGEFILE) = 26
24340 20:51:05.726912 [b7f4e402] fstat64(26, {st_mode=S_IFREG|0644, st_size=0, ...}) = 0
24340 20:51:05.728533 [b7f4e402] write(26, "DELTA 12277 3459 6737\n", 22) = 22
24340 20:51:05.829298 [b7f4e402] write(26, "SVN\1\0\205\351X\205\352#\20\201!\17\0\203\225z\0\200\201"..., 191) = 191
24340 20:51:05.829978 [b7f4e402] write(26, "ENDREP\n", 7) = 7
24340 20:51:05.830054 [b7f4e402] close(26) = 0
24340 20:51:05.937563 [b7f4e402] open("/var/freenet-svn/svn/db/transactions/12280-1.txn/rev", O_WRONLY|O_LARGEFILE) = 26
24340 20:51:05.937652 [b7f4e402] fstat64(26, {st_mode=S_IFREG|0644, st_size=220, ...}) = 0
24340 20:51:05.937746 [b7f4e402] _llseek(26, 220, [220], SEEK_SET) = 0
24340 20:51:05.950444 [b7f4e402] write(26, "DELTA 11270 0 4065\n", 19) = 19
24340 20:51:05.975945 [b7f4e402] write(26, "SVN\1\0\201\276\23\201\277\22-\202I,\0\210\30\0\200q\0"..., 388) = 388
24340 20:51:05.976550 [b7f4e402] write(26, "ENDREP\n", 7) = 7
24340 20:51:05.976625 [b7f4e402] close(26) = 0
24340 20:51:06.301414 [b7f4e402] open("/var/freenet-svn/svn/db/transactions/12280-1.txn/rev", O_WRONLY|O_LARGEFILE) = 22
24340 20:51:06.301515 [b7f4e402] fstat64(22, {st_mode=S_IFREG|0644, st_size=634, ...}) = 0
24340 20:51:06.301616 [b7f4e402] _llseek(22, 634, [634], SEEK_SET) = 0
17649 20:51:06.315621 [b7f4e402] write(22, "id: 4on.0.r12281/634\ntype: file\n"..., 256) = 256
24340 20:51:06.433223 [b7f4e402] write(22, "id: 4on.0.r12281/634\ntype: file\n"..., 256) = 256
24340 20:51:06.433917 [b7f4e402] write(22, "id: 4om.0.r12281/890\ntype: file\n"..., 245) = 245
24340 20:51:06.434178 [b7f4e402] write(22, "PLAIN\nK 20\nAnyInsertSender.java\n"..., 3772) = 3772
24340 20:51:06.434687 [b7f4e402] write(22, "id: 4oi.0.r12281/4907\ntype: dir\n"..., 182) = 182
24340 20:51:06.434811 [b7f4e402] write(22, "PLAIN\nK 6\nclient\nV 21\ndir 4rg.0."..., 559) = 559
24340 20:51:06.435338 [b7f4e402] write(22, "id: 4ja.0.r12281/5648\ntype: dir\n"..., 227) = 227
24340 20:51:06.435450 [b7f4e402] write(22, "PLAIN\nK 7\nfreenet\nV 21\ndir 4ja.0"..., 127) = 127
24340 20:51:06.435928 [b7f4e402] write(22, "id: 4j9.0.r12281/6002\ntype: dir\n"..., 222) = 222
24340 20:51:06.436053 [b7f4e402] write(22, "PLAIN\nK 10\n.classpath\nV 20\nfile "..., 609) = 609
24340 20:51:06.436509 [b7f4e402] write(22, "id: 2xq.0.r12281/6833\ntype: dir\n"..., 216) = 216
24340 20:51:06.436620 [b7f4e402] write(22, "PLAIN\nK 4\napps\nV 21\ndir 4uh.0.r1"..., 288) = 288
24340 20:51:06.437067 [b7f4e402] write(22, "id: 29y.0.r12281/7337\ntype: dir\n"..., 156) = 156
24340 20:51:06.437170 [b7f4e402] write(22, "PLAIN\nK 8\nbranches\nV 21\ndir 2xp."..., 127) = 127
24340 20:51:06.437600 [b7f4e402] write(22, "id: 0.0.r12281/7620\ntype: dir\npr"..., 147) = 147
24340 20:51:06.438125 [b7f4e402] write(22, "4om.0.t12280-1 modify true false"..., 173) = 173
24340 20:51:06.458865 [b7f4e402] fsync(22) = 0
24340 20:51:06.458963 [b7f4e402] close(22) = 0
24340 20:51:06.460220 [b7f4e402] chmod("/var/freenet-svn/svn/db/transactions/12280-1.txn/rev", 0644) = 0
24340 20:51:06.460328 [b7f4e402] rename("/var/freenet-svn/svn/db/transactions/12280-1.txn/rev", "/var/freenet-svn/svn/db/revs/12281") = 0