Package: amanda-client
Version: 1:3.5.1-10
Severity: normal
Dear Maintainer,
* What led up to the situation?
upgrade of amanda-{client,common,server} from 1:3.5.1-9+b1 to 1:3.5.1-10
* What exactly did you do (or not do) that was effective (or
ineffective)?
standard backup with normal package: failed.
* What was the outcome of this action?
runtar completes in sane manner, defaulDailySet1g to stdout; sendbackup
completes; backup works
myUser@mySystem:~$ for f in sendbackup.20230303031033.debug
runtar.20230303031033.debug ; do printf
"==========\n/var/log/amanda/client/DailySet1/$f\n==========\n" ; sudo cat
/var/log/amanda/client/DailySet1/$f ; done
==========
/var/log/amanda/client/DailySet1/sendbackup.20230303031033.debug
==========
Fri Mar 03 03:10:33.465231875 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: pid 1448741 ruid 34 euid 34 version 3.5.1: start at Fri Mar 3
03:10:33 2023
Fri Mar 03 03:10:33.465266461 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: Version 3.5.1
Fri Mar 03 03:10:33.465782977 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: pid 1448741 ruid 34 euid 34 version 3.5.1: rename at Fri Mar 3
03:10:33 2023
Fri Mar 03 03:10:33.465876561 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: Parsed request as: program `GNUTAR'
Fri Mar 03 03:10:33.465885463 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: disk `/boot'
Fri Mar 03 03:10:33.465890405 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: device `/boot'
Fri Mar 03 03:10:33.465894886 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: level 1
Fri Mar 03 03:10:33.465899391 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: since NODATE
Fri Mar 03 03:10:33.465903975 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: options `'
Fri Mar 03 03:10:33.465908806 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: datapath `AMANDA'
Fri Mar 03 03:10:33.465963068 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: start: mySystem:/boot lev 1
Fri Mar 03 03:10:33.465978023 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: Spawning "/bin/gzip /bin/gzip --best" in pipeline
Fri Mar 03 03:10:33.466337828 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: gnutar: pid 1448743: /bin/gzip
Fri Mar 03 03:10:33.466360045 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: pid 1448743: /bin/gzip --best
Fri Mar 03 03:10:33.466668981 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: doing level 1 dump as listed-incremental from
'/var/lib/amanda/gnutar-lists/mySystem_boot_0' to
'/var/lib/amanda/gnutar-lists/mySystem_boot_1.new'
Fri Mar 03 03:10:33.467301177 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: Spawning "/usr/lib/amanda/runtar runtar DailySet1 /bin/tar
--create --file - --directory /boot --one-file-system --listed-incremental
/var/lib/amanda/gnutar-lists/mySystem_boot_1.new --sparse
--ignore-failed-read --totals ." in pipeline
Fri Mar 03 03:10:33.467327842 2023: pid 1448746: thd-0x5643d3686c00:
sendbackup: Dupped file descriptor 3 to 11
Fri Mar 03 03:10:33.467621737 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: gnutar: /usr/lib/amanda/runtar: pid 1448747
Fri Mar 03 03:10:33.467655460 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: shm_ring_link /amanda_shm_control-1448740-0
Fri Mar 03 03:10:33.467707662 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: am_sem_open 0x7fc43646e000 1
Fri Mar 03 03:10:33.467726841 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: am_sem_open 0x7fc43646d000 1
Fri Mar 03 03:10:33.467742817 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: am_sem_open 0x7fc43646c000 1
Fri Mar 03 03:10:33.467765242 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: am_sem_open 0x7fc43646b000 1
Fri Mar 03 03:10:33.467773426 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: shm_ring_producer_set_size
Fri Mar 03 03:10:33.467871033 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: Started backup
Fri Mar 03 03:10:33.467934476 2023: pid 1448741: thd-0x5643d36930c0:
sendbackup: fd_to_shm_ring
Fri Mar 03 03:10:33.467941382 2023: pid 1448746: thd-0x5643d3686c00:
sendbackup: Started index creator: "/bin/tar -tf - 2>/dev/null | sed -e
's/^\.//'"
Fri Mar 03 03:10:33.483602083 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: 119: strange(?): runtar: error [runtar invalid option: -]
Fri Mar 03 03:10:33.485632813 2023: pid 1448746: thd-0x5643d3686c00:
sendbackup: Index created successfully
Fri Mar 03 03:10:34.486144166 2023: pid 1448741: thd-0x5643d3686c00:
sendbackup: critical (fatal): error [no backup size line]
/usr/lib/x86_64-linux-gnu/amanda/libamanda-3.5.1.so
(+0x38fea)[0x7fc4364a8fea]
/lib/x86_64-linux-gnu/libglib-2.0.so.0(g_logv+0x227)[0x7fc436364e67]
/lib/x86_64-linux-gnu/libglib-2.0.so.0(g_log+0x8f)[0x7fc4363650ff]
/usr/lib/amanda/sendbackup(parse_backup_messages+0x471)[0x5643d183ca61]
/usr/lib/amanda/sendbackup(main+0x121e)[0x5643d1839b2e]
/lib/x86_64-linux-gnu/libc.so.6(+0x2718a)[0x7fc43614f18a]
/lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0x85)[0x7fc43614f245]
/usr/lib/amanda/sendbackup(_start+0x21)[0x5643d183b341]
==========
/var/log/amanda/client/DailySet1/runtar.20230303031033.debug
==========
Fri Mar 03 03:10:33.480732543 2023: pid 1448747: thd-0x5596c4be7400:
runtar: pid 1448747 ruid 34 euid 0 version 3.5.1: start at Fri Mar 3
03:10:33 2023
Fri Mar 03 03:10:33.480818816 2023: pid 1448747: thd-0x5596c4be7400:
runtar: version 3.5.1
Fri Mar 03 03:10:33.482700629 2023: pid 1448747: thd-0x5596c4be7400:
runtar: /bin/tar version: tar (GNU tar) 1.34
Fri Mar 03 03:10:33.482792418 2023: pid 1448747: thd-0x5596c4be7400:
runtar: config: DailySet1
Fri Mar 03 03:10:33.483238009 2023: pid 1448747: thd-0x5596c4be7400:
runtar: pid 1448747 ruid 0 euid 0 version 3.5.1: rename at Fri Mar 3
03:10:33 2023
Fri Mar 03 03:10:33.483350034 2023: pid 1448747: thd-0x5596c4be7400:
runtar: critical (fatal): error [runtar invalid option: -]
/usr/lib/x86_64-linux-gnu/amanda/libamanda-3.5.1.so
(+0x38fea)[0x7f5cf0024fea]
/lib/x86_64-linux-gnu/libglib-2.0.so.0(g_logv+0x227)[0x7f5cefee0e67]
/lib/x86_64-linux-gnu/libglib-2.0.so.0(g_log+0x8f)[0x7f5cefee10ff]
runtar(main+0x6d9)[0x5596c34739e9]
/lib/x86_64-linux-gnu/libc.so.6(+0x2718a)[0x7f5cefccb18a]
/lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0x85)[0x7f5cefccb245]
runtar(_start+0x21)[0x5596c3473b51]
* What outcome did you expect instead?
successful backup
* My temporary patch to make things work for me:
sudo mv /usr/lib/amanda/runtar /usr/lib/amanda/runtar.orig
cat | sudo tee /usr/lib/amanda/runtar <<END
#!/bin/bash
args=\$(echo "\$@" | sed -r 's/--file\s+-\s+//')
\$0.orig \$args
END
sudo chown root:backup /usr/lib/amanda/runtar
sudo chmod 0750
* What was the outcome of using above patch:
backup works as expected:
myUser@mySystem:/scratch/torrent$ for f in sendbackup.20230303230428.debug
runtar.20230303230428.debug ; do printf
"==========\n/var/log/amanda/client/DailySet1/$f\n==========\n" ; sudo cat
/var/log/amanda/client/DailySet1/$f ; done
==========
/var/log/amanda/client/DailySet1/sendbackup.20230303230428.debug
==========
Fri Mar 03 23:04:28.343506643 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: pid 1500665 ruid 34 euid 34 version 3.5.1: start at Fri Mar 3
23:04:28 2023
Fri Mar 03 23:04:28.343547638 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: Version 3.5.1
Fri Mar 03 23:04:28.343932685 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: pid 1500665 ruid 34 euid 34 version 3.5.1: rename at Fri Mar 3
23:04:28 2023
Fri Mar 03 23:04:28.344019847 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: Parsed request as: program `GNUTAR'
Fri Mar 03 23:04:28.344029050 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: disk `/boot'
Fri Mar 03 23:04:28.344034242 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: device `/boot'
Fri Mar 03 23:04:28.344038942 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: level 0
Fri Mar 03 23:04:28.344043613 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: since NODATE
Fri Mar 03 23:04:28.344048410 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: options `'
Fri Mar 03 23:04:28.344053262 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: datapath `AMANDA'
Fri Mar 03 23:04:28.344389615 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: start: mySystem:/boot lev 0
Fri Mar 03 23:04:28.344412286 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: Spawning "/bin/gzip /bin/gzip --best" in pipeline
Fri Mar 03 23:04:28.344987135 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: gnutar: pid 1500667: /bin/gzipFri Mar 03 23:04:28.345011192
2023: pid 1500665: thd-0x55cbc82e6c00: sendbackup: pid 1500667: /bin/gzip
--best
Fri Mar 03 23:04:28.345069483 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: doing level 0 dump as listed-incremental to
'/var/lib/amanda/gnutar-lists/mySystem_boot_0.new'
Fri Mar 03 23:04:28.345919453 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: Spawning "/usr/lib/amanda/runtar runtar DailySet1 /bin/tar
--create --file - --directory /boot --one-file-system --listed-incremental
/var/lib/amanda/gnutar-lists/mySystem_boot_0.new --sparse
--ignore-failed-read --totals ." in pipeline
Fri Mar 03 23:04:28.346297887 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: gnutar: /usr/lib/amanda/runtar: pid 1500671
Fri Mar 03 23:04:28.346337351 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: shm_ring_link /amanda_shm_control-1500653-0
Fri Mar 03 23:04:28.346393074 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_open 0x7f0e6d365000 1
Fri Mar 03 23:04:28.346413252 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_open 0x7f0e6d364000 1
Fri Mar 03 23:04:28.346436470 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_open 0x7f0e6d363000 1
Fri Mar 03 23:04:28.346454307 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_open 0x7f0e6d362000 1
Fri Mar 03 23:04:28.346461651 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: shm_ring_producer_set_size
Fri Mar 03 23:04:28.347528826 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: Started backup
Fri Mar 03 23:04:28.347642729 2023: pid 1500665: thd-0x55cbc82f30c0:
sendbackup: fd_to_shm_ring
Fri Mar 03 23:04:28.347748742 2023: pid 1500670: thd-0x55cbc82e6c00:
sendbackup: Dupped file descriptor 3 to 11
Fri Mar 03 23:04:28.351307187 2023: pid 1500670: thd-0x55cbc82e6c00:
sendbackup: Started index creator: "/bin/tar -tf - 2>/dev/null | sed -e
's/^\.//'"
Fri Mar 03 23:04:37.562310071 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: 48: size(|): Total bytes written: 195717120 (187MiB,
21MiB/s)
Fri Mar 03 23:04:37.570045954 2023: pid 1500670: thd-0x55cbc82e6c00:
sendbackup: Index created successfully
Fri Mar 03 23:04:37.587300247 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: close_producer_shm_ring sem_close(sem_write 0x7f0e6d365000
Fri Mar 03 23:04:37.587330075 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_close 0x7f0e6d365000 0
Fri Mar 03 23:04:37.587360435 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_close 0x7f0e6d363000 0
Fri Mar 03 23:04:37.587378966 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_close 0x7f0e6d364000 0
Fri Mar 03 23:04:37.587403739 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: am_sem_close 0x7f0e6d362000 0
Fri Mar 03 23:04:37.587516095 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: sendbackup: native-CRC 684c3727:195717120
Fri Mar 03 23:04:37.587523472 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: sendbackup: client-CRC 8ca58d90:186943702
Fri Mar 03 23:04:37.589138849 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: Parsed backup messages
Fri Mar 03 23:04:37.589168047 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_utime : 0
Fri Mar 03 23:04:37.589179457 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_stime : 0
Fri Mar 03 23:04:37.589189211 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_maxrss : 12660
Fri Mar 03 23:04:37.589198718 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_ixrss : 0
Fri Mar 03 23:04:37.589208067 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_idrss : 0
Fri Mar 03 23:04:37.589217313 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_isrss : 0
Fri Mar 03 23:04:37.589226679 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_minflt : 1000
Fri Mar 03 23:04:37.589235951 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_majflt : 2
Fri Mar 03 23:04:37.589245163 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_nswap : 0
Fri Mar 03 23:04:37.589254403 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_inblock : 96
Fri Mar 03 23:04:37.589263729 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_oublock : 40
Fri Mar 03 23:04:37.589272885 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_msgsnd : 0
Fri Mar 03 23:04:37.589282083 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_msgrcv : 0
Fri Mar 03 23:04:37.589291190 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_nsignals: 0
Fri Mar 03 23:04:37.589300392 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_nvcsw : 7801
Fri Mar 03 23:04:37.589309817 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: ru_nivcsw : 137
Fri Mar 03 23:04:37.589447295 2023: pid 1500665: thd-0x55cbc82e6c00:
sendbackup: pid 1500665 finish time Fri Mar 3 23:04:37 2023
==========
/var/log/amanda/client/DailySet1/runtar.20230303230428.debug
==========
Fri Mar 03 23:04:28.364856643 2023: pid 1500680: thd-0x55a40ae74400:
runtar: pid 1500680 ruid 34 euid 0 version 3.5.1: start at Fri Mar 3
23:04:28 2023
Fri Mar 03 23:04:28.364954941 2023: pid 1500680: thd-0x55a40ae74400:
runtar: version 3.5.1
Fri Mar 03 23:04:28.366749300 2023: pid 1500680: thd-0x55a40ae74400:
runtar: /bin/tar version: tar (GNU tar) 1.34
Fri Mar 03 23:04:28.366815300 2023: pid 1500680: thd-0x55a40ae74400:
runtar: config: DailySet1
Fri Mar 03 23:04:28.367083586 2023: pid 1500680: thd-0x55a40ae74400:
runtar: pid 1500680 ruid 0 euid 0 version 3.5.1: rename at Fri Mar 3
23:04:28 2023
Fri Mar 03 23:04:28.367164842 2023: pid 1500680: thd-0x55a40ae74400:
runtar: running: /usr/bin/tar --create --directory /boot --one-file-system
--listed-incremental /var/lib/amanda/gnutar-lists/mySystem_boot_0.new
--sparse --ignore-failed-read --totals .
Fri Mar 03 23:04:28.367177548 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_utime : 0
Fri Mar 03 23:04:28.367183703 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_stime : 0
Fri Mar 03 23:04:28.367188934 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_maxrss : 11740
Fri Mar 03 23:04:28.367194037 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_ixrss : 0
Fri Mar 03 23:04:28.367198942 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_idrss : 0
Fri Mar 03 23:04:28.367203884 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_isrss : 0
Fri Mar 03 23:04:28.367208775 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_minflt : 670
Fri Mar 03 23:04:28.367213815 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_majflt : 1
Fri Mar 03 23:04:28.367218670 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_nswap : 0
Fri Mar 03 23:04:28.367223627 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_inblock : 40
Fri Mar 03 23:04:28.367228588 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_oublock : 16
Fri Mar 03 23:04:28.367233545 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_msgsnd : 0
Fri Mar 03 23:04:28.367238451 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_msgrcv : 0
Fri Mar 03 23:04:28.367243307 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_nsignals: 0
Fri Mar 03 23:04:28.367248211 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_nvcsw : 4
Fri Mar 03 23:04:28.367253047 2023: pid 1500680: thd-0x55a40ae74400:
runtar: ru_nivcsw : 3
Fri Mar 03 23:04:28.367311369 2023: pid 1500680: thd-0x55a40ae74400:
runtar: pid 1500680 finish time Fri Mar 3 23:04:28 2023
* Since I've moved runtar, and debsums below shows diff:
myUser@mySystem:~$ ls -al /usr/lib/amanda/runtar* ; sum
/usr/lib/amanda/runtar* ; md5sum /usr/lib/amanda/runtar*
-rwxr-xr-x 1 root root 74 Mar 3 21:54 /usr/lib/amanda/runtar
-rwsr-xr-- 1 root backup 18512 Feb 26 09:53 /usr/lib/amanda/runtar.orig
58844 1 /usr/lib/amanda/runtar
56789 19 /usr/lib/amanda/runtar.orig
fc485ce2d7a5f29de763935568e43a3b /usr/lib/amanda/runtar
54059ddd828a507838b3202e5ae33a01 /usr/lib/amanda/runtar.orig
-- System Information:
Debian Release: bookworm/sid
APT prefers unstable
APT policy: (500, 'unstable'), (500, 'stable'), (1, 'experimental')
Architecture: amd64 (x86_64)
Foreign Architectures: i386
Kernel: Linux 6.1.0-3-amd64 (SMP w/4 CPU threads; PREEMPT)
Kernel taint flags: TAINT_OOT_MODULE, TAINT_UNSIGNED_MODULE
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8), LANGUAGE
not set
Shell: /bin/sh linked to /usr/bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled
Versions of packages amanda-client depends on:
ii amanda-common 1:3.5.1-10
ii libc6 2.36-8
ii libglib2.0-0 2.74.5-1
ii libreadline8 8.2-1.3
ii libxml-simple-perl 2.25-2
ii perl 5.36.0-7
amanda-client recommends no packages.
Versions of packages amanda-client suggests:
ii dump 0.4b47-4
ii gnuplot 5.4.4+dfsg1-2
ii gnuplot-nox [gnuplot] 5.4.4+dfsg1-2+b2
ii smbclient 2:4.17.5+dfsg-2
-- no debconf information
-- debsums errors found:
debsums: changed file /usr/lib/amanda/runtar (from amanda-client package)
