Can't recover any backup and and can't install templates

Yorz · November 5, 2022, 3:03pm

Hello, I need help. I can’t recover any backup and and can’t install templates with qvm-template.
I’m not sure that new backups created after this breakage is
created correctly. I don’t have another ssd to reinstall QubesOS, so I need to fix problem without reinstalling.

I have read this topic on qubes forum and this on github, but no solution here.

qubes-backup-tool output when i try to restore any backup

→ Restoring notes…
Extracting data: 1.5 GiB to restore
Error while processing ‘/var/tmp/restore2211yqft/vm34/private.img.000’:
ERROR: unable to extract files for /var/tmp/restore2211yqft/vm34/private.img.000, tar output:
→ Done.
→ Please install updates for all the restored templates.
Finished successfully!
Please unmount your backup volume and cancel the file selection dialog.

AppVM are recovered and can be started, but data don’t recovered.
TemplateVMs do not start after recovering.

Output when installing any template with qvm-template. No matters which template:

$ qvm-template install --nogpgcheck qubes-template-archlinux.rpm
Installing template ‘archlinux-minimal’…
archlinux-minimal: Importing data
Traceback (most recent call last):
File “/usr/bin/qvm-template-postprocess”, line 5, in
sys.exit(main())
File “/usr/lib/python3.8/site-packages/qubesadmin/tools/qvm_template_postprocess.py”, line 430, in main
loop.run_until_complete(post_install(args))
File “/usr/lib64/python3.8/asyncio/base_events.py”, line 616, in run_until_complete
return future.result()
File “/usr/lib/python3.8/site-packages/qubesadmin/tools/qvm_template_postprocess.py”, line 296, in post_install
import_root_img(vm, args.dir)
File “/usr/lib/python3.8/site-packages/qubesadmin/tools/qvm_template_postprocess.py”, line 110, in import_root_img
raise qubesadmin.exc.QubesException(
qubesadmin.exc.QubesException: root.img extraction failed
ERROR: Command ‘[‘qvm-template-postprocess’, ‘–really’, ‘–no-installed-by-rpm’, ‘post-install’, ‘archlinux-minimal’, ‘/var/tmp/tmpzn8obmpb/var/lib/qubes/vm-templates/archlinux-minimal’]’ returned non-zero exit status 1.

No matters which template I try to install, it doesn’t work.

I tried to downgrade dom0 packages qubes-core-admin and python3-qubesadmin to previous version
(4.1-25-1 to 4.1-24-1 in my case) but it doesn’t help.
Disk space is enough, now I have 77 GB free space in dom0
Help me please.

Suspicious_Actions · November 5, 2022, 3:30pm

Let’s first start with the backup-restore problem, as this seems to be more pressing:

Try to run it in the terminal in dom0 with the --verbose flag activated. Maybe there will be more telling output. You may want to specify what should happen in case of a name conflict, for example by skipping or renaming the qubes, see here.

Further how much space do you have in your pools? You can display this by clicking on the HDD Symbol (or the alert symbol) in your bar.

Yorz · November 5, 2022, 5:09pm

I’m not create additional pools.
In varlibqubes 131GB of 209GB is used (63%).

Here is output when restore my old, 100% correctly created backup (I restore in the past) of alpine TemplateVMs. but when I restore it now, it can’t boot, same as other Templates.

[{username}@dom0 Desktop]$ qvm-backup-restore --verbose -d file-mgmt /media/user/{Path_fragment}/qubes-backup-alpine
Please enter the passphrase to verify and (if encrypted) decrypt the backup: 
2022-11-05 19:44:52,864 [MainProcess qvm_backup_restore.main:271] app: Checking backup content...
2022-11-05 19:44:52,872 [MainProcess restore._start_retrieval_process:1021] qubesadmin.backup: Run command ['/usr/libexec/qubes/qfile-dom0-unpacker', '1000', '/var/tmp/restore_ao6qlrt', '-v', '-w', '524288000']
2022-11-05 19:44:55,770 [MainProcess restore._retrieve_backup_header:1267] qubesadmin.backup: Got backup header and hmac: backup-header, backup-header.hmac
2022-11-05 19:44:55,770 [MainProcess restore._verify_hmac:1084] qubesadmin.backup: Verifying file backup-header
2022-11-05 19:44:59,516 [MainProcess restore._start_retrieval_process:1021] qubesadmin.backup: Run command ['/usr/libexec/qubes/qfile-dom0-unpacker', '1000', '/var/tmp/restore_ao6qlrt', '-v', '-w', '524288000']
2022-11-05 19:45:02,847 [MainProcess restore._start_inner_extraction_worker:1317] qubesadmin.backup: Starting extraction worker in /var/tmp/restore_ao6qlrt, file handlers map: {'qubes.xml': (functools.partial(<function BackupRestore._save_qubes_xml at 0x7828652d6dc0>, '/var/tmp/restore_ao6qlrt/qubes-restored.xml'), None)}
2022-11-05 19:45:02,852 [ExtractWorker3-1 restore.__run__:609] qubesadmin.backup.extract: Started sending thread
2022-11-05 19:45:02,852 [ExtractWorker3-1 restore.__run__:610] qubesadmin.backup.extract: Moving to dir /var/tmp/restore_ao6qlrt
2022-11-05 19:45:02,852 [ExtractWorker3-1 restore.__run__:622] qubesadmin.backup.extract: Extracting file qubes.xml.000
2022-11-05 19:45:02,852 [ExtractWorker3-1 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'qubes.xml']
2022-11-05 19:45:02,861 [ExtractWorker3-1 restore.__run__:756] qubesadmin.backup.extract: Removing file qubes.xml.000
2022-11-05 19:45:02,864 [ExtractWorker3-1 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:02,864 [ExtractWorker3-1 restore.__run__:768] qubesadmin.backup.extract: Finished extracting thread
2022-11-05 19:45:02,870 [MainProcess restore.get_restore_info:1690] qubesadmin.backup: alpine is included in backup
2022-11-05 19:45:02,870 [MainProcess restore.get_restore_info:1690] qubesadmin.backup: alpine-inst-w-repos is included in backup
2022-11-05 19:45:02,870 [MainProcess restore.get_restore_info:1690] qubesadmin.backup: alpine-installed is included in backup
2022-11-05 19:45:02,871 [MainProcess restore.get_restore_info:1690] qubesadmin.backup: alpinelinux is included in backup
The following VMs are included in the backup:

---------------------+------------+----------+---------+-------+
                name |       type | template |   netvm | label |
---------------------+------------+----------+---------+-------+
              alpine | TemplateVM |      n/a |       - | black |
 alpine-inst-w-repos | TemplateVM |      n/a |       - | black |
    alpine-installed | TemplateVM |      n/a | sys-net | black |
         alpinelinux | TemplateVM |      n/a | sys-net | black |

2022-11-05 19:45:02,872 [MainProcess qvm_backup_restore.handle_broken:158] app: The above VMs will be copied and added to your system.
2022-11-05 19:45:02,872 [MainProcess qvm_backup_restore.handle_broken:159] app: Exisiting VMs will NOT be removed.
Do you want to proceed? [y/N] y
2022-11-05 19:45:16,731 [MainProcess restore._restore_vms_metadata:2008] qubesadmin.backup: -> Restoring alpine...
2022-11-05 19:45:17,192 [MainProcess restore._restore_vms_metadata:2008] qubesadmin.backup: -> Restoring alpine-inst-w-repos...
2022-11-05 19:45:17,648 [MainProcess restore._restore_vms_metadata:2008] qubesadmin.backup: -> Restoring alpine-installed...
2022-11-05 19:45:18,104 [MainProcess restore._restore_vms_metadata:2008] qubesadmin.backup: -> Restoring alpinelinux...
2022-11-05 19:45:23,782 [MainProcess restore._restore_vm_data:1404] qubesadmin.backup: Working in temporary dir: /var/tmp/restore_ao6qlrt
2022-11-05 19:45:23,782 [MainProcess restore._restore_vm_data:1405] qubesadmin.backup: Extracting data: 3.3 GiB to restore
2022-11-05 19:45:23,789 [MainProcess restore._start_retrieval_process:1021] qubesadmin.backup: Run command ['/usr/libexec/qubes/qfile-dom0-unpacker', '1000', '/var/tmp/restore_ao6qlrt', '-v', '-w', '524288000']
2022-11-05 19:45:23,792 [MainProcess restore._start_inner_extraction_worker:1317] qubesadmin.backup: Starting extraction worker in /var/tmp/restore_ao6qlrt, file handlers map: {'vm53/root.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06d0>, <qubesadmin.storage.Volume object at 0x782864e0f1c0>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06d0>, <qubesadmin.storage.Volume object at 0x782864e0f1c0>)), 'vm53/private.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06d0>, <qubesadmin.storage.Volume object at 0x782864e0f1f0>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06d0>, <qubesadmin.storage.Volume object at 0x782864e0f1f0>)), 'vm53/firewall.xml': (functools.partial(<bound method Core3VM.handle_firewall_xml of <qubesadmin.backup.core3.Core3VM object at 0x782864ec3040>>, <qubesadmin.vm.QubesVM object at 0x782864ed06d0>), None), 'vm53/whitelisted-appmenus.list': (functools.partial(<bound method BackupRestore._handle_appmenus_list of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06d0>), None), 'vm60/root.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed04f0>, <qubesadmin.storage.Volume object at 0x782864e0f280>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed04f0>, <qubesadmin.storage.Volume object at 0x782864e0f280>)), 'vm60/private.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed04f0>, <qubesadmin.storage.Volume object at 0x782864e0f2b0>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed04f0>, <qubesadmin.storage.Volume object at 0x782864e0f2b0>)), 'vm60/firewall.xml': (functools.partial(<bound method Core3VM.handle_firewall_xml of <qubesadmin.backup.core3.Core3VM object at 0x782864ec33d0>>, <qubesadmin.vm.QubesVM object at 0x782864ed04f0>), None), 'vm60/whitelisted-appmenus.list': (functools.partial(<bound method BackupRestore._handle_appmenus_list of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed04f0>), None), 'vm59/root.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed0910>, <qubesadmin.storage.Volume object at 0x782864e0f340>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed0910>, <qubesadmin.storage.Volume object at 0x782864e0f340>)), 'vm59/private.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed0910>, <qubesadmin.storage.Volume object at 0x782864e0f3a0>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed0910>, <qubesadmin.storage.Volume object at 0x782864e0f3a0>)), 'vm59/firewall.xml': (functools.partial(<bound method Core3VM.handle_firewall_xml of <qubesadmin.backup.core3.Core3VM object at 0x782864ec3430>>, <qubesadmin.vm.QubesVM object at 0x782864ed0910>), None), 'vm59/whitelisted-appmenus.list': (functools.partial(<bound method BackupRestore._handle_appmenus_list of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed0910>), None), 'vm50/root.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06a0>, <qubesadmin.storage.Volume object at 0x782864e0f430>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06a0>, <qubesadmin.storage.Volume object at 0x782864e0f430>)), 'vm50/private.img': (functools.partial(<bound method BackupRestore._handle_volume_data of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06a0>, <qubesadmin.storage.Volume object at 0x782864e0f4c0>), functools.partial(<bound method BackupRestore._handle_volume_size of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06a0>, <qubesadmin.storage.Volume object at 0x782864e0f4c0>)), 'vm50/firewall.xml': (functools.partial(<bound method Core3VM.handle_firewall_xml of <qubesadmin.backup.core3.Core3VM object at 0x782864ec3400>>, <qubesadmin.vm.QubesVM object at 0x782864ed06a0>), None), 'vm50/whitelisted-appmenus.list': (functools.partial(<bound method BackupRestore._handle_appmenus_list of <qubesadmin.backup.restore.BackupRestore object at 0x782864dc7af0>>, <qubesadmin.vm.QubesVM object at 0x782864ed06a0>), None)}
2022-11-05 19:45:23,796 [ExtractWorker3-2 restore.__run__:609] qubesadmin.backup.extract: Started sending thread
2022-11-05 19:45:23,797 [ExtractWorker3-2 restore.__run__:610] qubesadmin.backup.extract: Moving to dir /var/tmp/restore_ao6qlrt
2022-11-05 19:45:24,960 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/root.img.000.enc
2022-11-05 19:45:29,305 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/root.img.000
2022-11-05 19:45:29,305 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/root.img.001.enc
2022-11-05 19:45:29,306 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm50/root.img']
2022-11-05 19:45:29,603 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:29,603 [ExtractWorker3-2 restore.check_processes:604] qubesadmin.backup.extract: Error while processing '/var/tmp/restore_ao6qlrt/vm50/root.img.000': 
2022-11-05 19:45:29,603 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:29,603 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm50/root.img.000, tar output:
  
2022-11-05 19:45:29,603 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm50/root.img.000
2022-11-05 19:45:33,561 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/root.img.002.enc
2022-11-05 19:45:33,561 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/root.img.001
2022-11-05 19:45:37,646 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/private.img.000.enc
2022-11-05 19:45:37,647 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/root.img.002
2022-11-05 19:45:41,623 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/private.img.000
2022-11-05 19:45:41,623 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/private.img.001.enc
2022-11-05 19:45:41,623 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm50/private.img']
2022-11-05 19:45:41,896 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:41,897 [ExtractWorker3-2 restore.check_processes:604] qubesadmin.backup.extract: Error while processing '/var/tmp/restore_ao6qlrt/vm50/private.img.000': 
2022-11-05 19:45:41,897 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:41,897 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm50/private.img.000, tar output:
  
2022-11-05 19:45:41,897 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm50/private.img.000
2022-11-05 19:45:43,849 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/private.img.002.enc
2022-11-05 19:45:43,850 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/private.img.001
2022-11-05 19:45:47,776 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm50/firewall.xml.000.enc
2022-11-05 19:45:47,777 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/private.img.002
2022-11-05 19:45:51,054 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm50/firewall.xml.000
2022-11-05 19:45:51,054 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm50/firewall.xml']
2022-11-05 19:45:51,054 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm59/root.img.000.enc
2022-11-05 19:45:51,061 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm50/firewall.xml.000
2022-11-05 19:45:55,044 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm59/private.img.000.enc
2022-11-05 19:45:55,047 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm59/root.img.000
2022-11-05 19:45:55,062 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:55,062 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm59/root.img']
2022-11-05 19:45:55,295 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:55,295 [ExtractWorker3-2 restore.check_processes:604] qubesadmin.backup.extract: Error while processing '/var/tmp/restore_ao6qlrt/vm59/root.img.000': 
2022-11-05 19:45:55,295 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:45:55,295 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm59/root.img.000, tar output:
  
2022-11-05 19:45:55,295 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm59/root.img.000
2022-11-05 19:45:58,766 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm59/firewall.xml.000.enc
2022-11-05 19:45:58,766 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm59/private.img.000
2022-11-05 19:45:58,767 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm59/private.img']
2022-11-05 19:45:58,773 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm59/private.img.000
2022-11-05 19:46:02,520 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm60/root.img.000.enc
2022-11-05 19:46:02,521 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm59/firewall.xml.000
2022-11-05 19:46:02,742 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:02,743 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm59/private.img.000, tar output:
  
2022-11-05 19:46:02,743 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm59/firewall.xml']
2022-11-05 19:46:02,749 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm59/firewall.xml.000
2022-11-05 19:46:06,529 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm60/root.img.001.enc
2022-11-05 19:46:06,530 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm60/root.img.000
2022-11-05 19:46:06,548 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:06,548 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm60/root.img']
2022-11-05 19:46:06,777 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:06,778 [ExtractWorker3-2 restore.check_processes:604] qubesadmin.backup.extract: Error while processing '/var/tmp/restore_ao6qlrt/vm60/root.img.000': 
2022-11-05 19:46:06,778 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:06,778 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm60/root.img.000, tar output:
  
2022-11-05 19:46:06,778 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm60/root.img.000
2022-11-05 19:46:09,400 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm60/private.img.000.enc
2022-11-05 19:46:09,401 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm60/root.img.001
2022-11-05 19:46:12,676 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm60/private.img.000
2022-11-05 19:46:12,676 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm60/private.img']
2022-11-05 19:46:12,676 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm60/firewall.xml.000.enc
2022-11-05 19:46:12,683 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm60/private.img.000
2022-11-05 19:46:14,185 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.000.enc
2022-11-05 19:46:14,185 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm60/firewall.xml.000
2022-11-05 19:46:14,412 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:14,412 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm60/private.img.000, tar output:
  
2022-11-05 19:46:14,412 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm60/firewall.xml']
2022-11-05 19:46:14,419 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm60/firewall.xml.000
2022-11-05 19:46:18,179 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.001.enc
2022-11-05 19:46:18,180 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.000
2022-11-05 19:46:18,207 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:18,207 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm53/root.img']
2022-11-05 19:46:18,439 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:18,439 [ExtractWorker3-2 restore.check_processes:604] qubesadmin.backup.extract: Error while processing '/var/tmp/restore_ao6qlrt/vm53/root.img.000': 
2022-11-05 19:46:18,439 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:18,439 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm53/root.img.000, tar output:
  
2022-11-05 19:46:18,439 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm53/root.img.000
2022-11-05 19:46:22,318 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.002.enc
2022-11-05 19:46:22,318 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.001
2022-11-05 19:46:26,284 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.003.enc
2022-11-05 19:46:26,285 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.002
2022-11-05 19:46:30,254 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.004.enc
2022-11-05 19:46:30,255 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.003
2022-11-05 19:46:34,665 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.005.enc
2022-11-05 19:46:34,665 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.004
2022-11-05 19:46:38,635 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/root.img.006.enc
2022-11-05 19:46:38,635 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.005
2022-11-05 19:46:42,099 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/private.img.000.enc
2022-11-05 19:46:42,100 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/root.img.006
2022-11-05 19:46:44,939 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: vm53/firewall.xml.000.enc
2022-11-05 19:46:44,939 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/private.img.000
2022-11-05 19:46:44,939 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm53/private.img']
2022-11-05 19:46:44,948 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm53/private.img.000
2022-11-05 19:46:48,863 [MainProcess restore._restore_vm_data:1439] qubesadmin.backup: Getting new file: 
2022-11-05 19:46:48,863 [ExtractWorker3-2 restore.__run__:622] qubesadmin.backup.extract: Extracting file /var/tmp/restore_ao6qlrt/vm53/firewall.xml.000
2022-11-05 19:46:48,864 [MainProcess restore._restore_vm_data:1542] qubesadmin.backup: Waiting for the extraction process to finish...
2022-11-05 19:46:48,864 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:48,864 [ExtractWorker3-2 restore.cleanup_tar2:498] qubesadmin.backup.extract: ERROR: unable to extract files for /var/tmp/restore_ao6qlrt/vm53/private.img.000, tar output:
  
2022-11-05 19:46:48,864 [ExtractWorker3-2 restore.__run__:674] qubesadmin.backup.extract: Running command ['tar', '-xvvO', '--use-compress-program=gzip ', 'vm53/firewall.xml']
2022-11-05 19:46:48,871 [ExtractWorker3-2 restore.__run__:756] qubesadmin.backup.extract: Removing file /var/tmp/restore_ao6qlrt/vm53/firewall.xml.000
2022-11-05 19:46:48,888 [ExtractWorker3-2 restore.collect_tar_output:434] qubesadmin.backup.extract: tar2_stderr: 
2022-11-05 19:46:48,888 [ExtractWorker3-2 restore.__run__:768] qubesadmin.backup.extract: Finished extracting thread
2022-11-05 19:46:48,889 [MainProcess restore._restore_vm_data:1544] qubesadmin.backup: Extraction process finished with code: 0
2022-11-05 19:46:48,890 [MainProcess restore.restore_do:1973] qubesadmin.backup: -> Done.
2022-11-05 19:46:48,890 [MainProcess restore.restore_do:1975] qubesadmin.backup: -> Please install updates for all the restored templates.

Suspicious_Actions · November 15, 2022, 10:48am

The tar error output is not particularly helpful…
Are there any error messages in your dom0s journal when trying to start the template?

BEBF738VD · November 15, 2022, 10:54am

Welcome aboard

github.com/QubesOS/qubes-issues

tar fails to extract root.img from template.rpm

opened 04:28PM - 07 Jan 22 UTC

closed 04:28PM - 22 Nov 22 UTC

sajkbflksadbfkasdjf

T: bug C: templates P: default r4.1-dom0-stable diagnosed pr submitted r4.2-host-cur-test affects-4.1

### Qubes OS release 4.1 with all updates applied (subscribed to testing) ### …Brief summary Qubes fails to install templates because the root.img extraction fails ### Steps to reproduce Install template (I installed from commandline with `dnf install ./qubes-template-...` ### Expected behavior Template is installed correctly ### Actual behavior ```archlinux: Importing data tar: root.img: Cannot seek to 212860928: Broken pipe tar: Exiting with failure status due to previous errors Traceback (most recent call last): File "/bin/qvm-template-postprocess", line 5, in <module> sys.exit(main()) File "/usr/lib/python3.8/site-packages/qubesadmin/tools/qvm_template_postprocess.py", line 431, in main loop.run_until_complete(post_install(args)) File "/usr/lib64/python3.8/asyncio/base_events.py", line 616, in run_until_complete return future.result() File "/usr/lib/python3.8/site-packages/qubesadmin/tools/qvm_template_postprocess.py", line 297, in post_install import_root_img(vm, args.dir) File "/usr/lib/python3.8/site-packages/qubesadmin/tools/qvm_template_postprocess.py", line 109, in import_root_img raise qubesadmin.exc.QubesException('root.img extraction failed') qubesadmin.exc.QubesException: root.img extraction failed warning: %post(qubes-template-archlinux-4.0.6-202201062107.noarch) scriptlet failed, exit status 1 Error in POSTIN scriptlet in rpm package qubes-template-archlinux Running scriptlet: qubes-template-archlinux-4.0.6-202201062107.noarch 2/2 Cleanup : qubes-template-archlinux-4.0.6-202201062107.noarch 2/2 Verifying : qubes-template-archlinux-4.0.6-202201062107.noarch 1/2 Verifying : qubes-template-archlinux-4.0.6-202201062107.noarch 2/2 ``` This has been tested with self-built arch template and the gentoo template I downloaded manually and moved to dom0 to install. Can't confirm it happens with "normal" installs from qvm-template too.

BEBF738VD · November 16, 2022, 1:19pm

@Yorz did you remove vim from dom0?
If you did, try running, in dom0, sudo qubes-dom0-update -y vim

Then try again qvm-template install <template>

enmus · November 20, 2022, 6:14pm

I followed your original topic on this, and if this work for @Yorz it would be one of the most fantastic findings I faced here, hahaha. How did you even get an idea…?