I'm having a problem where one of my hosts has one filesystem which apears to back up perfectly fine, but produces an empty index file. Specifically, the (timestamp)_(level).gz file comes out to be exactly 20 bytes, and uncompressed it's completely empty.
The machine is Fedora 24, and I've tried 3.3.9, 3.4 and 3.4.1 (both as provided by Fedora and self-compiled). The filesystem is XFS. Note that several filesystems on the machine, all XFS, are being backed up and only one (a big one containing my home directory) has this issue. The others produce index files which appear to be quite correct. The log files don't appear to tell me much; all say "Index created successfully". Last night's log is attached. Running through the differences between the log files for each filesystem from last night's run, the only changes I can see are that the problematic volume is dumped without compression (the others are all compressed), and that the filesystem in question has quotas enabled while the others don't. I'll play with changing those tonight to see if it makes any difference, but I'm not optimistic as we do compressed backups of plenty of other XFS filesystems with quotas. The warning about overwriting xfsdump_quotas is normal with xfsdump for whatever reason. Are there any tricks for trying to duplicate the index generation? I guess I could try to pull a raw dump file off of tape and run the same xfsrestore command line and see what happens. I welcome any suggestions anyone might have. -- Jason L Tibbitts III - [email protected] - 713/743-3486 - 660PGH System Manager: University of Houston Department of Mathematics Tue Dec 13 00:12:12.277433734 2016: pid 27392: thd-0x55832f61cc00: sendbackup: pid 27392 ruid 33 euid 33 version 3.4.1: start at Tue Dec 13 00:12:12 2016 Tue Dec 13 00:12:12.277484221 2016: pid 27392: thd-0x55832f61cc00: sendbackup: Version 3.4.1 Tue Dec 13 00:12:12.278291654 2016: pid 27392: thd-0x55832f61cc00: sendbackup: pid 27392 ruid 33 euid 33 version 3.4.1: rename at Tue Dec 13 00:12:12 2016 Tue Dec 13 00:12:12.278325811 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <dle> Tue Dec 13 00:12:12.278349000 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <program>DUMP</program> Tue Dec 13 00:12:12.278437488 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <disk>/export/h-tibbs</disk> Tue Dec 13 00:12:12.278456627 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <level>1</level> Tue Dec 13 00:12:12.278471706 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <auth>BSDTCP</auth> Tue Dec 13 00:12:12.278484818 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <record>YES</record> Tue Dec 13 00:12:12.278498659 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <index>YES</index> Tue Dec 13 00:12:12.278511873 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: <datapath>AMANDA</datapath> Tue Dec 13 00:12:12.278526124 2016: pid 27392: thd-0x55832f61cc00: sendbackup: line: </dle> Tue Dec 13 00:12:12.278544857 2016: pid 27392: thd-0x55832f61cc00: sendbackup: Parsed request as: program `DUMP' Tue Dec 13 00:12:12.278553318 2016: pid 27392: thd-0x55832f61cc00: sendbackup: disk `/export/h-tibbs' Tue Dec 13 00:12:12.278560754 2016: pid 27392: thd-0x55832f61cc00: sendbackup: device `/export/h-tibbs' Tue Dec 13 00:12:12.278567596 2016: pid 27392: thd-0x55832f61cc00: sendbackup: level 1 Tue Dec 13 00:12:12.278574199 2016: pid 27392: thd-0x55832f61cc00: sendbackup: since NODATE Tue Dec 13 00:12:12.278580924 2016: pid 27392: thd-0x55832f61cc00: sendbackup: options `' Tue Dec 13 00:12:12.278588810 2016: pid 27392: thd-0x55832f61cc00: sendbackup: datapath `AMANDA' Tue Dec 13 00:12:12.278662099 2016: pid 27392: thd-0x55832f61cc00: sendbackup: start: epithumia:/export/h-tibbs lev 1 Tue Dec 13 00:12:12.279896690 2016: pid 27392: thd-0x55832f61cc00: sendbackup: dumping device '/dev/mapper/home-h--tibbs' with 'xfs' Tue Dec 13 00:12:12.280545974 2016: pid 27392: thd-0x55832f61cc00: sendbackup: Spawning "/usr/lib64/amanda/rundump /usr/lib64/amanda/rundump math xfsdump -F -l 1 - /dev/mapper/home-h--tibbs" in pipeline Tue Dec 13 00:12:12.280925840 2016: pid 27392: thd-0x55832f61cc00: sendbackup: shm_ring_link /amanda_shm_control-27391-0 Tue Dec 13 00:12:12.281062895 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_open 0x7f07fd92b000 1 Tue Dec 13 00:12:12.281086177 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_open 0x7f07fd92a000 1 Tue Dec 13 00:12:12.281100921 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_open 0x7f07fd929000 1 Tue Dec 13 00:12:12.281114768 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_open 0x7f07fd928000 1 Tue Dec 13 00:12:12.281124510 2016: pid 27392: thd-0x55832f61cc00: sendbackup: shm_ring_producer_set_size Tue Dec 13 00:12:12.281221780 2016: pid 27392: thd-0x55832f61cc00: sendbackup: Started backup Tue Dec 13 00:12:12.281355468 2016: pid 27395: thd-0x55832f61cc00: sendbackup: Started index creator: "/sbin/xfsrestore -t -v silent - 2>/dev/null | sed -e 's/^/\//'" Tue Dec 13 00:12:12.281789021 2016: pid 27392: thd-0x55832f62f800: sendbackup: fd_to_shm_ring Tue Dec 13 00:12:12.297095901 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: using file dump (drive_simple) strategy Tue Dec 13 00:12:12.297184777 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: version 3.1.6 (dump format 3.0) Tue Dec 13 00:12:12.297242191 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: saving user quota information for: /home/tibbs Tue Dec 13 00:12:12.297496175 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: WARNING: overwriting: /home/tibbs/xfsdump_quotas Tue Dec 13 00:12:12.302963263 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: level 1 incremental dump of epithumia.math.uh.edu:/home/tibbs based on level 0 dump begun Sun Dec 11 00:07:47 2016 Tue Dec 13 00:12:12.303071689 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: dump date: Tue Dec 13 00:12:12 2016 Tue Dec 13 00:12:12.303128665 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: session id: e9534c33-09f3-4d14-a466-2f555087dbee Tue Dec 13 00:12:12.303177662 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: session label: "" Tue Dec 13 00:12:12.537952620 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: ino map phase 1: constructing initial dump list Tue Dec 13 00:12:13.110109115 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: ino map phase 2: pruning unneeded subtrees Tue Dec 13 00:12:14.288438745 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: ino map phase 3: skipping (only one dump stream) Tue Dec 13 00:12:14.289856033 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: ino map construction complete Tue Dec 13 00:12:14.290080379 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: estimated dump size: 1571638144 bytes Tue Dec 13 00:12:14.290138098 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: creating dump session media file 0 (media 0, file 0) Tue Dec 13 00:12:14.290171042 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: dumping ino map Tue Dec 13 00:12:14.294038732 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: dumping directories Tue Dec 13 00:12:14.383965769 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: dumping non-directory files Tue Dec 13 00:12:31.937735253 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: ending media file Tue Dec 13 00:12:31.939163828 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 80: size(|): xfsdump: media file size 1573122736 bytes Tue Dec 13 00:12:31.939233778 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: dump size (non-dir files) : 1570714376 bytes Tue Dec 13 00:12:31.939361731 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: dump complete: 19 seconds elapsed Tue Dec 13 00:12:31.939418884 2016: pid 27392: thd-0x55832f61cc00: sendbackup: 98: normal(|): xfsdump: Dump Status: SUCCESS Tue Dec 13 00:12:31.940430441 2016: pid 27395: thd-0x55832f61cc00: sendbackup: Index created successfully Tue Dec 13 00:12:31.942968442 2016: pid 27392: thd-0x55832f61cc00: sendbackup: close_producer_shm_ring sem_close(sem_write 0x7f07fd92b000 Tue Dec 13 00:12:31.942999006 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_close 0x7f07fd92b000 0 Tue Dec 13 00:12:31.943063748 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_close 0x7f07fd929000 0 Tue Dec 13 00:12:31.943090452 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_close 0x7f07fd92a000 0 Tue Dec 13 00:12:31.943104749 2016: pid 27392: thd-0x55832f61cc00: sendbackup: am_sem_close 0x7f07fd928000 0 Tue Dec 13 00:12:31.943305755 2016: pid 27392: thd-0x55832f61cc00: sendbackup: Parsed backup messages Tue Dec 13 00:12:31.943327439 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_utime : 0 Tue Dec 13 00:12:31.943335054 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_stime : 0 Tue Dec 13 00:12:31.943341613 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_maxrss : 9292 Tue Dec 13 00:12:31.943348305 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_ixrss : 0 Tue Dec 13 00:12:31.943354763 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_idrss : 0 Tue Dec 13 00:12:31.943361202 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_isrss : 0 Tue Dec 13 00:12:31.943367671 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_minflt : 841 Tue Dec 13 00:12:31.943383341 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_majflt : 0 Tue Dec 13 00:12:31.943390769 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_nswap : 0 Tue Dec 13 00:12:31.943397414 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_inblock : 0 Tue Dec 13 00:12:31.943404117 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_oublock : 40 Tue Dec 13 00:12:31.943410864 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_msgsnd : 0 Tue Dec 13 00:12:31.943417408 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_msgrcv : 0 Tue Dec 13 00:12:31.943424001 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_nsignals: 0 Tue Dec 13 00:12:31.943430776 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_nvcsw : 52335 Tue Dec 13 00:12:31.943437698 2016: pid 27392: thd-0x55832f61cc00: sendbackup: ru_nivcsw : 10 Tue Dec 13 00:12:31.943576311 2016: pid 27392: thd-0x55832f61cc00: sendbackup: pid 27392 finish time Tue Dec 13 00:12:31 2016
