#2386 Issue closed: ReaR generating very large initrd of 3GB

Labels: support / question, fixed / solved / done

NormHenderson opened issue at 2020-05-03 11:48:

Relax-and-Recover (ReaR) Issue Template

Fill in the following items before submitting a new issue
(quick response is not guaranteed with free support):

  • ReaR version ("/usr/sbin/rear -V"): 2.5 2019-05-10

  • OS version ("cat /etc/rear/os.conf" or "lsb_release -a" or "cat /etc/os-release"):
    Ubuntu 18.04.4 LTS
    Linux cem01vpbx 4.15.0-99-generic #100-Ubuntu SMP Wed Apr 22 20:32:56 UTC 2020 x86_64 x86_64 x86_64 GNU/Linux

  • ReaR configuration files ("cat /etc/rear/site.conf" and/or "cat /etc/rear/local.conf"):
    First system:

BACKUP=DUPLICITY
OUTPUT=ISO
ISO_VOLID="ReaR-cem01vpbx"
OUTPUT_URL='nfs://cem01nas2.cem.ceml.net/volume1/cem01pbx_backups'
KEEP_OLD_OUTPUT_COPY=y
SSH_UNPROTECTED_PRIVATE_KEYS='yes'
TIMESYNC=NTP
TIMESYNC_SOURCE=10.0.69.1
DUPLY_PROFILE="cem01vpbx"
USE_DHCLIENT=n
USE_STATIC_NETWORKING=y
USE_RESOLV_CONF=( '1.1.1.1' )
GRUB_RESCUE=y

Second system:

BACKUP=DUPLICITY
OUTPUT=ISO
ISO_VOLID="ReaR-CEM01FW"
OUTPUT_URL='nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups'
KEEP_OLD_OUTPUT_COPY=y
REAR_INITRD_COMPRESSION="lzma"
SSH_UNPROTECTED_PRIVATE_KEYS='yes'
TIMESYNC=NTP
TIMESYNC_SOURCE=10.0.69.2
DUPLY_PROFILE="cem01vfw"
USE_DHCLIENT=n
USE_STATIC_NETWORKING=y
USE_RESOLV_CONF=( '10.0.69.2' )
GRUB_RESCUE=y
  • Hardware (PC or PowerNV BareMetal or ARM) or virtual machine (KVM guest or PoverVM LPAR):
    VM guests on XCP-ng server 8.1

  • System architecture (x86 compatible or PPC64/PPC64LE or what exact ARM device):
    x86-64

  • Firmware (BIOS or UEFI or Open Firmware) and bootloader (GRUB or ELILO or Petitboot):
    Xenserver BIOS, GRUB

  • Storage (local disk or SSD) and/or SAN (FC or iSCSI or FCoE) and/or multipath (DM or NVMe):
    virtual local disk

  • Storage layout ("lsblk -ipo NAME,KNAME,PKNAME,TRAN,TYPE,FSTYPE,SIZE,MOUNTPOINT" or "lsblk" as makeshift):
    First system:

 NAME                         KNAME      PKNAME     TRAN TYPE FSTYPE       SIZE MOUNTPOINT
/dev/loop0                   /dev/loop0                 loop squashfs    93.9M /snap/core/9066
/dev/loop1                   /dev/loop1                 loop squashfs      91M /snap/core/6350
/dev/sr0                     /dev/sr0              ata  rom              1024M
/dev/xvda                    /dev/xvda                  disk               50G
|-/dev/xvda1                 /dev/xvda1 /dev/xvda       part                1M
|-/dev/xvda2                 /dev/xvda2 /dev/xvda       part ext4           2G /boot
|-/dev/xvda3                 /dev/xvda3 /dev/xvda       part swap           4G [SWAP]
|-/dev/xvda4                 /dev/xvda4 /dev/xvda       part LVM2_member  5.2G
| `-/dev/mapper/vg0-lv--root /dev/dm-0  /dev/xvda4      lvm  ext4          44G /
`-/dev/xvda5                 /dev/xvda5 /dev/xvda       part LVM2_member 38.8G
  `-/dev/mapper/vg0-lv--root /dev/dm-0  /dev/xvda5      lvm  ext4          44G /

Second system:

NAME                                  KNAME      PKNAME     TRAN TYPE FSTYPE       SIZE MOUNTPOINT
/dev/loop0                            /dev/loop0                 loop squashfs    93.8M /snap/core/8935
/dev/loop1                            /dev/loop1                 loop squashfs    93.9M /snap/core/9066
/dev/sr0                              /dev/sr0              ata  rom              1024M
/dev/xvda                             /dev/xvda                  disk               50G
|-/dev/xvda1                          /dev/xvda1 /dev/xvda       part                1M
|-/dev/xvda2                          /dev/xvda2 /dev/xvda       part ext4           1G /boot
`-/dev/xvda3                          /dev/xvda3 /dev/xvda       part LVM2_member   49G
  `-/dev/mapper/ubuntu--vg-ubuntu--lv /dev/dm-0  /dev/xvda3      lvm  ext4          49G /
  • Description of the issue (ideally so that others can reproduce it):
    The rear-initrd files are HUGE. In the case of the first system, it wants over 3 GB which won't fit on the /boot therefore rear mkrescue fails, see log. In the case of the second system, when using cgz compression (earlier attempt) produced rear-initrd.cgz of 347M, (current attempt) rear-initrd.xz is 223M, normal initrd is 56M. Both systems were recently built.
  • Workaround, if any:
    None
  • Attachments, as applicable ("rear -D mkrescue/mkbackup/recover" debug log files):
    From first system:
Relax-and-Recover 2.5 / 2019-05-10

Relax-and-Recover comes with ABSOLUTELY NO WARRANTY; for details see
the GNU General Public License at: http://www.gnu.org/licenses/gpl.html

Host cem01vpbx using Backup DUPLICITY and Output ISO
Build date: Sun, 03 May 2020 10:24:03 +0000
'
++ echo 'display message'
++ echo 'F1 message'
+++ get_template rear.help
+++ [[ -e /etc/rear/templates/rear.help ]]
+++ echo /usr/share/rear/conf/templates/rear.help
++ [[ -s /usr/share/rear/conf/templates/rear.help ]]
+++ get_template rear.help
+++ [[ -e /etc/rear/templates/rear.help ]]
+++ echo /usr/share/rear/conf/templates/rear.help
++ cp -v /usr/share/rear/conf/templates/rear.help /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/rear.help
'/usr/share/rear/conf/templates/rear.help' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/rear.help'
++ echo 'F2 rear.help'
++ echo 'say F2 - Show help'
++ syslinux_menu 'TABMSG Press [Tab] to edit, [F2] for help, [F1] for version info'
++ echo 'MENU TABMSG Press [Tab] to edit, [F2] for help, [F1] for version info'
++ echo 'timeout 300'
++ echo '#noescape 1'
++ syslinux_menu title Relax-and-Recover v2.5
++ echo 'MENU title' Relax-and-Recover v2.5
++ echo 'say rear - Recover cem01vpbx'
++ echo 'label rear'
++ syslinux_menu 'label ^Recover cem01vpbx'
++ echo 'MENU label ^Recover cem01vpbx'
+++ date -R
++ syslinux_menu_help 'Rescue image kernel 4.15.0-99-generic  Sun, 03 May 2020 10:28:20 +0000' 'BACKUP=DUPLICITY OUTPUT=ISO '
++ [[ -n y ]]
++ echo 'TEXT HELP'
++ for line in "$@"
++ echo 'Rescue image kernel 4.15.0-99-generic  Sun, 03 May 2020 10:28:20 +0000'
++ for line in "$@"
++ echo 'BACKUP=DUPLICITY OUTPUT=ISO '
++ echo ENDTEXT
++ echo 'kernel kernel'
++ echo 'append initrd=initrd.cgz root=/dev/ram0 vga=normal rw selinux=0 console=ttyS0,9600 console=tty0'
++ '[' boothd == manual ']'
++ echo ''
++ echo 'say rear - Recover cem01vpbx'
++ echo 'label rear-automatic'
++ syslinux_menu 'label ^Automatic Recover cem01vpbx'
++ echo 'MENU label ^Automatic Recover cem01vpbx'
+++ date -R
++ syslinux_menu_help 'Rescue image kernel 4.15.0-99-generic  Sun, 03 May 2020 10:28:20 +0000' 'BACKUP=DUPLICITY OUTPUT=ISO '
++ [[ -n y ]]
++ echo 'TEXT HELP'
++ for line in "$@"
++ echo 'Rescue image kernel 4.15.0-99-generic  Sun, 03 May 2020 10:28:20 +0000'
++ for line in "$@"
++ echo 'BACKUP=DUPLICITY OUTPUT=ISO '
++ echo ENDTEXT
++ echo 'kernel kernel'
++ echo 'append initrd=initrd.cgz root=/dev/ram0 vga=normal rw selinux=0 console=ttyS0,9600 console=tty0 auto_recover '
++ '[' boothd == automatic ']'
++ echo ''
++ syslinux_menu separator
++ echo 'MENU separator'
++ echo 'label -'
++ syslinux_menu 'label Other actions'
++ echo 'MENU label Other actions'
++ syslinux_menu disable
++ echo 'MENU disable'
++ echo ''
++ [[ -n y ]]
+++ get_template rear.help
+++ [[ -e /etc/rear/templates/rear.help ]]
+++ echo /usr/share/rear/conf/templates/rear.help
++ [[ -r /usr/share/rear/conf/templates/rear.help ]]
++ echo 'label help'
++ syslinux_menu 'label ^Help for Relax-and-Recover'
++ echo 'MENU label ^Help for Relax-and-Recover'
++ syslinux_menu_help 'More information about Relax-and-Recover and the steps for recovering your system'
++ [[ -n y ]]
++ echo 'TEXT HELP'
++ for line in "$@"
++ echo 'More information about Relax-and-Recover and the steps for recovering your system'
++ echo ENDTEXT
++ syslinux_menu 'help rear.help'
++ echo 'MENU help rear.help'
++ [[ -r /usr/lib/syslinux/modules/bios/chain.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/chain.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/chain.c32
'/usr/lib/syslinux/modules/bios/chain.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/chain.c32'
++ echo 'say boothd0 - boot first local disk'
++ echo 'label boothd0'
++ syslinux_menu 'label Boot First ^Local disk (hd0)'
++ echo 'MENU label Boot First ^Local disk (hd0)'
++ [[ isolinux == \i\s\o\l\i\n\u\x ]]
++ '[' boothd == boothd ']'
++ echo 'default boothd0'
++ syslinux_menu default
++ echo 'MENU default'
++ echo 'kernel chain.c32'
++ echo 'append hd0'
++ echo ''
++ echo 'say boothd1 - boot second local disk'
++ echo 'label boothd1'
++ syslinux_menu 'label Boot ^Second Local disk (hd1)'
++ echo 'MENU label Boot ^Second Local disk (hd1)'
++ [[ isolinux == \e\x\t\l\i\n\u\x ]]
++ echo 'kernel chain.c32'
++ echo 'append hd1'
++ echo ''
++ [[ isolinux != \e\x\t\l\i\n\u\x ]]
++ [[ ! -r /usr/lib/syslinux/modules/bios/chain.c32 ]]
++ echo 'say local - Boot from next boot device'
++ echo 'label local'
++ syslinux_menu 'label Boot ^Next device'
++ echo 'MENU label Boot ^Next device'
++ syslinux_menu_help 'Boot from the next device in the BIOS boot order list.'
++ [[ -n y ]]
++ echo 'TEXT HELP'
++ for line in "$@"
++ echo 'Boot from the next device in the BIOS boot order list.'
++ echo ENDTEXT
++ [[ isolinux == \p\x\e\l\i\n\u\x ]]
++ echo 'localboot -1'
++ echo ''
++ [[ -r /usr/lib/syslinux/modules/bios/ldlinux.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/ldlinux.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/ldlinux.c32
'/usr/lib/syslinux/modules/bios/ldlinux.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/ldlinux.c32'
++ [[ -r /usr/lib/syslinux/modules/bios/libcom32.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/libcom32.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libcom32.c32
'/usr/lib/syslinux/modules/bios/libcom32.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libcom32.c32'
++ [[ -r /usr/lib/syslinux/modules/bios/libgpl.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/libgpl.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libgpl.c32
'/usr/lib/syslinux/modules/bios/libgpl.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libgpl.c32'
++ [[ -r /usr/lib/syslinux/modules/bios/libmenu.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/libmenu.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libmenu.c32
'/usr/lib/syslinux/modules/bios/libmenu.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libmenu.c32'
++ [[ -r /usr/lib/syslinux/modules/bios/libutil.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/libutil.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libutil.c32
'/usr/lib/syslinux/modules/bios/libutil.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libutil.c32'
++ [[ -r /usr/lib/syslinux/modules/bios/vesamenu.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/vesamenu.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/vesamenu.c32
'/usr/lib/syslinux/modules/bios/vesamenu.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/vesamenu.c32'
++ [[ -r /usr/lib/syslinux/modules/bios/hdt.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/hdt.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/hdt.c32
'/usr/lib/syslinux/modules/bios/hdt.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/hdt.c32'
++ [[ -r /usr/share/hwdata/pci.ids ]]
++ [[ -r /usr/share/pci.ids ]]
++ [[ -r /lib/modules/4.15.0-99-generic/modules.pcimap ]]
++ echo 'say hdt - Hardware Detection Tool'
++ echo 'label hdt'
++ syslinux_menu 'label ^Hardware Detection Tool'
++ echo 'MENU label ^Hardware Detection Tool'
++ syslinux_menu_help 'Information about your current hardware configuration'
++ [[ -n y ]]
++ echo 'TEXT HELP'
++ for line in "$@"
++ echo 'Information about your current hardware configuration'
++ echo ENDTEXT
++ echo 'kernel hdt.c32'
++ echo ''
+++ ls -d
+++ tail -1
++ MEMTEST_BIN=.
++ [[ . != \. ]]
++ [[ -r /usr/lib/syslinux/modules/bios/reboot.c32 ]]
++ cp -v /usr/lib/syslinux/modules/bios/reboot.c32 /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/reboot.c32
'/usr/lib/syslinux/modules/bios/reboot.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/reboot.c32'
++ echo 'say reboot - Reboot the system'
++ echo 'label reboot'
++ syslinux_menu 'label Re^Boot system'
++ echo 'MENU label Re^Boot system'
++ syslinux_menu_help 'Reboot the system now'
++ [[ -n y ]]
++ echo 'TEXT HELP'
++ for line in "$@"
++ echo 'Reboot the system now'
++ echo ENDTEXT
++ echo 'kernel reboot.c32'
++ echo ''
++ [[ -r /usr/lib/syslinux/modules/bios/poweroff.com ]]
++ [[ -r /usr/lib/syslinux/modules/bios/menu.c32 ]]
++ echo 'default menu.c32'
++ Log 'Created isolinux configuration'
++ echo '2020-05-03 10:28:20.151319674 Created isolinux configuration'
2020-05-03 10:28:20.151319674 Created isolinux configuration
++ cp -v -r /tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux /tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/isolinux.bin' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/isolinux.bin'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/isolinux.cfg' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/isolinux.cfg'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/menu.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/menu.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/message' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/message'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/rear.help' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/rear.help'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/chain.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/chain.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/ldlinux.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/ldlinux.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libcom32.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/libcom32.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libgpl.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/libgpl.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libmenu.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/libmenu.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/libutil.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/libutil.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/vesamenu.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/vesamenu.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/hdt.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/hdt.c32'
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/isolinux/reboot.c32' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/reboot.c32'
++ StopIfError 'Could not copy syslinux boot directory to isofs/'
++ ((  0 != 0  ))
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:20.157125590 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:20.157125590 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:20.164451718 Including output/default/400_copy_disk_struct_files.sh
2020-05-03 10:28:20.166502934 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/default/400_copy_disk_struct_files.sh
++ '[' -z '' ']'
++ return
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:20.173201101 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:20.173201101 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:20.179044841 Including output/ISO/Linux-i386/700_create_efibootimg.sh
2020-05-03 10:28:20.180508559 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/ISO/Linux-i386/700_create_efibootimg.sh
++ is_true
++ case "$1" in
++ return 1
++ return 0
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:20.186699626 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:20.186699626 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:20.192428852 Including output/ISO/Linux-i386/800_create_isofs.sh
2020-05-03 10:28:20.193954782 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/ISO/Linux-i386/800_create_isofs.sh
++ test -x /usr/bin/xorrisofs
+++ basename /usr/bin/xorrisofs
++ [[ xorrisofs = \e\b\i\s\o ]]
++ Log 'Copying kernel and initrd'
++ echo '2020-05-03 10:28:20.201920338 Copying kernel and initrd'
2020-05-03 10:28:20.201920338 Copying kernel and initrd
++ cp -pL -v /boot/vmlinuz-4.15.0-99-generic /tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/kernel
'/boot/vmlinuz-4.15.0-99-generic' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/kernel'
++ cp -v /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz /tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/initrd.cgz
'/tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz' -> '/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs/isolinux/initrd.cgz'
++ test 0 -gt 0
++ mkdir -p -v /var/lib/rear/output
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:23.538566265 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:23.538566265 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:23.544408737 Including output/ISO/Linux-i386/810_prepare_multiple_iso.sh
2020-05-03 10:28:23.545902087 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/ISO/Linux-i386/810_prepare_multiple_iso.sh
++ test ''
++ return 0
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:23.552269656 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:23.552269656 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:23.559550092 Including output/ISO/Linux-i386/820_create_iso_image.sh
2020-05-03 10:28:23.561236087 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/ISO/Linux-i386/820_create_iso_image.sh
++ is_true no
++ case "$1" in
++ return 1
++ Log 'Starting '\''/usr/bin/xorrisofs'\'''
++ echo '2020-05-03 10:28:23.568867350 Starting '\''/usr/bin/xorrisofs'\'''
2020-05-03 10:28:23.568867350 Starting '/usr/bin/xorrisofs'
++ LogPrint 'Making ISO image'
++ Log 'Making ISO image'
++ echo '2020-05-03 10:28:23.570946801 Making ISO image'
2020-05-03 10:28:23.570946801 Making ISO image
++ Print 'Making ISO image'
++ is_true
++ case "$1" in
++ return 1
++ EFIBOOT=
++ pushd /tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs
+++ basename /usr/bin/xorrisofs
++ test ebiso = xorrisofs
++ /usr/bin/xorrisofs -v -o /var/lib/rear/output/rear-cem01vpbx.iso -b isolinux/isolinux.bin -c isolinux/boot.cat -no-emul-boot -boot-load-size 4 -boot-info-table -R -J -volid ReaR-cem01vpbx -v -iso-level 3 .
xorriso 1.4.8 : RockRidge filesystem manipulator, libburnia project.

Drive current: -outdev 'stdio:/var/lib/rear/output/rear-cem01vpbx.iso'
Media current: stdio file, overwriteable
Media status : is blank
Media summary: 0 sessions, 0 data blocks, 0 data, 19.6g free
xorriso : WARNING : -volid text does not comply to ISO 9660 / ECMA 119 rules
Added to ISO image: directory '/'='/tmp/rear.tHhrnFJmZnPiDnI/tmp/isofs'
xorriso : UPDATE : 17 files added in 1 seconds
xorriso : UPDATE : 17 files added in 1 seconds
xorriso : UPDATE :  0.78% done
xorriso : UPDATE :  18.10% done
xorriso : UPDATE :  29.74% done, estimate finish Sun May 03 10:28:32 2020
xorriso : UPDATE :  38.31% done, estimate finish Sun May 03 10:28:33 2020
xorriso : UPDATE :  52.07% done, estimate finish Sun May 03 10:28:33 2020
xorriso : UPDATE :  65.57% done, estimate finish Sun May 03 10:28:32 2020
xorriso : UPDATE :  79.62% done, estimate finish Sun May 03 10:28:32 2020
xorriso : UPDATE :  93.07% done
xorriso : UPDATE : Thank you for being patient. Working since 8 seconds.
xorriso : UPDATE : Thank you for being patient. Working since 9 seconds.
xorriso : UPDATE : Thank you for being patient. Working since 10 seconds.
xorriso : UPDATE : Thank you for being patient. Working since 11 seconds.
xorriso : UPDATE : Thank you for being patient. Working since 12 seconds.
ISO image produced: 1643975 sectors
Written to medium : 1643975 sectors at LBA 0
Writing to 'stdio:/var/lib/rear/output/rear-cem01vpbx.iso' completed successfully.

++ StopIfError 'Could not create ISO image (with /usr/bin/xorrisofs)'
++ ((  0 != 0  ))
++ popd
++ iso_image_size=($(du -h "$ISO_DIR/$ISO_PREFIX.iso"))
+++ du -h /var/lib/rear/output/rear-cem01vpbx.iso
++ LogPrint 'Wrote ISO image: /var/lib/rear/output/rear-cem01vpbx.iso (3.2G)'
++ Log 'Wrote ISO image: /var/lib/rear/output/rear-cem01vpbx.iso (3.2G)'
++ echo '2020-05-03 10:28:37.463322049 Wrote ISO image: /var/lib/rear/output/rear-cem01vpbx.iso (3.2G)'
2020-05-03 10:28:37.463322049 Wrote ISO image: /var/lib/rear/output/rear-cem01vpbx.iso (3.2G)
++ Print 'Wrote ISO image: /var/lib/rear/output/rear-cem01vpbx.iso (3.2G)'
++ RESULT_FILES=("${RESULT_FILES[@]}" "$ISO_DIR/$ISO_PREFIX.iso")
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:37.469922980 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:37.469922980 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:37.516561596 Including output/ISO/Linux-i386/830_create_iso_image_EFISTUB.sh
2020-05-03 10:28:37.521129071 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/ISO/Linux-i386/830_create_iso_image_EFISTUB.sh
++ is_true no
++ case "$1" in
++ return 1
++ return 0
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:37.549264574 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:37.549264574 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:37.562042254 Including output/ISO/Linux-i386/850_check_for_errors.sh
2020-05-03 10:28:37.564524180 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/ISO/Linux-i386/850_check_for_errors.sh
++ [[ 1 -eq 1 ]]
++ return
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 10:28:37.574884651 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 10:28:37.574884651 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 10:28:37.584871463 Including output/default/940_grub2_rescue.sh
2020-05-03 10:28:37.587565593 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/default/940_grub2_rescue.sh
++ is_true no
++ case "$1" in
++ return 1
++ is_true y
++ case "$1" in
++ return 0
+++ type -p grub-probe
++ [[ ! -n /usr/sbin/grub-probe ]]
++ local grub_rear_menu_entry_name=Relax-and-Recover
++ LogPrint 'Setting up GRUB_RESCUE: Adding Relax-and-Recover rescue system to the local GRUB 2 configuration.'
++ Log 'Setting up GRUB_RESCUE: Adding Relax-and-Recover rescue system to the local GRUB 2 configuration.'
++ echo '2020-05-03 10:28:37.608123353 Setting up GRUB_RESCUE: Adding Relax-and-Recover rescue system to the local GRUB 2 configuration.'
2020-05-03 10:28:37.608123353 Setting up GRUB_RESCUE: Adding Relax-and-Recover rescue system to the local GRUB 2 configuration.
++ Print 'Setting up GRUB_RESCUE: Adding Relax-and-Recover rescue system to the local GRUB 2 configuration.'
++ test unrestricted = ''
++ local grub_num=
+++ type -p grub2-probe
++ [[ -n '' ]]
++ test -r /boot/vmlinuz-4.15.0-99-generic
++ local initrd_file=/tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz
++ test -r /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz
++ local boot_dir=/boot
++ local boot_kernel_name=rear-kernel
++ local boot_initrd_name=rear-initrd.cgz
++ local boot_kernel_file=/boot/rear-kernel
++ local boot_initrd_file=/boot/rear-initrd.cgz
++ local grub_config_dir=/boot/grub
+++ awk 'END { print $4 * 1024 }'
+++ df -Pkl /boot
++ local free_space=1699467264
+++ total_filesize /boot/rear-kernel /boot/rear-initrd.cgz
+++ stat --format %s /boot/rear-kernel /boot/rear-initrd.cgz
+++ awk 'BEGIN { t=0 } { t+=$1 } END { print t }'
++ local already_used_space=0
++ local available_space=1699467264
+++ total_filesize /boot/vmlinuz-4.15.0-99-generic /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz
+++ stat --format %s /boot/vmlinuz-4.15.0-99-generic /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz
+++ awk 'BEGIN { t=0 } { t+=$1 } END { print t }'
++ local required_space=3365772749
++ echo KERNEL_FILE is /boot/vmlinuz-4.15.0-99-generic , initrd_file is /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz , required_space is 3365772749
KERNEL_FILE is /boot/vmlinuz-4.15.0-99-generic , initrd_file is /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz , required_space is 3365772749
++ ls -l /boot/vmlinuz-4.15.0-99-generic /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz
-rw------- 1 root root    8380056 Apr 22 18:32 /boot/vmlinuz-4.15.0-99-generic
-rw-r--r-- 1 root root 3357392693 May  3 10:28 /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz
++ ((  available_space < required_space  ))
++ required_MiB=3209
++ available_MiB=1620
++ Error 'Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.'
++ PrintError 'ERROR: Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.'
++ PrintError 'Some latest log messages since the last called script 940_grub2_rescue.sh:'
++ PrintError '  2020-05-03 10:28:37.584871463 Including output/default/940_grub2_rescue.sh
  2020-05-03 10:28:37.587565593 Entering debugscripts mode via '\''set -x'\''.
  2020-05-03 10:28:37.608123353 Setting up GRUB_RESCUE: Adding Relax-and-Recover rescue system to the local GRUB 2 configuration.
  KERNEL_FILE is /boot/vmlinuz-4.15.0-99-generic , initrd_file is /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz , required_space is 3365772749
  -rw------- 1 root root    8380056 Apr 22 18:32 /boot/vmlinuz-4.15.0-99-generic
  -rw-r--r-- 1 root root 3357392693 May  3 10:28 /tmp/rear.tHhrnFJmZnPiDnI/tmp/initrd.cgz'
++ Log 'ERROR: Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.'
++ echo '2020-05-03 10:28:37.857257834 ERROR: Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.'
2020-05-03 10:28:37.857257834 ERROR: Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.
++ LogToSyslog 'ERROR: Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.'
++ logger -t rear -i 'ERROR: Cannot setup GRUB_RESCUE: Not enough disk space in /boot for Relax-and-Recover rescue system. Required: 3209 MiB. Available: 1620 MiB.'
++ echo '===== Stack trace ====='
===== Stack trace =====
++ local c=0
++ caller 0
++ :
++ caller 1
++ :
++ caller 2
++ :
++ caller 3
++ :
++ caller 4
++ :
++ caller 5
++ awk ' { l[NR]=$3":"$1" "$2 }
                   END { for (i=NR; i>0;) print "Trace "NR-i": "l[i--] }
                 '
Trace 0: /usr/sbin/rear:541 main
Trace 1: /usr/share/rear/lib/mkrescue-workflow.sh:22 WORKFLOW_mkrescue
Trace 2: /usr/share/rear/lib/framework-functions.sh:116 SourceStage
Trace 3: /usr/share/rear/lib/framework-functions.sh:56 Source
Trace 4: /usr/share/rear/output/default/940_grub2_rescue.sh:67 source
++ echo '=== End stack trace ==='
=== End stack trace ===
++ kill -USR1 2873
+++ EXIT_FAIL_MESSAGE=0
+++ echo 'Aborting due to an error, check /var/log/rear/rear-cem01vpbx.log for details'
+++ kill 2873
+++ DoExitTasks
+++ LogPrint 'Exiting rear mkrescue (PID 2873) and its descendant processes ...'
+++ Log 'Exiting rear mkrescue (PID 2873) and its descendant processes ...'
+++ echo '2020-05-03 10:28:37.869923183 Exiting rear mkrescue (PID 2873) and its descendant processes ...'
2020-05-03 10:28:37.869923183 Exiting rear mkrescue (PID 2873) and its descendant processes ...
+++ Print 'Exiting rear mkrescue (PID 2873) and its descendant processes ...'
+++ sleep 3
+++ log_descendants_pids
++++ pstree -Aplau 2873
+++ Log 'rear,2873 /usr/sbin/rear -D -d -v mkrescue
  `-rear,21442 /usr/sbin/rear -D -d -v mkrescue
      `-pstree,21443 -Aplau 2873'
+++ echo '2020-05-03 10:28:40.901532509 rear,2873 /usr/sbin/rear -D -d -v mkrescue
  `-rear,21442 /usr/sbin/rear -D -d -v mkrescue
      `-pstree,21443 -Aplau 2873'
2020-05-03 10:28:40.901532509 rear,2873 /usr/sbin/rear -D -d -v mkrescue
  `-rear,21442 /usr/sbin/rear -D -d -v mkrescue
      `-pstree,21443 -Aplau 2873
+++ terminate_descendants_from_grandchildren_to_children
+++ local remaining_columns=-40
+++ test -40 -ge 40
+++ remaining_columns=40
+++ local descendant_pid=
+++ local not_yet_terminated_pids=
++++ descendants_pids 2873
++++ local parent_pid=2873
++++ kill -0 2873
++++ local child_pid=
+++++ ps --ppid 2873 -o pid=
++++ for child_pid in $( ps --ppid $parent_pid -o pid= )
++++ kill -0 21446
++++ descendants_pids 21446
++++ local parent_pid=21446
++++ kill -0 21446
++++ local child_pid=
+++++ ps --ppid 21446 -o pid=
++++ for child_pid in $( ps --ppid $parent_pid -o pid= )
++++ kill -0 21449
++++ kill -0 21446
++++ echo 21446
++++ kill -0 2873
++++ echo 2873
+++ for descendant_pid in $( descendants_pids $MASTER_PID )
+++ test 2873 -eq 21446
+++ kill -0 21446
/usr/share/rear/lib/_input-output-functions.sh: line 146: kill: (21446) - No such process
+++ continue
+++ for descendant_pid in $( descendants_pids $MASTER_PID )
+++ test 2873 -eq 2873
+++ continue
+++ test ''
+++ return 0
+++ LogPrint 'Running exit tasks'
+++ Log 'Running exit tasks'
+++ echo '2020-05-03 10:28:41.005620263 Running exit tasks'
2020-05-03 10:28:41.005620263 Running exit tasks
+++ Print 'Running exit tasks'
+++ local exit_task=
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''umount -f -v '\''/tmp/rear.tHhrnFJmZnPiDnI/outputfs'\'' >&2'\'''
+++ test 1
+++ Log 'Exit task '\''umount -f -v '\''/tmp/rear.tHhrnFJmZnPiDnI/outputfs'\'' >&2'\'''
+++ echo '2020-05-03 10:28:41.010045875 Exit task '\''umount -f -v '\''/tmp/rear.tHhrnFJmZnPiDnI/outputfs'\'' >&2'\'''
2020-05-03 10:28:41.010045875 Exit task 'umount -f -v '/tmp/rear.tHhrnFJmZnPiDnI/outputfs' >&2'
+++ eval 'umount -f -v '\''/tmp/rear.tHhrnFJmZnPiDnI/outputfs'\'' >&2'
++++ umount -f -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs
/tmp/rear.tHhrnFJmZnPiDnI/outputfs: nfs4 mount point detected
/tmp/rear.tHhrnFJmZnPiDnI/outputfs: umounted
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''rm -Rf -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs >&2'\'''
+++ test 1
+++ Log 'Exit task '\''rm -Rf -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs >&2'\'''
+++ echo '2020-05-03 10:28:41.116882538 Exit task '\''rm -Rf -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs >&2'\'''
2020-05-03 10:28:41.116882538 Exit task 'rm -Rf -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs >&2'
+++ eval 'rm -Rf -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs >&2'
++++ rm -Rf -v /tmp/rear.tHhrnFJmZnPiDnI/outputfs
removed directory '/tmp/rear.tHhrnFJmZnPiDnI/outputfs'
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''cleanup_build_area_and_end_program'\'''
+++ test 1
+++ Log 'Exit task '\''cleanup_build_area_and_end_program'\'''
+++ echo '2020-05-03 10:28:41.123462161 Exit task '\''cleanup_build_area_and_end_program'\'''
2020-05-03 10:28:41.123462161 Exit task 'cleanup_build_area_and_end_program'
+++ eval cleanup_build_area_and_end_program
++++ cleanup_build_area_and_end_program
++++ Log 'Finished in 278 seconds'
++++ echo '2020-05-03 10:28:41.126012408 Finished in 278 seconds'
2020-05-03 10:28:41.126012408 Finished in 278 seconds
++++ test 1
++++ LogPrint 'You should also rm -Rf /tmp/rear.tHhrnFJmZnPiDnI'
++++ Log 'You should also rm -Rf /tmp/rear.tHhrnFJmZnPiDnI'
++++ echo '2020-05-03 10:28:41.128469981 You should also rm -Rf /tmp/rear.tHhrnFJmZnPiDnI'
2020-05-03 10:28:41.128469981 You should also rm -Rf /tmp/rear.tHhrnFJmZnPiDnI
++++ Print 'You should also rm -Rf /tmp/rear.tHhrnFJmZnPiDnI'
++++ Log 'End of program reached'
++++ echo '2020-05-03 10:28:41.131120977 End of program reached'
2020-05-03 10:28:41.131120977 End of program reached
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vpbx.log for details'\'' 1>&8'\'''
+++ test 1
+++ Log 'Exit task '\''(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vpbx.log for details'\'' 1>&8'\'''
+++ echo '2020-05-03 10:28:41.133577984 Exit task '\''(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vpbx.log for details'\'' 1>&8'\'''
2020-05-03 10:28:41.133577984 Exit task '(( EXIT_FAIL_MESSAGE )) && echo 'rear mkrescue failed, check /var/log/rear/rear-cem01vpbx.log for details' 1>&8'
+++ eval '(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vpbx.log for details'\'' 1>&8'
++++ ((  EXIT_FAIL_MESSAGE  ))
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''exec 8>&-'\'''
+++ test 1
+++ Log 'Exit task '\''exec 8>&-'\'''
+++ echo '2020-05-03 10:28:41.135857064 Exit task '\''exec 8>&-'\'''
2020-05-03 10:28:41.135857064 Exit task 'exec 8>&-'
+++ eval 'exec 8>&-'
++++ exec
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''exec 7>&-'\'''
+++ test 1
+++ Log 'Exit task '\''exec 7>&-'\'''
+++ echo '2020-05-03 10:28:41.138163363 Exit task '\''exec 7>&-'\'''
2020-05-03 10:28:41.138163363 Exit task 'exec 7>&-'
+++ eval 'exec 7>&-'
++++ exec
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\''exec 6<&-'\'''
+++ test 1
+++ Log 'Exit task '\''exec 6<&-'\'''
+++ echo '2020-05-03 10:28:41.140382447 Exit task '\''exec 6<&-'\'''
2020-05-03 10:28:41.140382447 Exit task 'exec 6<&-'
+++ eval 'exec 6<&-'
++++ exec
+++ for exit_task in "${EXIT_TASKS[@]}"
+++ Debug 'Exit task '\'''\'''
+++ test 1
+++ Log 'Exit task '\'''\'''
+++ echo '2020-05-03 10:28:41.142598446 Exit task '\'''\'''
2020-05-03 10:28:41.142598446 Exit task ''
+++ eval ''

From second system:

Relax-and-Recover 2.5 / 2019-05-10

Relax-and-Recover comes with ABSOLUTELY NO WARRANTY; for details see
the GNU General Public License at: http://www.gnu.org/licenses/gpl.html

Host cem01vfw using Backup DUPLICITY and Output ISO
Build date: Sun, 03 May 2020 11:16:29 +0000
\n\n'
+++ get_template 'RESULT_m*ailbody.txt'
+++ [[ -e /etc/rear/templates/RESULT_m*ailbody.txt ]]
+++ echo /usr/share/rear/conf/templates/RESULT_mailbody.txt
+++ get_template 'RESULT_u*sage_ISO.txt'
+++ [[ -e /etc/rear/templates/RESULT_u*sage_ISO.txt ]]
+++ echo /usr/share/rear/conf/templates/RESULT_usage_ISO.txt
++ cat - /usr/share/rear/conf/templates/RESULT_mailbody.txt /usr/share/rear/conf/templates/RESULT_usage_ISO.txt
++ echo
++ for file in "${MAIL_FILES[@]}"
++ create_mime_part_binary /tmp/rear.TatryvPaWbvImmH/tmp/VERSION
++ echo --_---------_-28619478150619862
++ echo 'Content-Transfer-Encoding: base64'
+++ basename /tmp/rear.TatryvPaWbvImmH/tmp/VERSION
++ echo 'Content-Type: application/octet-stream; name="VERSION"'
+++ basename /tmp/rear.TatryvPaWbvImmH/tmp/VERSION
++ echo 'Content-Disposition: attachment; filename="VERSION"'
++ echo
++ perl -MMIME::Base64 -0777 -ne 'print encode_base64($_)'
++ StopIfError 'perl MIME::Base64 failed'
++ ((  0 != 0  ))
++ echo
++ for file in "${MAIL_FILES[@]}"
++ create_mime_part_binary /tmp/rear.TatryvPaWbvImmH/tmp/README
++ echo --_---------_-28619478150619862
++ echo 'Content-Transfer-Encoding: base64'
+++ basename /tmp/rear.TatryvPaWbvImmH/tmp/README
++ echo 'Content-Type: application/octet-stream; name="README"'
+++ basename /tmp/rear.TatryvPaWbvImmH/tmp/README
++ echo 'Content-Disposition: attachment; filename="README"'
++ echo
++ perl -MMIME::Base64 -0777 -ne 'print encode_base64($_)'
++ StopIfError 'perl MIME::Base64 failed'
++ ((  0 != 0  ))
++ echo
++ for file in "${MAIL_FILES[@]}"
++ create_mime_part_binary /tmp/rear.TatryvPaWbvImmH/tmp/rear-cem01vfw.log
++ echo --_---------_-28619478150619862
++ echo 'Content-Transfer-Encoding: base64'
+++ basename /tmp/rear.TatryvPaWbvImmH/tmp/rear-cem01vfw.log
++ echo 'Content-Type: application/octet-stream; name="rear-cem01vfw.log"'
+++ basename /tmp/rear.TatryvPaWbvImmH/tmp/rear-cem01vfw.log
++ echo 'Content-Disposition: attachment; filename="rear-cem01vfw.log"'
++ echo
++ perl -MMIME::Base64 -0777 -ne 'print encode_base64($_)'
++ StopIfError 'perl MIME::Base64 failed'
++ ((  0 != 0  ))
++ echo
++ create_mime_ending
++ echo --_---------_-28619478150619862--
++ echo
++ MAIL_SIZE=($(du -h $TMP_DIR/email.bin))
+++ du -h /tmp/rear.TatryvPaWbvImmH/tmp/email.bin
++ LogPrint 'Mailing resulting files (6.9M) to nhenderson@ceml.net'
++ Log 'Mailing resulting files (6.9M) to nhenderson@ceml.net'
++ echo '2020-05-03 11:24:37.633287208 Mailing resulting files (6.9M) to nhenderson@ceml.net'
2020-05-03 11:24:37.633287208 Mailing resulting files (6.9M) to nhenderson@ceml.net
++ Print 'Mailing resulting files (6.9M) to nhenderson@ceml.net'
++ /usr/sbin/sendmail -oi -t
++ LogPrintIfError 'WARNING ! Sending Email with '\''/usr/sbin/sendmail -oi' '-t'\'' failed.'
++ ((  0 != 0  ))
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 11:24:37.725049537 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 11:24:37.725049537 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 11:24:37.733033343 Including output/default/970_remove_lock.sh
2020-05-03 11:24:37.734221869 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/default/970_remove_lock.sh
+++ url_scheme nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ local url=nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ local scheme=nfs
+++ grep -q :
+++ echo nfs
+++ echo nfs
++ local scheme=nfs
+++ url_path nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ local url=nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ local url_without_scheme=cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ echo /volume1/cem01fw_backups
++ local path=/volume1/cem01fw_backups
+++ output_path nfs /volume1/cem01fw_backups
+++ local scheme=nfs
+++ local path=/volume1/cem01fw_backups
+++ case $scheme in
+++ path=/tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw
+++ echo /tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw
++ local opath=/tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw
++ '[' -z /tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw ']'
++ [[ nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups != '' ]]
++ rm -f -v /tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw/.lockfile
removed '/tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw/.lockfile'
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 11:24:37.754337555 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 11:24:37.754337555 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 11:24:37.758763862 Including output/default/980_umount_output_dir.sh
2020-05-03 11:24:37.759912696 Entering debugscripts mode via 'set -x'.
+ source /usr/share/rear/output/default/980_umount_output_dir.sh
++ [[ -n '' ]]
++ [[ -z nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups ]]
++ umount_url nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups /tmp/rear.TatryvPaWbvImmH/outputfs
++ local url=nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
++ local mountpoint=/tmp/rear.TatryvPaWbvImmH/outputfs
++ case $(url_scheme $url) in
+++ url_scheme nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ local url=nfs://cem01nas2.cem.ceml.net/volume1/cem01fw_backups
+++ local scheme=nfs
+++ grep -q :
+++ echo nfs
+++ echo nfs
++ umount_mountpoint /tmp/rear.TatryvPaWbvImmH/outputfs
++ local mountpoint=/tmp/rear.TatryvPaWbvImmH/outputfs
++ Log 'Unmounting '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'''
++ echo '2020-05-03 11:24:37.770011972 Unmounting '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'''
2020-05-03 11:24:37.770011972 Unmounting '/tmp/rear.TatryvPaWbvImmH/outputfs'
++ umount -v /tmp/rear.TatryvPaWbvImmH/outputfs
/tmp/rear.TatryvPaWbvImmH/outputfs: nfs4 mount point detected
/tmp/rear.TatryvPaWbvImmH/outputfs: umounted
++ [[ 0 -eq 0 ]]
++ return 0
++ StopIfError 'Unmounting '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' failed.'
++ ((  0 != 0  ))
++ RemoveExitTask 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ local removed= exit_tasks=
++ (( c=0  ))
++ (( c<8  ))
++ test 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2' = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ unset 'EXIT_TASKS[c]'
++ removed=yes
++ Debug 'Removed '\''umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'\'' from the list of exit tasks'
++ test 1
++ Log 'Removed '\''umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'\'' from the list of exit tasks'
++ echo '2020-05-03 11:24:37.902989674 Removed '\''umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'\'' from the list of exit tasks'
2020-05-03 11:24:37.902989674 Removed 'umount -f -v '/tmp/rear.TatryvPaWbvImmH/outputfs' >&2' from the list of exit tasks
++ (( c++  ))
++ (( c<7  ))
++ test 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2' = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ (( c++  ))
++ (( c<7  ))
++ test cleanup_build_area_and_end_program = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ (( c++  ))
++ (( c<7  ))
++ test '(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details'\'' 1>&8' = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ (( c++  ))
++ (( c<7  ))
++ test 'exec 8>&-' = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ (( c++  ))
++ (( c<7  ))
++ test 'exec 7>&-' = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ (( c++  ))
++ (( c<7  ))
++ test 'exec 6<&-' = 'umount -f -v '\''/tmp/rear.TatryvPaWbvImmH/outputfs'\'' >&2'
++ (( c++  ))
++ (( c<7  ))
++ test yes = yes
++ return 0
++ [[ -d /tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw ]]
++ [[ -d /tmp/rear.TatryvPaWbvImmH/outputfs/cem01vfw ]]
++ rmdir -v /tmp/rear.TatryvPaWbvImmH/outputfs
rmdir: removing directory, '/tmp/rear.TatryvPaWbvImmH/outputfs'
++ [[ 0 -eq 0 ]]
++ RemoveExitTask 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ local removed= exit_tasks=
++ (( c=0  ))
++ (( c<7  ))
++ test '' = 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ (( c++  ))
++ (( c<7  ))
++ test 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2' = 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ unset 'EXIT_TASKS[c]'
++ removed=yes
++ Debug 'Removed '\''rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'\'' from the list of exit tasks'
++ test 1
++ Log 'Removed '\''rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'\'' from the list of exit tasks'
++ echo '2020-05-03 11:24:37.907158985 Removed '\''rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'\'' from the list of exit tasks'
2020-05-03 11:24:37.907158985 Removed 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2' from the list of exit tasks
++ (( c++  ))
++ (( c<6  ))
++ test cleanup_build_area_and_end_program = 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ (( c++  ))
++ (( c<6  ))
++ test '(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details'\'' 1>&8' = 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ (( c++  ))
++ (( c<6  ))
++ test 'exec 8>&-' = 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ (( c++  ))
++ (( c<6  ))
++ test 'exec 7>&-' = 'rm -Rf -v /tmp/rear.TatryvPaWbvImmH/outputfs >&2'
++ (( c++  ))
++ (( c<6  ))
++ test yes = yes
+ source_return_code=0
+ test 0 -eq 0
+ test 1
+ Debug 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ test 1
+ Log 'Leaving debugscripts mode (back to previous bash flags and options settings).'
+ echo '2020-05-03 11:24:37.908721413 Leaving debugscripts mode (back to previous bash flags and options settings).'
2020-05-03 11:24:37.908721413 Leaving debugscripts mode (back to previous bash flags and options settings).
2020-05-03 11:24:37.910696769 Finished running 'output' stage in 8 seconds
2020-05-03 11:24:37.911920234 Finished running mkrescue workflow
+ LogPrint 'Exiting rear mkrescue (PID 8731) and its descendant processes ...'
+ Log 'Exiting rear mkrescue (PID 8731) and its descendant processes ...'
+ echo '2020-05-03 11:24:37.939623662 Exiting rear mkrescue (PID 8731) and its descendant processes ...'
2020-05-03 11:24:37.939623662 Exiting rear mkrescue (PID 8731) and its descendant processes ...
+ Print 'Exiting rear mkrescue (PID 8731) and its descendant processes ...'
+ sleep 3
+ log_descendants_pids
++ pstree -Aplau 8731
+ Log 'rear,8731 /usr/sbin/rear -D -d -v mkrescue
  `-rear,27552 /usr/sbin/rear -D -d -v mkrescue
      `-pstree,27553 -Aplau 8731'
+ echo '2020-05-03 11:24:40.973794289 rear,8731 /usr/sbin/rear -D -d -v mkrescue
  `-rear,27552 /usr/sbin/rear -D -d -v mkrescue
      `-pstree,27553 -Aplau 8731'
2020-05-03 11:24:40.973794289 rear,8731 /usr/sbin/rear -D -d -v mkrescue
  `-rear,27552 /usr/sbin/rear -D -d -v mkrescue
      `-pstree,27553 -Aplau 8731
+ terminate_descendants_from_grandchildren_to_children
+ local remaining_columns=-40
+ test -40 -ge 40
+ remaining_columns=40
+ local descendant_pid=
+ local not_yet_terminated_pids=
++ descendants_pids 8731
++ local parent_pid=8731
++ kill -0 8731
++ local child_pid=
+++ ps --ppid 8731 -o pid=
++ for child_pid in $( ps --ppid $parent_pid -o pid= )
++ kill -0 27556
++ descendants_pids 27556
++ local parent_pid=27556
++ kill -0 27556
++ local child_pid=
+++ ps --ppid 27556 -o pid=
++ for child_pid in $( ps --ppid $parent_pid -o pid= )
++ kill -0 27559
++ kill -0 27556
++ echo 27556
++ kill -0 8731
++ echo 8731
+ for descendant_pid in $( descendants_pids $MASTER_PID )
+ test 8731 -eq 27556
+ kill -0 27556
/usr/share/rear/lib/_input-output-functions.sh: line 146: kill: (27556) - No such process
+ continue
+ for descendant_pid in $( descendants_pids $MASTER_PID )
+ test 8731 -eq 8731
+ continue
+ test ''
+ return 0
+ LogPrint 'Running exit tasks'
+ Log 'Running exit tasks'
+ echo '2020-05-03 11:24:41.030693595 Running exit tasks'
2020-05-03 11:24:41.030693595 Running exit tasks
+ Print 'Running exit tasks'
+ local exit_task=
+ for exit_task in "${EXIT_TASKS[@]}"
+ Debug 'Exit task '\''cleanup_build_area_and_end_program'\'''
+ test 1
+ Log 'Exit task '\''cleanup_build_area_and_end_program'\'''
+ echo '2020-05-03 11:24:41.032947323 Exit task '\''cleanup_build_area_and_end_program'\'''
2020-05-03 11:24:41.032947323 Exit task 'cleanup_build_area_and_end_program'
+ eval cleanup_build_area_and_end_program
++ cleanup_build_area_and_end_program
++ Log 'Finished in 492 seconds'
++ echo '2020-05-03 11:24:41.034784605 Finished in 492 seconds'
2020-05-03 11:24:41.034784605 Finished in 492 seconds
++ test 1
++ LogPrint 'You should also rm -Rf /tmp/rear.TatryvPaWbvImmH'
++ Log 'You should also rm -Rf /tmp/rear.TatryvPaWbvImmH'
++ echo '2020-05-03 11:24:41.036531092 You should also rm -Rf /tmp/rear.TatryvPaWbvImmH'
2020-05-03 11:24:41.036531092 You should also rm -Rf /tmp/rear.TatryvPaWbvImmH
++ Print 'You should also rm -Rf /tmp/rear.TatryvPaWbvImmH'
++ Log 'End of program reached'
++ echo '2020-05-03 11:24:41.038368764 End of program reached'
2020-05-03 11:24:41.038368764 End of program reached
+ for exit_task in "${EXIT_TASKS[@]}"
+ Debug 'Exit task '\''(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details'\'' 1>&8'\'''
+ test 1
+ Log 'Exit task '\''(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details'\'' 1>&8'\'''
+ echo '2020-05-03 11:24:41.039948826 Exit task '\''(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details'\'' 1>&8'\'''
2020-05-03 11:24:41.039948826 Exit task '(( EXIT_FAIL_MESSAGE )) && echo 'rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details' 1>&8'
+ eval '(( EXIT_FAIL_MESSAGE )) && echo '\''rear mkrescue failed, check /var/log/rear/rear-cem01vfw.log for details'\'' 1>&8'
++ ((  EXIT_FAIL_MESSAGE  ))
+ for exit_task in "${EXIT_TASKS[@]}"
+ Debug 'Exit task '\''exec 8>&-'\'''
+ test 1
+ Log 'Exit task '\''exec 8>&-'\'''
+ echo '2020-05-03 11:24:41.041553861 Exit task '\''exec 8>&-'\'''
2020-05-03 11:24:41.041553861 Exit task 'exec 8>&-'
+ eval 'exec 8>&-'
++ exec
+ for exit_task in "${EXIT_TASKS[@]}"
+ Debug 'Exit task '\''exec 7>&-'\'''
+ test 1
+ Log 'Exit task '\''exec 7>&-'\'''
+ echo '2020-05-03 11:24:41.043256177 Exit task '\''exec 7>&-'\'''
2020-05-03 11:24:41.043256177 Exit task 'exec 7>&-'
+ eval 'exec 7>&-'
++ exec
+ for exit_task in "${EXIT_TASKS[@]}"
+ Debug 'Exit task '\''exec 6<&-'\'''
+ test 1
+ Log 'Exit task '\''exec 6<&-'\'''
+ echo '2020-05-03 11:24:41.044928905 Exit task '\''exec 6<&-'\'''
2020-05-03 11:24:41.044928905 Exit task 'exec 6<&-'
+ eval 'exec 6<&-'
++ exec
+ for exit_task in "${EXIT_TASKS[@]}"
+ Debug 'Exit task '\'''\'''
+ test 1
+ Log 'Exit task '\'''\'''
+ echo '2020-05-03 11:24:41.046553253 Exit task '\'''\'''
2020-05-03 11:24:41.046553253 Exit task ''
+ eval ''

jsmeix commented at 2020-05-07 08:27:

For me with BACKUP=NETFS and OUTPUT=ISO
on openSUSE Leap 15.1 I get an ISO that is 326M
where my ReaR initrd is 317M and my kernel is 7M
the rest is basically the bootloader of the ISO.

ReaR's initrd contains the whole ReaR recovery system
(the ReaR recovery system runs in a ramdisk)
so ReaR's initrd is bigger than a normal initrd.
See the section
"How disaster recovery with ReaR basically works" in
https://en.opensuse.org/SDB:Disaster_Recovery

This is the amount of the contents of my ReaR recovery system:

# du -hs /tmp/rear.CQLPT6iipW0tDyf/rootfs/
918M    /tmp/rear.CQLPT6iipW0tDyf/rootfs/

When you do not run rear in debug mode you need to explicitly
set KEEP_BUILD_DIR="yes" to keep the ReaR recovery system content,
see the KEEP_BUILD_DIR description in usr/share/rear/conf/default.conf
https://github.com/rear/rear/blob/master/usr/share/rear/conf/default.conf#L142

In your 3GB case something makes your ReaR recovery system huge.
To find out what that "something" is you need to to inspect
your ReaR recovery system contents in your particular
$TMPDIR/rear.XXXXXXXXXXXXXXX/rootfs/
directory.

To be able to call the restore tool during "rear recover"
the restore tool and all what it needs to run
(libraries, config files, whatever else)
must be included in the ReaR recovery system
where "rear recover" is run, see the section
"Relax-and-Recover versus backup and restore" in
https://en.opensuse.org/SDB:Disaster_Recovery

With BACKUP=NETFS the backup and restore tool is tar.
When you use a third-party backup tool like BACKUP=DUPLICITY
what it needs to run could be much more than what tar needs
so with third-party backup tools the size of the ReaR recovery system
could be more compared to what BACKUP=NETFS with tar needs.
so 347M for a ReaR recovery system with BACKUP=DUPLICITY
looks reasonable.

But 3GB for the ReaR recovery system is far too much
so something pulls in huge stuff in your other case
and you need to find out what that is in your particular case.

jsmeix commented at 2020-06-02 09:26:

Because "no news is good news" I assume the root cause was found
that made the ReaR recovery system so big in this particular case
so the issue can be closed.


[Export of Github issue for rear/rear.]