Page 1 of 1

Bus error with fsarchiver on SystemRescueCD

Posted: Fri Apr 30, 2010 2:05 pm
by ayuyaia
I am a first-time user of fsarchiver, running it from SystemRescueCD 1.5.2 (default boot options). I used it to backup a fairly small ext4 partition (<10 Gb data) to a ntfs partition, with options "-v -z1". FSArchiver ran fine, but on completion I received the error message

zsh: bus error fsarchiver....

before returning to the command prompt (where fsarchiver... stands for the original command line).
After this happened, fsarchiver would no longer run (calling it resulted in another zsh:bus error line).

Is this just a problem with the shell used on the SystemRescueCD?
Could it affect the integrity of the archive file which was generated?

Running fsarchiver archinfo on the generated fsa file, everything looks OK, but is there a way to verify its integrity without restoring the filesystem from it?

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Tue May 04, 2010 5:57 pm
by admin
Thanks for having reported that problem. Unfortunately there is currently no command to check the archive, so you should try to restore it. It can be due to a bug in the program.

Can you check the memory of that computer using memtest which is accessible from the sub menus in SystemRescueCd-1.5.2+ ?

Is that an error that you can reproduce if you run the same command again ?

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Thu May 06, 2010 2:25 pm
by ayuyaia
I ran memtest and the memory is fine. I have also run fsarchiver again using the same command, but without the -v option. This did not produce the error - so maybe (long shot) it is related to all that text being dumped to the shell?
Unfortunately I do not have a spare partition of the same type so I cannot currently restore the archive to check its integrity.

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Mon May 10, 2010 10:04 am
by admin
We can also suspect a problem with the shell if it only happens when fsarchiver terminates.
But we cannot really be sure. It could be interesting to run multiple tests with a different shell (bash) to see what happens.
You can type "exec bash" from zsh just to be sure that zsh is not involved in that session. Else bash would be the child of zsh which is still in memory. With exec we are sure that the process has been replaced with bash in memory.

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Sat May 15, 2010 8:02 pm
by ayuyaia
I have run the same command with the -v option under bash as you suggested. This also produces a bus error! (The error message is just "Bus error")

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Fri May 28, 2010 1:26 am
by ayuyaia
I've now also experienced the error when running fsarchiver without the -v option (just -z1). No idea what the cause could be.

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Fri May 28, 2010 10:51 am
by admin
Could you run fsarchiver from strace to see what's in the logs ?
Basically you just have to run fsarchiver with its arguments as an option of strace:

Code: Select all

strace -o /var/tmp/fsarchiver-strace.log fsarchiver savefs /path/to/myarchive.fsa /dev/xxx /dev/yyy
It will produce a log in /var/tmp/fsarchiver-strace.log and we can see what's at the very end of this log, just before the error is reported.

Also have you noticed differences when you used fsarchiver in a different way ? For instance do you only have that error when your to savefs or is that also with other commands such as restfs or archinfo ?

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Sun May 30, 2010 8:36 pm
by ayuyaia
I have never observed a bus error on running archinfo. I currently don't have the space to try restfs (which is the reason I'd really like to know whether the bus errors are causing any corruption of the fsa files produced).

I ran fsarchiver as above with strace, without -v. After printing the statistics to the screen at the end, the program did not return to the system prompt. Very strange behaviour - it was possible to type on the screen, but nothing was executed, nor did ctrl+c or such work. Neither was there a bus error message (so I can't know whether a bus error actually occurred).

Tail of strace log (let me know if you need more):

Code: Select all

rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
lstat64("/tmp/fsa/20100530-172729-00/.kde/share/config", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
open("/tmp/fsa/20100530-172729-00/.kde/share/config", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 7
lstat64("/tmp/fsa/20100530-172729-00/.kde/share/config", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
llistxattr("/tmp/fsa/20100530-172729-00/.kde/share/config", 0xbfccfd50, 4095) = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787756) = 2
getdents64(7, /* 2 entries */, 32768)   = 48
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
getdents64(7, /* 0 entries */, 32768)   = 0
close(7)                                = 0
getdents64(6, /* 0 entries */, 32768)   = 0
close(6)                                = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
getdents64(5, /* 0 entries */, 32768)   = 0
close(5)                                = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
lstat64("/tmp/fsa/20100530-172729-00/sys", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
open("/tmp/fsa/20100530-172729-00/sys", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 5
lstat64("/tmp/fsa/20100530-172729-00/sys", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
llistxattr("/tmp/fsa/20100530-172729-00/sys", 0xbfcd5e90, 4095) = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787762) = 2
getdents64(5, /* 2 entries */, 32768)   = 48
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
getdents64(5, /* 0 entries */, 32768)   = 0
close(5)                                = 0
getdents64(4, /* 0 entries */, 32768)   = 0
close(4)                                = 0
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787768) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787772) = 1
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787776) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787782) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787788) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787794) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787800) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787806) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787812) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787818) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787824) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787830) = 2
futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1787832) = 1
rt_sigpending([])                       = 0
rt_sigpending([])                       = 0
write(2, "Statistics for filesystem 0\n", 28) = 28
write(2, "* files successfully processed:."..., 115) = 115
write(2, "* files with errors:............"..., 98) = 98

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Mon Jun 07, 2010 6:00 pm
by admin
I don't see any problem there. Could you run bash in strace with the -f option so that we can see if the problem is with the shell.
It will trace both bash and fsarchiver (its child):

1) strace -f -o /var/tmp/strace.txt bash
2) in bash just run fsarchiver
3) type "exit" in bash

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Mon Jun 07, 2010 6:42 pm
by ayuyaia
Will do this later. It struck me that the computer I am running it on has comparatively little memory (640Mb) - could this be an issue?

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Tue Jun 08, 2010 11:27 am
by admin
A computer with not enough memory can have errors if you run very high compression levels (-z8 or -z9) especially if you are running multiple compression jobs (-jX). But it should not be a problem to just run fsarchiver with the default compression options (equivalent to -z3 -j1).

With the new strace, I hope we will see if the error comes from the shell.

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Tue Jun 08, 2010 11:55 pm
by ayuyaia
OK, I ran fsarchiver in bash as suggested, with -v -z1. The behaviour was as described above for the first strace run with zsh - fsarchiver does not terminate properly and does not return to the shell.
Tail of the log looks very different, possibly due to -v turned on. Since I couldn't get back to a prompt and so had to reboot, the abrupt end at the EOF might be due to that.

Code: Select all

1627  lstat64("/tmp/fsa/20100608-194619-00/.kde/share/config", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
1627  open("/tmp/fsa/20100608-194619-00/.kde/share/config", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 7
1627  lstat64("/tmp/fsa/20100608-194619-00/.kde/share/config", {st_mode=S_IFDIR|0700, st_size=4096, ...}) = 0
1627  llistxattr("/tmp/fsa/20100608-194619-00/.kde/share/config", 0xbfb13c30, 4095) = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  write(2, "-[00][ 99%][DIR     ] /.kde/shar"..., 41) = 41
1627  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038852 <unfinished ...>
1629  <... futex resumed> )             = 0
1627  <... futex resumed> )             = 2
1629  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1627  getdents64(7,  <unfinished ...>
1629  <... futex resumed> )             = 1
1628  <... futex resumed> )             = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... _llseek resumed> [7445922705], SEEK_CUR) = 0
1628  <... clock_gettime resumed> {1276039404, 942210314}) = 0
1629  write(3, "ObJt#{\2L\0\0{\0\0\0\t\0\4\0\1\0\10\0\23\34\4\0\0\0\0\0\6\0"..., 141 <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1627  <... getdents64 resumed> /* 2 entries */, 32768) = 48
1629  <... write resumed> )             = 141
1628  <... futex resumed> )             = 0
1629  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... clock_gettime resumed> {1276039404, 947372982}) = 0
1628  <... clock_gettime resumed> {1276039404, 947910201}) = 0
1629  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038853, {0, 994300113} <unfinished ...>
1629  <... clock_gettime resumed> {1276039404, 951654532}) = 0
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039404, 954305988}) = 0
1629  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038854, {0, 995718450} <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038854, {0, 987904326} <unfinished ...>
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  getdents64(7, /* 0 entries */, 32768) = 0
1627  close(7)                          = 0
1627  getdents64(6, /* 0 entries */, 32768) = 0
1627  close(6)                          = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  getdents64(5, /* 0 entries */, 32768) = 0
1627  close(5)                          = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  lstat64("/tmp/fsa/20100608-194619-00/sys", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
1627  open("/tmp/fsa/20100608-194619-00/sys", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 5
1627  lstat64("/tmp/fsa/20100608-194619-00/sys", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
1627  llistxattr("/tmp/fsa/20100608-194619-00/sys", 0xbfb19d70, 4095) = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  write(2, "-[00][100%][DIR     ] /sys\n", 27) = 27
1627  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038856 <unfinished ...>
1629  <... futex resumed> )             = 0
1627  <... futex resumed> )             = 2
1629  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1627  getdents64(5,  <unfinished ...>
1629  <... futex resumed> )             = 1
1628  <... futex resumed> )             = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... _llseek resumed> [7445922846], SEEK_CUR) = 0
1628  <... clock_gettime resumed> {1276039404, 990910926}) = 0
1629  write(3, "ObJt#{\2L\0\0m\0\0\0\t\0\4\0\1\0\10\0\24\34\4\0\0\0\0\0\6\0"..., 127 <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1) = 0
1629  <... write resumed> )             = 127
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1628  <... clock_gettime resumed> {1276039404, 995119562}) = 0
1629  <... clock_gettime resumed> {1276039404, 995623257}) = 0
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038857, {0, 995791364} <unfinished ...>
1629  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... clock_gettime resumed> {1276039404, 997668769}) = 0
1628  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038858, {0, 997954488} <unfinished ...>
1628  <... clock_gettime resumed> {1276039405, 1109989}) = 0
1627  <... getdents64 resumed> /* 2 entries */, 32768) = 48
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1627  rt_sigpending( <unfinished ...>
1628  <... futex resumed> )             = 0
1627  <... rt_sigpending resumed> [])   = 0
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038858, {0, 989800937} <unfinished ...>
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  rt_sigpending([])                 = 0
1627  getdents64(5, /* 0 entries */, 32768) = 0
1627  close(5)                          = 0
1627  getdents64(4, /* 0 entries */, 32768) = 0
1627  close(4)                          = 0
1627  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038860 <unfinished ...>
1629  <... futex resumed> )             = 0
1627  <... futex resumed> )             = 2
1629  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1627  rt_sigpending( <unfinished ...>
1629  <... futex resumed> )             = 1
1628  <... futex resumed> )             = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... _llseek resumed> [7445922973], SEEK_CUR) = 0
1628  <... futex resumed> )             = 0
1629  write(3, "ObJt#{\2L\0\0\256\0\0\0\f\0\4\0\1\0\10\0\1\34\4\0\0\0\0\0\6\0"..., 192 <unfinished ...>
1627  <... rt_sigpending resumed> [])   = 0
1627  rt_sigpending( <unfinished ...>
1629  <... write resumed> )             = 192
1627  <... rt_sigpending resumed> [])   = 0
1629  _llseek(3, 0,  <unfinished ...>
1627  write(2, "Statistics for filesystem 0\n", 28 <unfinished ...>
1629  <... _llseek resumed> [7445923165], SEEK_CUR) = 0
1628  madvise(0xb6449000, 622592, MADV_DONTNEED <unfinished ...>
1629  write(3, "ObJt#{\2L\0\0\246\0\0\0\f\0\4\0\1\0\10\0\2\34\4\0\0\0\0\0\6\0"..., 184 <unfinished ...>
1627  <... write resumed> )             = 28
1627  write(2, "* files successfully processed:."..., 115 <unfinished ...>
1629  <... write resumed> )             = 184
1627  <... write resumed> )             = 115
1629  futex(0xb6500010, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  <... madvise resumed> )           = 0
1627  write(2, "* files with errors:............"..., 98 <unfinished ...>
1628  futex(0xb6500010, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1627  <... write resumed> )             = 98
1629  <... futex resumed> )             = 0
1628  <... futex resumed> )             = 1
1629  futex(0xb6500010, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 44391760}) = 0
1629  futex(0x81a9260, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 48448422}) = 0
1629  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038861, {0, 995943338} <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038862 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 54478801}) = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... _llseek resumed> [7445923349], SEEK_CUR) = 0
1628  <... futex resumed> )             = 0
1629  write(3, "ObJt#{\2L\0\0\255\0\0\0\f\0\4\0\1\0\10\0\4\34\4\0\0\0\0\0\6\0"..., 191 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME, {1276039405, 59378587}) = 0
1629  <... write resumed> )             = 191
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038863, {0, 995100214} <unfinished ...>
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038864 <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  _llseek(3, 0,  <unfinished ...>
1628  <... clock_gettime resumed> {1276039405, 63617116}) = 0
1629  <... _llseek resumed> [7445923540], SEEK_CUR) = 0
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  write(3, "ObJt#{\2L\0\0\255\0\0\0\f\0\4\0\1\0\10\0\5\34\4\0\0\0\0\0\6\0"..., 191 <unfinished ...>
1628  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... write resumed> )             = 191
1628  <... clock_gettime resumed> {1276039405, 67789994}) = 0
1629  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038865, {0, 995827122} <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038866 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 74056436}) = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... _llseek resumed> [7445923731], SEEK_CUR) = 0
1628  <... futex resumed> )             = 0
1629  write(3, "ObJt#{\2L\0\0\251\0\0\0\f\0\4\0\1\0\10\0\6\34\4\0\0\0\0\0\6\0"..., 187 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME, {1276039405, 78409225}) = 0
1629  <... write resumed> )             = 187
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038867, {0, 995647211} <unfinished ...>
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038868 <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  _llseek(3, 0,  <unfinished ...>
1628  <... clock_gettime resumed> {1276039405, 82489354}) = 0
1629  <... _llseek resumed> [7445923918], SEEK_CUR) = 0
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  write(3, "ObJt#{\2L\0\0\247\0\0\0\f\0\4\0\1\0\10\0\7\34\4\0\0\0\0\0\6\0"..., 185 <unfinished ...>
1628  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... write resumed> )             = 185
1628  <... clock_gettime resumed> {1276039405, 86541826}) = 0
1629  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038869, {0, 995947528} <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038870 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 92507111}) = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... _llseek resumed> [7445924103], SEEK_CUR) = 0
1628  <... futex resumed> )             = 0
1629  write(3, "ObJt#{\2L\0\0\246\0\0\0\f\0\4\0\1\0\10\0\10\34\4\0\0\0\0\0\6\0"..., 184 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME, {1276039405, 97465006}) = 0
1629  <... write resumed> )             = 184
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038871, {0, 995042105} <unfinished ...>
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038872 <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  _llseek(3, 0,  <unfinished ...>
1628  <... clock_gettime resumed> {1276039405, 101732590}) = 0
1629  <... _llseek resumed> [7445924287], SEEK_CUR) = 0
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  write(3, "ObJt#{\2L\0\0\250\0\0\0\f\0\4\0\1\0\10\0\t\34\4\0\0\0\0\0\6\0"..., 186 <unfinished ...>
1628  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... write resumed> )             = 186
1628  <... clock_gettime resumed> {1276039405, 105898763}) = 0
1629  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038873, {0, 995833827} <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038874 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 112095362}) = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... _llseek resumed> [7445924473], SEEK_CUR) = 0
1628  <... futex resumed> )             = 0
1629  write(3, "ObJt#{\2L\0\0\246\0\0\0\f\0\4\0\1\0\10\0\n\34\4\0\0\0\0\0\6\0"..., 184 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME, {1276039405, 116435301}) = 0
1629  <... write resumed> )             = 184
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038875, {0, 995660061} <unfinished ...>
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038876 <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  _llseek(3, 0,  <unfinished ...>
1628  <... clock_gettime resumed> {1276039405, 120497830}) = 0
1629  <... _llseek resumed> [7445924657], SEEK_CUR) = 0
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  write(3, "ObJt#{\2L\0\0\247\0\0\0\f\0\4\0\1\0\10\0\v\34\4\0\0\0\0\0\6\0"..., 185 <unfinished ...>
1628  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... write resumed> )             = 185
1628  <... clock_gettime resumed> {1276039405, 124526556}) = 0
1629  futex(0x81a9278, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
1628  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1628  <... futex resumed> )             = 0
1629  futex(0x81a9278, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038877, {0, 995971274} <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038878 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  <... futex resumed> )             = 0
1628  <... clock_gettime resumed> {1276039405, 131053924}) = 0
1629  _llseek(3, 0,  <unfinished ...>
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  <... _llseek resumed> [7445924842], SEEK_CUR) = 0
1628  <... futex resumed> )             = 0
1629  write(3, "ObJt#{\2L\0\0\265\0\0\0\f\0\4\0\1\0\10\0\17\34\4\0\0\0\0\0\6\0"..., 199 <unfinished ...>
1628  clock_gettime(CLOCK_REALTIME, {1276039405, 135557850}) = 0
1629  <... write resumed> )             = 199
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038879, {0, 995496074} <unfinished ...>
1629  futex(0x81a927c, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x81a9260, 1038880 <unfinished ...>
1628  <... futex resumed> )             = -1 EAGAIN (Resource temporarily unavailable)
1629  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME,  <unfinished ...>
1629  _llseek(3, 0,  <unfinished ...>
1628  <... clock_gettime resumed> {1276039405, 140208163}) = 0
1629  <... _llseek resumed> [7445925041], SEEK_CUR) = 0
1628  futex(0x81a9260, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
1629  write(3, "BlKh#{\2L\0\0H\0\0\0\7\0\4\0\2\0\10\0\0\0\0\0\0\0\0\0\3\0"..., 48260 <unfinished ...>
1628  <... futex resumed> )             = 0
1628  clock_gettime(CLOCK_REALTIME, {1276039405, 145012965}) = 0
1628  futex(0x81a927c, FUTEX_WAIT_PRIVATE, 1038881, {0, 995195198} <unfinished ...>
1629  <... write resumed> )             = 48260
1629  munmap(0xb6400000, 1048576 <unfinished ...>
1628  <... futex resumed> )             = -1 ETIMEDOUT (Connection timed out)
1628  clock_gettime(CLOCK_REALTIME, 

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Thu Jun 17, 2010 5:40 pm
by admin
Thanks for this new log file. Unfortunately I can't see anything that would explain the problem. Another approach would be to run fsarchiver in various conditions to narrow it down to one particular thing (or combination of things) that are necessary to have this problem. But it can take some time. And it's difficult if you don't have a lot of disk space and machines. We can also just see if other users report the same error...

Re: Bus error with fsarchiver on SystemRescueCD

Posted: Thu Jun 17, 2010 7:26 pm
by ayuyaia
Of course it's possible the relevant part of the trace would have followed on from where the log stops... Yes, maybe someone else will report the same problem.

What would be a great help would be if fsarchiver had a "verify archive integrity" function - then it would be easy to put one's mind at rest.