Hello everybody,
at the moment, I am attempting to restore a file backed up earlier using
backuppc. Unfortunately, the restore stalls before completion and I am
somewhat stuck in the analysis of its cause.
Initially, some information: I am using backuppc version 3.0.0 as
shipped with Ubuntu Gutsy. Backuppc is running on localhost, which is
also the host backed up. The file to be restored is 104653731840 bytes
in size and was backed up by a full backup.
localhost.pl looks like this:
-- 8< --
$Conf{XferMethod} = 'rsync';
$Conf{BackupFilesExclude} = ['/media/backup', '/proc', '/sys', \
'/media/data'];
$Conf{RsyncClientPath} = '/usr/bin/rsync';
$Conf{RsyncClientCmd} = '/usr/bin/nice -n 19 /usr/bin/sudo $rsyncPath \
$argList+'
$Conf{RsyncClientRestoreCmd} = '/usr/bin/nice -n 19 /usr/bin/sudo \
$rsyncPath $argList+';
$Conf{RsyncShareName} = ['/'];
-- 8< --
CompressLevel is set to '3' in config.pl.
Starting the restore ended up with three processes:
-- 8< --
backuppc 7228 44.3 2.8 106824 58136 ? S 21:03 17:55 \
/usr/bin/perl /usr/share/backuppc/bin/BackupPC_restore localhost lo
root 7231 0.1 0.0 10664 824 ? SN 21:03 0:03 \
/usr/bin/rsync --server --numeric-ids --perms --owner --group -D --
root 7232 10.1 0.0 10924 736 ? SN 21:03 4:05 \
/usr/bin/rsync --server --numeric-ids --perms --owner --group -D --
-- 8< --
Using strace on all three processes during the whole restore shows what
happens until the stall occurs:
BackupPC_restore (pid 7228):
-- 8< --
[...]
select(8, [6], NULL, [6], NULL) = 1 (in [6])
read(6, "\200\23\0\0\0\0\304\0063\\\35=\220\360\326Xy\0024\276\246"...,\
65536) = 1959
alarm(72000) = 72000
select(8, [6], NULL, [6], NULL
-- 8< --
rsync (pid 7231):
-- 8< --
[...]
select(4, [3], [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "&O(\26 \37\0\0\0\0\304\0063\307\34\322}e\324 \
\200\2\n\366"..., 4092) = 4092
read(0, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., \
262144) = 262144
read(0, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., \
247808) = 247808
munmap(0x2b8fe429c000, 266240) = 0
close(0) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\247\7\0\7", 4) = 4
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\200\23\0\0\0\0\304\0063\\\35=\220\360\326Xy\0024\276 \
\246"..., 1959) = 1959
select(4, [3], [], NULL, {60, 0}) = 0 (Timeout)
[... repeating ...]
select(4, [3], [], NULL, {60, 0}) = 0 (Timeout)
-- 8< --
rsync (pid 7232):
-- 8< --
[...]
select(1, [0], [], NULL, {60, 0}) = 1 (in [0], left {60, 0})
read(0, "\377\377\377\377\377\377\377\377\377\377\377\377\377\377"..., \
8184) = 1300
write(3, "\377\377\377\377\377\377\377\377\377\377\377\377\377 \
\377"..., 262144) = 262144
select(1, [0], [], NULL, {60, 0}) = 0 (Timeout)
[... repeating ...]
select(1, [0], [], NULL, {60, 0}) = 0 (Timeout)
-- 8< --
The log is not of much help as it just provides a single relevant entry:
-- 8< --
2007-11-03 21:03:52 Started restore on localhost (pid=7228)
-- 8< --
Any advice how to track down the cause of this issue is highly appreciated!
Let me know if any important info is missing.
Thanks,
Thilo
-------------------------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems? Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
_______________________________________________
BackupPC-users mailing list
BackupPC-users@lists.sourceforge.net
List: https://lists.sourceforge.net/lists/listinfo/backuppc-users
Wiki: http://backuppc.wiki.sourceforge.net
Project: http://backuppc.sourceforge.net/
