Le Wednesday 21 November 2007 20:07:47, vous avez écrit : > You should never get a "hmm, disk was stranded on waitq" error message. > Post the complete amdump.X log file from the server and the complete > amanda.*.debug and sendsize.*.debug files from the client. >
The sendsize.*.debug now. And some news... On the server, due to an undetected bad operation, / was full... /var/lib/amanda, where the holding disk and vtapes are, is not on /, however ~amanda is (I have changed its home to /home/amanda and made a symlink /var/lib/amanda/gnutar-lists to /home/amanda/gnutar-lists because we need the account for custom scripts). I don't know for how long it has been so, and whether this can explain the errors. I've seen your patch. Will apply. Thanks! -- Francis Galiegue, [EMAIL PROTECTED] - Ingénieur système [ATTENTION : CHANGEMENT DE COORDONNÉES !] One2team - 42 Av. Raymond Poincaré - 75116 PARIS CEDEX +33683877875, +33178945552
sendsize: debug 1 pid 8229 ruid 33 euid 33: start at Wed Nov 21 01:10:20 2007 sendsize: version 2.4.4p3 sendsize[8231]: time 0.095: calculating for amname '/var/spool/sbackup/current', dirname '/var/spool/sbackup/current', spindle -1 sendsize[8231]: time 0.095: getting size via gnutar for /var/spool/sbackup/current level 0 sendsize[8229]: time 0.095: waiting for any estimate child: 1 running sendsize[8231]: time 0.164: spawning /usr/lib/amanda/runtar in pipeline sendsize[8231]: argument list: /bin/tar --create --file /dev/null --directory /var/spool/sbackup/current --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_spool_sbackup_current_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_spool_sbackup_current.20071121011021.exclude . sendsize[8231]: time 0.348: Total bytes written: 2014679040 (1.9GiB, ?/s) sendsize[8231]: time 0.348: ..... sendsize[8231]: estimate time for /var/spool/sbackup/current level 0: 0.183 sendsize[8231]: estimate size for /var/spool/sbackup/current level 0: 1967460 KB sendsize[8231]: time 0.348: waiting for /bin/tar "/var/spool/sbackup/current" child sendsize[8231]: time 0.348: after /bin/tar "/var/spool/sbackup/current" wait sendsize[8231]: time 0.348: getting size via gnutar for /var/spool/sbackup/current level 1 sendsize[8231]: time 0.350: spawning /usr/lib/amanda/runtar in pipeline sendsize[8231]: argument list: /bin/tar --create --file /dev/null --directory /var/spool/sbackup/current --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_spool_sbackup_current_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_spool_sbackup_current.20071121011021000.exclude . sendsize[8231]: time 0.373: Total bytes written: 2014658560 (1.9GiB, ?/s) sendsize[8231]: time 0.373: ..... sendsize[8231]: estimate time for /var/spool/sbackup/current level 1: 0.023 sendsize[8231]: estimate size for /var/spool/sbackup/current level 1: 1967440 KB sendsize[8231]: time 0.373: waiting for /bin/tar "/var/spool/sbackup/current" child sendsize[8231]: time 0.374: after /bin/tar "/var/spool/sbackup/current" wait sendsize[8231]: time 0.374: done with amname '/var/spool/sbackup/current', dirname '/var/spool/sbackup/current', spindle -1 sendsize[8229]: time 0.374: child 8231 terminated normally sendsize[8234]: time 0.374: calculating for amname '/etc/sbackup.d', dirname '/etc/sbackup.d', spindle -1 sendsize[8234]: time 0.374: getting size via gnutar for /etc/sbackup.d level 0 sendsize[8229]: time 0.376: waiting for any estimate child: 1 running sendsize[8234]: time 0.376: spawning /usr/lib/amanda/runtar in pipeline sendsize[8234]: argument list: /bin/tar --create --file /dev/null --directory /etc/sbackup.d --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_etc_sbackup.d_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._etc_sbackup.d.20071121011021.exclude . sendsize[8234]: time 0.381: Total bytes written: 143360 (140KiB, ?/s) sendsize[8234]: time 0.381: ..... sendsize[8234]: estimate time for /etc/sbackup.d level 0: 0.005 sendsize[8234]: estimate size for /etc/sbackup.d level 0: 140 KB sendsize[8234]: time 0.381: waiting for /bin/tar "/etc/sbackup.d" child sendsize[8234]: time 0.381: after /bin/tar "/etc/sbackup.d" wait sendsize[8234]: time 0.381: getting size via gnutar for /etc/sbackup.d level 1 sendsize[8234]: time 0.408: spawning /usr/lib/amanda/runtar in pipeline sendsize[8234]: argument list: /bin/tar --create --file /dev/null --directory /etc/sbackup.d --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_etc_sbackup.d_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._etc_sbackup.d.20071121011021000.exclude . sendsize[8234]: time 0.413: Total bytes written: 51200 (50KiB, ?/s) sendsize[8234]: time 0.413: ..... sendsize[8234]: estimate time for /etc/sbackup.d level 1: 0.005 sendsize[8234]: estimate size for /etc/sbackup.d level 1: 50 KB sendsize[8234]: time 0.413: waiting for /bin/tar "/etc/sbackup.d" child sendsize[8234]: time 0.413: after /bin/tar "/etc/sbackup.d" wait sendsize[8234]: time 0.413: done with amname '/etc/sbackup.d', dirname '/etc/sbackup.d', spindle -1 sendsize[8229]: time 0.413: child 8234 terminated normally sendsize[8237]: time 0.414: calculating for amname '/var/lib/cvs', dirname '/var/lib/cvs', spindle -1 sendsize[8237]: time 0.414: getting size via gnutar for /var/lib/cvs level 0 sendsize[8237]: time 0.414: spawning /usr/lib/amanda/runtar in pipeline sendsize[8237]: argument list: /bin/tar --create --file /dev/null --directory /var/lib/cvs --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_lib_cvs_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_lib_cvs.20071121011021.exclude . sendsize[8229]: time 0.418: waiting for any estimate child: 1 running sendsize[8237]: time 21.785: Total bytes written: 1391697920 (1.3GiB, 64MiB/s) sendsize[8237]: time 21.785: ..... sendsize[8237]: estimate time for /var/lib/cvs level 0: 21.370 sendsize[8237]: estimate size for /var/lib/cvs level 0: 1359080 KB sendsize[8237]: time 21.785: waiting for /bin/tar "/var/lib/cvs" child sendsize[8237]: time 21.786: after /bin/tar "/var/lib/cvs" wait sendsize[8237]: time 21.786: getting size via gnutar for /var/lib/cvs level 1 sendsize[8237]: time 21.871: spawning /usr/lib/amanda/runtar in pipeline sendsize[8237]: argument list: /bin/tar --create --file /dev/null --directory /var/lib/cvs --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_lib_cvs_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_lib_cvs.20071121011042.exclude . sendsize[8237]: time 22.575: Total bytes written: 161054720 (154MiB, 154MiB/s) sendsize[8237]: time 22.575: ..... sendsize[8237]: estimate time for /var/lib/cvs level 1: 0.703 sendsize[8237]: estimate size for /var/lib/cvs level 1: 157280 KB sendsize[8237]: time 22.575: waiting for /bin/tar "/var/lib/cvs" child sendsize[8237]: time 22.575: after /bin/tar "/var/lib/cvs" wait sendsize[8237]: time 22.576: done with amname '/var/lib/cvs', dirname '/var/lib/cvs', spindle -1 sendsize[8229]: time 22.576: child 8237 terminated normally sendsize[8240]: time 22.576: calculating for amname '/var/lib/samba/profiles', dirname '/var/lib/samba/profiles', spindle -1 sendsize[8240]: time 22.576: getting size via gnutar for /var/lib/samba/profiles level 0 sendsize[8240]: time 22.577: spawning /usr/lib/amanda/runtar in pipeline sendsize[8240]: argument list: /bin/tar --create --file /dev/null --directory /var/lib/samba/profiles --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_lib_samba_profiles_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_lib_samba_profiles.20071121011043.exclude . sendsize[8229]: time 22.580: waiting for any estimate child: 1 running sendsize[8240]: time 59.140: Total bytes written: 3612999680 (3.4GiB, 94MiB/s) sendsize[8240]: time 59.140: ..... sendsize[8240]: estimate time for /var/lib/samba/profiles level 0: 36.563 sendsize[8240]: estimate size for /var/lib/samba/profiles level 0: 3528320 KB sendsize[8240]: time 59.140: waiting for /bin/tar "/var/lib/samba/profiles" child sendsize[8240]: time 59.141: after /bin/tar "/var/lib/samba/profiles" wait sendsize[8240]: time 59.141: getting size via gnutar for /var/lib/samba/profiles level 1 sendsize[8240]: time 59.193: spawning /usr/lib/amanda/runtar in pipeline sendsize[8240]: argument list: /bin/tar --create --file /dev/null --directory /var/lib/samba/profiles --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_lib_samba_profiles_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_lib_samba_profiles.20071121011120.exclude . sendsize[8240]: time 60.824: Total bytes written: 75939840 (73MiB, 73MiB/s) sendsize[8240]: time 60.824: ..... sendsize[8240]: estimate time for /var/lib/samba/profiles level 1: 1.631 sendsize[8240]: estimate size for /var/lib/samba/profiles level 1: 74160 KB sendsize[8240]: time 60.824: waiting for /bin/tar "/var/lib/samba/profiles" child sendsize[8240]: time 60.825: after /bin/tar "/var/lib/samba/profiles" wait sendsize[8240]: time 60.825: done with amname '/var/lib/samba/profiles', dirname '/var/lib/samba/profiles', spindle -1 sendsize[8229]: time 60.825: child 8240 terminated normally sendsize[8243]: time 60.826: calculating for amname '/home', dirname '/home', spindle -1 sendsize[8243]: time 60.826: getting size via gnutar for /home level 0 sendsize[8243]: time 60.826: spawning /usr/lib/amanda/runtar in pipeline sendsize[8243]: argument list: /bin/tar --create --file /dev/null --directory /home --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_home_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._home.20071121011121.exclude . sendsize[8229]: time 60.828: waiting for any estimate child: 1 running sendsize[8243]: time 127.188: Total bytes written: 12016742400 (12GiB, 172MiB/s) sendsize[8243]: time 127.188: ..... sendsize[8243]: estimate time for /home level 0: 66.361 sendsize[8243]: estimate size for /home level 0: 11735100 KB sendsize[8243]: time 127.188: waiting for /bin/tar "/home" child sendsize[8243]: time 127.189: after /bin/tar "/home" wait sendsize[8243]: time 127.190: getting size via gnutar for /home level 1 sendsize[8243]: time 127.241: spawning /usr/lib/amanda/runtar in pipeline sendsize[8243]: argument list: /bin/tar --create --file /dev/null --directory /home --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_home_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._home.20071121011228.exclude . sendsize[8243]: time 131.434: Total bytes written: 1120378880 (1.1GiB, 268MiB/s) sendsize[8243]: time 131.435: ..... sendsize[8243]: estimate time for /home level 1: 4.193 sendsize[8243]: estimate size for /home level 1: 1094120 KB sendsize[8243]: time 131.435: waiting for /bin/tar "/home" child sendsize[8243]: time 131.435: after /bin/tar "/home" wait sendsize[8243]: time 131.436: done with amname '/home', dirname '/home', spindle -1 sendsize[8229]: time 131.436: child 8243 terminated normally sendsize[8246]: time 131.436: calculating for amname '/var/exports/rpmsys', dirname '/var/exports/rpmsys', spindle -1 sendsize[8246]: time 131.436: getting size via gnutar for /var/exports/rpmsys level 0 sendsize[8246]: time 131.438: spawning /usr/lib/amanda/runtar in pipeline sendsize[8246]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/rpmsys --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_rpmsys_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_rpmsys.20071121011232.exclude . sendsize[8229]: time 131.438: waiting for any estimate child: 1 running sendsize[8246]: time 131.793: Total bytes written: 127232000 (122MiB, ?/s) sendsize[8246]: time 131.794: ..... sendsize[8246]: estimate time for /var/exports/rpmsys level 0: 0.355 sendsize[8246]: estimate size for /var/exports/rpmsys level 0: 124250 KB sendsize[8246]: time 131.794: waiting for /bin/tar "/var/exports/rpmsys" child sendsize[8246]: time 131.794: after /bin/tar "/var/exports/rpmsys" wait sendsize[8246]: time 131.794: getting size via gnutar for /var/exports/rpmsys level 1 sendsize[8246]: time 131.807: spawning /usr/lib/amanda/runtar in pipeline sendsize[8246]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/rpmsys --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_rpmsys_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_rpmsys.20071121011232000.exclude . sendsize[8246]: time 131.815: Total bytes written: 30720 (30KiB, ?/s) sendsize[8246]: time 131.815: ..... sendsize[8246]: estimate time for /var/exports/rpmsys level 1: 0.007 sendsize[8246]: estimate size for /var/exports/rpmsys level 1: 30 KB sendsize[8246]: time 131.815: waiting for /bin/tar "/var/exports/rpmsys" child sendsize[8246]: time 131.816: after /bin/tar "/var/exports/rpmsys" wait sendsize[8246]: time 131.816: done with amname '/var/exports/rpmsys', dirname '/var/exports/rpmsys', spindle -1 sendsize[8229]: time 131.816: child 8246 terminated normally sendsize[8249]: time 131.816: calculating for amname '/var/exports/devel', dirname '/var/exports/devel', spindle -1 sendsize[8249]: time 131.816: getting size via gnutar for /var/exports/devel level 0 sendsize[8229]: time 131.816: waiting for any estimate child: 1 running sendsize[8249]: time 131.818: spawning /usr/lib/amanda/runtar in pipeline sendsize[8249]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/devel --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_devel_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_devel.20071121011232.exclude . sendsize[8249]: time 271.327: Total bytes written: 10817525760 (11GiB, 74MiB/s) sendsize[8249]: time 271.327: ..... sendsize[8249]: estimate time for /var/exports/devel level 0: 139.509 sendsize[8249]: estimate size for /var/exports/devel level 0: 10563990 KB sendsize[8249]: time 271.327: waiting for /bin/tar "/var/exports/devel" child sendsize[8249]: time 271.330: after /bin/tar "/var/exports/devel" wait sendsize[8249]: time 271.331: getting size via gnutar for /var/exports/devel level 1 sendsize[8249]: time 271.446: spawning /usr/lib/amanda/runtar in pipeline sendsize[8249]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/devel --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_devel_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_devel.20071121011452.exclude . sendsize[8249]: time 281.876: Total bytes written: 92569600 (89MiB, 8.9MiB/s) sendsize[8249]: time 281.877: ..... sendsize[8249]: estimate time for /var/exports/devel level 1: 10.430 sendsize[8249]: estimate size for /var/exports/devel level 1: 90400 KB sendsize[8249]: time 281.877: waiting for /bin/tar "/var/exports/devel" child sendsize[8249]: time 281.879: after /bin/tar "/var/exports/devel" wait sendsize[8249]: time 281.880: done with amname '/var/exports/devel', dirname '/var/exports/devel', spindle -1 sendsize[8229]: time 281.880: child 8249 terminated normally sendsize[8255]: time 281.880: calculating for amname '/var/exports/taz', dirname '/var/exports/taz', spindle -1 sendsize[8255]: time 281.880: getting size via gnutar for /var/exports/taz level 0 sendsize[8229]: time 281.881: waiting for any estimate child: 1 running sendsize[8255]: time 281.882: spawning /usr/lib/amanda/runtar in pipeline sendsize[8255]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/taz --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_taz_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_taz.20071121011502.exclude . sendsize[8255]: time 302.340: Total bytes written: 8554823680 (8.0GiB, 389MiB/s) sendsize[8255]: time 302.341: ..... sendsize[8255]: estimate time for /var/exports/taz level 0: 20.459 sendsize[8255]: estimate size for /var/exports/taz level 0: 8354320 KB sendsize[8255]: time 302.341: waiting for /bin/tar "/var/exports/taz" child sendsize[8255]: time 302.342: after /bin/tar "/var/exports/taz" wait sendsize[8255]: time 302.342: getting size via gnutar for /var/exports/taz level 1 sendsize[8255]: time 302.387: spawning /usr/lib/amanda/runtar in pipeline sendsize[8255]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/taz --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_taz_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_taz.20071121011523.exclude . sendsize[8255]: time 303.067: Total bytes written: 4710400 (4.5MiB, 4.5MiB/s) sendsize[8255]: time 303.068: ..... sendsize[8255]: estimate time for /var/exports/taz level 1: 0.680 sendsize[8255]: estimate size for /var/exports/taz level 1: 4600 KB sendsize[8255]: time 303.068: waiting for /bin/tar "/var/exports/taz" child sendsize[8255]: time 303.068: after /bin/tar "/var/exports/taz" wait sendsize[8255]: time 303.069: done with amname '/var/exports/taz', dirname '/var/exports/taz', spindle -1 sendsize[8229]: time 303.069: child 8255 terminated normally sendsize[8258]: time 303.069: calculating for amname '/var/exports/platform', dirname '/var/exports/platform', spindle -1 sendsize[8258]: time 303.069: getting size via gnutar for /var/exports/platform level 0 sendsize[8258]: time 303.071: spawning /usr/lib/amanda/runtar in pipeline sendsize[8258]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/platform --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_platform_0.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_platform.20071121011524.exclude . sendsize[8229]: time 303.074: waiting for any estimate child: 1 running sendsize[8258]: time 303.232: Total bytes written: 1547694080 (1.5GiB, ?/s) sendsize[8258]: time 303.233: ..... sendsize[8258]: estimate time for /var/exports/platform level 0: 0.161 sendsize[8258]: estimate size for /var/exports/platform level 0: 1511420 KB sendsize[8258]: time 303.233: waiting for /bin/tar "/var/exports/platform" child sendsize[8258]: time 303.233: after /bin/tar "/var/exports/platform" wait sendsize[8258]: time 303.233: getting size via gnutar for /var/exports/platform level 1 sendsize[8258]: time 303.247: spawning /usr/lib/amanda/runtar in pipeline sendsize[8258]: argument list: /bin/tar --create --file /dev/null --directory /var/exports/platform --one-file-system --listed-incremental /var/lib/amanda/gnutar-lists/chaos.olympe.o2t_var_exports_platform_1.new --sparse --ignore-failed-read --totals --exclude-from /var/log/amanda/sendsize._var_exports_platform.20071121011524000.exclude . sendsize[8258]: time 303.254: Total bytes written: 30720 (30KiB, ?/s) sendsize[8258]: time 303.254: ..... sendsize[8258]: estimate time for /var/exports/platform level 1: 0.007 sendsize[8258]: estimate size for /var/exports/platform level 1: 30 KB sendsize[8258]: time 303.254: waiting for /bin/tar "/var/exports/platform" child sendsize[8258]: time 303.254: after /bin/tar "/var/exports/platform" wait sendsize[8258]: time 303.254: done with amname '/var/exports/platform', dirname '/var/exports/platform', spindle -1 sendsize[8229]: time 303.255: child 8258 terminated normally sendsize: time 303.255: pid 8229 finish time Wed Nov 21 01:15:24 2007
