Package: vmdb2
Version: 0.19-1
Severity: normal
Dear Maintainer,
When I put "qemu-debootstrap" after "virtual-filesystems"
in a vmdb yaml file, vmdb2 fails without meaningful error messages.
I observed this symptom the first attached yaml file on my amd64 laptop.
A log with vmdb2 --log= is attached as the second attachment.
If I delete "virtual-filesystems", everything goes fine.
Best regards, Ryutaroh Matsumoto
-- System Information:
Debian Release: bullseye/sid
APT prefers testing
APT policy: (990, 'testing'), (500, 'unstable'), (1, 'experimental')
Architecture: amd64 (x86_64)
Kernel: Linux 5.9.0-2-amd64 (SMP w/12 CPU threads)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8),
LANGUAGE=en_US:en
Shell: /bin/sh linked to /usr/bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled
Versions of packages vmdb2 depends on:
ii cmdtest 0.32.14.gcdfe14e-1
ii debootstrap 1.0.123
ii kpartx 0.8.4-4
ii parted 3.3-4
ii python3 3.8.6-1
ii python3-cliapp 1.20180812.1-4
ii python3-jinja2 2.11.2-1
ii python3-yaml 5.3.1-3
ii qemu-utils 1:5.1+dfsg-4+b1
Versions of packages vmdb2 recommends:
ii ansible 2.9.13+dfsg-1
vmdb2 suggests no packages.
-- no debconf information
# This is modified from the sample VMDB2 input file that specifies a simple
system for
# a PC that boots with UEFI.
steps:
- mkimg: "{{ output }}"
size: 4G
- mklabel: gpt
device: "{{ output }}"
- mkpart: primary
device: "{{ output }}"
start: 0%
end: 1G
tag: efi
- mkpart: primary
device: "{{ output }}"
start: 1G
end: 100%
tag: /
- kpartx: "{{ output }}"
- mkfs: vfat
partition: efi
- mkfs: ext4
partition: /
- mount: /
- virtual-filesystems: /
- qemu-debootstrap: buster
arch: i386
mirror: http://deb.debian.org/debian
target: /
2020-11-18 12:47:06 INFO vmdb2 version 0.19 starts
2020-11-18 12:47:06 DEBUG sys.argv: ['/bin/vmdb2', '--log=vmdb-qemu-error.txt',
'-v', '--output=/tmp/test1.img', 'uefi-qemu-with-virtual.vmdb']
2020-11-18 12:47:06 DEBUG current working directory: /root
2020-11-18 12:47:06 DEBUG uid: 0
2020-11-18 12:47:06 DEBUG effective uid: 0
2020-11-18 12:47:06 DEBUG gid: 0
2020-11-18 12:47:06 DEBUG effective gid: 0
2020-11-18 12:47:06 DEBUG environment variables:
2020-11-18 12:47:06 DEBUG environment: SHELL=/bin/bash
2020-11-18 12:47:06 DEBUG environment:
SESSION_MANAGER=local/bullseye-gnome:@/tmp/.ICE-unix/1843,unix/bullseye-gnome:/tmp/.ICE-unix/1843
2020-11-18 12:47:06 DEBUG environment: QT_ACCESSIBILITY=1
2020-11-18 12:47:06 DEBUG environment: COLORTERM=truecolor
2020-11-18 12:47:06 DEBUG environment: XDG_MENU_PREFIX=gnome-
2020-11-18 12:47:06 DEBUG environment:
GNOME_DESKTOP_SESSION_ID=this-is-deprecated
2020-11-18 12:47:06 DEBUG environment: LANGUAGE=en_US:en
2020-11-18 12:47:06 DEBUG environment: SSH_AUTH_SOCK=/run/user/1000/keyring/ssh
2020-11-18 12:47:06 DEBUG environment: XMODIFIERS=@im=ibus
2020-11-18 12:47:06 DEBUG environment: DESKTOP_SESSION=gnome-xorg
2020-11-18 12:47:06 DEBUG environment: SSH_AGENT_PID=1800
2020-11-18 12:47:06 DEBUG environment: GTK_MODULES=gail:atk-bridge
2020-11-18 12:47:06 DEBUG environment: PWD=/root
2020-11-18 12:47:06 DEBUG environment: XDG_SESSION_DESKTOP=gnome-xorg
2020-11-18 12:47:06 DEBUG environment: LOGNAME=ryutaroh
2020-11-18 12:47:06 DEBUG environment: XDG_SESSION_TYPE=x11
2020-11-18 12:47:06 DEBUG environment:
GPG_AGENT_INFO=/run/user/1000/gnupg/S.gpg-agent:0:1
2020-11-18 12:47:06 DEBUG environment: XAUTHORITY=/run/user/1000/gdm/Xauthority
2020-11-18 12:47:06 DEBUG environment: WINDOWPATH=2
2020-11-18 12:47:06 DEBUG environment: GDM_LANG=en_US.UTF-8
2020-11-18 12:47:06 DEBUG environment: HOME=/root
2020-11-18 12:47:06 DEBUG environment: USERNAME=ryutaroh
2020-11-18 12:47:06 DEBUG environment: IM_CONFIG_PHASE=1
2020-11-18 12:47:06 DEBUG environment: LANG=en_US.UTF-8
2020-11-18 12:47:06 DEBUG environment:
LS_COLORS=rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=00:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arc=01;31:*.arj=01;31:*.taz=01;31:*.lha=01;31:*.lz4=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.tzo=01;31:*.t7z=01;31:*.zip=01;31:*.z=01;31:*.dz=01;31:*.gz=01;31:*.lrz=01;31:*.lz=01;31:*.lzo=01;31:*.xz=01;31:*.zst=01;31:*.tzst=01;31:*.bz2=01;31:*.bz=01;31:*.tbz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.war=01;31:*.ear=01;31:*.sar=01;31:*.rar=01;31:*.alz=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.cab=01;31:*.wim=01;31:*.swm=01;31:*.dwm=01;31:*.esd=01;31:*.jpg=01;35:*.jpeg=01;35:*.mjpg=01;35:*.mjpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.webm=01;35:*.webp=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=00;36:*.au=00;36:*.flac=00;36:*.m4a=00;36:*.mid=00;36:*.midi=00;36:*.mka=00;36:*.mp3=00;36:*.mpc=00;36:*.ogg=00;36:*.ra=00;36:*.wav=00;36:*.oga=00;36:*.opus=00;36:*.spx=00;36:*.xspf=00;36:
2020-11-18 12:47:06 DEBUG environment: XDG_CURRENT_DESKTOP=GNOME
2020-11-18 12:47:06 DEBUG environment: VTE_VERSION=6201
2020-11-18 12:47:06 DEBUG environment:
GNOME_TERMINAL_SCREEN=/org/gnome/Terminal/screen/442ffa21_de84_48a8_9778_1e9737eeff0c
2020-11-18 12:47:06 DEBUG environment: https_proxy=http://192.168.1.2:3128/
2020-11-18 12:47:06 DEBUG environment: XDG_SESSION_CLASS=user
2020-11-18 12:47:06 DEBUG environment: TERM=xterm-256color
2020-11-18 12:47:06 DEBUG environment: USER=ryutaroh
2020-11-18 12:47:06 DEBUG environment: GNOME_TERMINAL_SERVICE=:1.77
2020-11-18 12:47:06 DEBUG environment: DISPLAY=:0
2020-11-18 12:47:06 DEBUG environment: SHLVL=2
2020-11-18 12:47:06 DEBUG environment: QT_IM_MODULE=ibus
2020-11-18 12:47:06 DEBUG environment: http_proxy=http://192.168.1.2:3128/
2020-11-18 12:47:06 DEBUG environment: XDG_RUNTIME_DIR=/run/user/1000
2020-11-18 12:47:06 DEBUG environment:
XDG_DATA_DIRS=/usr/share/gnome:/usr/local/share/:/usr/share/
2020-11-18 12:47:06 DEBUG environment:
PATH=/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/sbin:/usr/local/bin
2020-11-18 12:47:06 DEBUG environment: GDMSESSION=gnome-xorg
2020-11-18 12:47:06 DEBUG environment:
DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2020-11-18 12:47:06 DEBUG environment: MAIL=/var/mail/root
2020-11-18 12:47:06 DEBUG environment:
OLDPWD=/usr/lib/python3/dist-packages/vmdb/plugins
2020-11-18 12:47:06 DEBUG environment: _=/bin/vmdb2
2020-11-18 12:47:06 DEBUG Config:
[config]
output = /tmp/test1.img
log = vmdb-qemu-error.txt
log-level = debug
log-max = 0
log-keep = 10
log-mode = 0600
dump-memory-profile = simple
memory-dump-interval = 300
image =
verbose = True
rootfs-tarball =
size = 1073741824
2020-11-18 12:47:06 DEBUG Python version: 3.8.6 (default, Sep 25 2020,
09:36:53)
[GCC 10.2.0]
2020-11-18 12:47:06 INFO Load spec file uefi-qemu-with-virtual.vmdb
2020-11-18 12:47:06 INFO Running step: {'mkimg': '/tmp/test1.img', 'size': '4G'}
2020-11-18 12:47:06 INFO Calling <bound method MkimgStepRunner.run of
<mkimg_plugin.MkimgStepRunner object at 0x7ff969ac48b0>>
2020-11-18 12:47:06 INFO Exec: ['qemu-img', 'create', '-f', 'raw',
'/tmp/test1.img', '4G']
2020-11-18 12:47:06 DEBUG STDOUT: Formatting '/tmp/test1.img', fmt=raw
size=4294967296
2020-11-18 12:47:06 DEBUG STDERR:
2020-11-18 12:47:06 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mkimg_plugin.MkimgStepRunner object
at 0x7ff969ac48b0>>
2020-11-18 12:47:06 INFO Running step: {'mklabel': 'gpt', 'device':
'/tmp/test1.img'}
2020-11-18 12:47:06 INFO Calling <bound method MklabelStepRunner.run of
<mklabel_plugin.MklabelStepRunner object at 0x7ff969ac49d0>>
2020-11-18 12:47:06 INFO Exec: ['parted', '-s', '/tmp/test1.img', 'mklabel',
'gpt']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mklabel_plugin.MklabelStepRunner
object at 0x7ff969ac49d0>>
2020-11-18 12:47:07 INFO Running step: {'mkpart': 'primary', 'device':
'/tmp/test1.img', 'start': '0%', 'end': '1G', 'tag': 'efi'}
2020-11-18 12:47:07 INFO Calling <bound method MkpartStepRunner.run of
<mkpart_plugin.MkpartStepRunner object at 0x7ff969ac4a00>>
2020-11-18 12:47:07 INFO Exec: ['parted', '-m', '/tmp/test1.img', 'print']
2020-11-18 12:47:07 DEBUG STDOUT: BYT;
/tmp/test1.img:4295MB:file:512:512:gpt::;
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['parted', '-s', '/tmp/test1.img', 'mkpart',
'primary', 'ext2', '0%', '1G']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['parted', '-m', '/tmp/test1.img', 'print']
2020-11-18 12:47:07 DEBUG STDOUT: BYT;
/tmp/test1.img:4295MB:file:512:512:gpt::;
1:1049kB:1000MB:999MB::primary:;
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mkpart_plugin.MkpartStepRunner
object at 0x7ff969ac4a00>>
2020-11-18 12:47:07 INFO Running step: {'mkpart': 'primary', 'device':
'/tmp/test1.img', 'start': '1G', 'end': '100%', 'tag': '/'}
2020-11-18 12:47:07 INFO Calling <bound method MkpartStepRunner.run of
<mkpart_plugin.MkpartStepRunner object at 0x7ff969ac4a00>>
2020-11-18 12:47:07 INFO Exec: ['parted', '-m', '/tmp/test1.img', 'print']
2020-11-18 12:47:07 DEBUG STDOUT: BYT;
/tmp/test1.img:4295MB:file:512:512:gpt::;
1:1049kB:1000MB:999MB::primary:;
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['parted', '-s', '/tmp/test1.img', 'mkpart',
'primary', 'ext2', '1G', '100%']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['parted', '-m', '/tmp/test1.img', 'print']
2020-11-18 12:47:07 DEBUG STDOUT: BYT;
/tmp/test1.img:4295MB:file:512:512:gpt::;
1:1049kB:1000MB:999MB::primary:;
2:1000MB:4294MB:3294MB::primary:;
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mkpart_plugin.MkpartStepRunner
object at 0x7ff969ac4a00>>
2020-11-18 12:47:07 INFO Running step: {'kpartx': '/tmp/test1.img'}
2020-11-18 12:47:07 INFO Calling <bound method KpartxStepRunner.run of
<kpartx_plugin.KpartxStepRunner object at 0x7ff969ac47f0>>
2020-11-18 12:47:07 INFO Exec: ['kpartx', '-asv', '/tmp/test1.img']
2020-11-18 12:47:07 DEBUG STDOUT: add map loop0p1 (254:0): 0 1951744 linear 7:0
2048
add map loop0p2 (254:1): 0 6432768 linear 7:0 1953792
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO remembering /dev/mapper/loop0p1 as efi
2020-11-18 12:47:07 INFO remembering /dev/mapper/loop0p2 as /
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <kpartx_plugin.KpartxStepRunner
object at 0x7ff969ac47f0>>
2020-11-18 12:47:07 INFO Running step: {'mkfs': 'vfat', 'partition': 'efi'}
2020-11-18 12:47:07 INFO Calling <bound method MkfsStepRunner.run of
<mkfs_plugin.MkfsStepRunner object at 0x7ff969ac4880>>
2020-11-18 12:47:07 INFO Exec: ['/sbin/mkfs', '-t', 'vfat',
'/dev/mapper/loop0p1']
2020-11-18 12:47:07 DEBUG STDOUT: mkfs.fat 4.1 (2017-01-24)
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mkfs_plugin.MkfsStepRunner object
at 0x7ff969ac4880>>
2020-11-18 12:47:07 INFO Running step: {'mkfs': 'ext4', 'partition': '/'}
2020-11-18 12:47:07 INFO Calling <bound method MkfsStepRunner.run of
<mkfs_plugin.MkfsStepRunner object at 0x7ff969ac4880>>
2020-11-18 12:47:07 INFO Exec: ['/sbin/mkfs', '-t', 'ext4',
'/dev/mapper/loop0p2']
2020-11-18 12:47:07 DEBUG STDOUT: Discarding device blocks:
4096/804096 done
Creating filesystem with 804096 4k blocks and 201200 inodes
Filesystem UUID: cc8cf6c9-d4b1-4217-b7ba-5fe4398136ac
Superblock backups stored on blocks:
32768, 98304, 163840, 229376, 294912
Allocating group tables: 0/25 done
Writing inode tables: 0/25 done
Creating journal (16384 blocks): done
Writing superblocks and filesystem accounting information: 0/25
done
2020-11-18 12:47:07 DEBUG STDERR: mke2fs 1.45.6 (20-Mar-2020)
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mkfs_plugin.MkfsStepRunner object
at 0x7ff969ac4880>>
2020-11-18 12:47:07 INFO Running step: {'mount': '/'}
2020-11-18 12:47:07 INFO Calling <bound method MountStepRunner.run of
<mount_plugin.MountStepRunner object at 0x7ff969ac4a60>>
2020-11-18 12:47:07 INFO Exec: ['mount', '/dev/mapper/loop0p2',
'/tmp/tmpn_q0oc_h']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of <mount_plugin.MountStepRunner object
at 0x7ff969ac4a60>>
2020-11-18 12:47:07 INFO Running step: {'virtual-filesystems': '/'}
2020-11-18 12:47:07 INFO Calling <bound method
VirtualFilesystemMountStepRunner.run of
<virtualfs_plugin.VirtualFilesystemMountStepRunner object at 0x7ff969ac49a0>>
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'proc', 'none',
'/tmp/tmpn_q0oc_h/.//proc']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'devtmpfs', 'none',
'/tmp/tmpn_q0oc_h/.//dev']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'devpts', 'none',
'/tmp/tmpn_q0oc_h/.//dev/pts']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'tmpfs', 'none',
'/tmp/tmpn_q0oc_h/.//dev/shm']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'tmpfs', 'none',
'/tmp/tmpn_q0oc_h/.//run']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'tmpfs', 'none',
'/tmp/tmpn_q0oc_h/.//run/lock']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 INFO Exec: ['mount', '-t', 'sysfs', 'none',
'/tmp/tmpn_q0oc_h/.//sys']
2020-11-18 12:47:07 DEBUG STDOUT:
2020-11-18 12:47:07 DEBUG STDERR:
2020-11-18 12:47:07 DEBUG mounted virtuals: ['/tmp/tmpn_q0oc_h/.//proc',
'/tmp/tmpn_q0oc_h/.//dev', '/tmp/tmpn_q0oc_h/.//dev/pts',
'/tmp/tmpn_q0oc_h/.//dev/shm', '/tmp/tmpn_q0oc_h/.//run',
'/tmp/tmpn_q0oc_h/.//run/lock', '/tmp/tmpn_q0oc_h/.//sys']
2020-11-18 12:47:07 INFO Calling <bound method
StepRunnerInterface.run_even_if_skipped of
<virtualfs_plugin.VirtualFilesystemMountStepRunner object at 0x7ff969ac49a0>>
2020-11-18 12:47:07 INFO Running step: {'qemu-debootstrap': 'buster', 'arch':
'i386', 'mirror': 'http://deb.debian.org/debian', 'target': '/'}
2020-11-18 12:47:07 INFO Calling <bound method QemuDebootstrapStepRunner.run of
<qemudebootstrap_plugin.QemuDebootstrapStepRunner object at 0x7ff969ac4a90>>
2020-11-18 12:47:07 INFO Exec: ['qemu-debootstrap', '--arch', 'i386',
'--variant', '-', '--components', 'main', 'buster', '/tmp/tmpn_q0oc_h',
'http://deb.debian.org/debian']
2020-11-18 12:47:56 DEBUG STDOUT: I: Target architecture can be executed
I: Retrieving InRelease
I: Checking Release signature
I: Valid Release signature (key id 6D33866EDD8FFA41C0143AEDDCC9EFBF77E11517)
I: Retrieving Packages
I: Validating Packages
I: Resolving dependencies of required packages...
I: Resolving dependencies of base packages...
I: Checking component main on http://deb.debian.org/debian...
I: Retrieving libacl1 2.2.53-4
I: Validating libacl1 2.2.53-4
I: Retrieving adduser 3.118
I: Validating adduser 3.118
I: Retrieving libapparmor1 2.13.2-10
I: Validating libapparmor1 2.13.2-10
I: Retrieving apt 1.8.2.1
I: Validating apt 1.8.2.1
I: Retrieving apt-utils 1.8.2.1
I: Validating apt-utils 1.8.2.1
I: Retrieving libapt-inst2.0 1.8.2.1
I: Validating libapt-inst2.0 1.8.2.1
I: Retrieving libapt-pkg5.0 1.8.2.1
I: Validating libapt-pkg5.0 1.8.2.1
I: Retrieving libargon2-1 0~20171227-0.2
I: Validating libargon2-1 0~20171227-0.2
I: Retrieving libattr1 1:2.4.48-4
I: Validating libattr1 1:2.4.48-4
I: Retrieving libaudit-common 1:2.8.4-3
I: Validating libaudit-common 1:2.8.4-3
I: Retrieving libaudit1 1:2.8.4-3
I: Validating libaudit1 1:2.8.4-3
I: Retrieving base-files 10.3+deb10u6
I: Validating base-files 10.3+deb10u6
I: Retrieving base-passwd 3.5.46
I: Validating base-passwd 3.5.46
I: Retrieving bash 5.0-4
I: Validating bash 5.0-4
I: Retrieving libdns-export1104 1:9.11.5.P4+dfsg-5.1+deb10u2
I: Validating libdns-export1104 1:9.11.5.P4+dfsg-5.1+deb10u2
I: Retrieving libisc-export1100 1:9.11.5.P4+dfsg-5.1+deb10u2
I: Validating libisc-export1100 1:9.11.5.P4+dfsg-5.1+deb10u2
I: Retrieving bsdmainutils 11.1.2+b1
I: Validating bsdmainutils 11.1.2+b1
I: Retrieving libbz2-1.0 1.0.6-9.2~deb10u1
I: Validating libbz2-1.0 1.0.6-9.2~deb10u1
I: Retrieving libdebconfclient0 0.249
I: Validating libdebconfclient0 0.249
I: Retrieving coreutils 8.30-3
I: Validating coreutils 8.30-3
I: Retrieving cpio 2.12+dfsg-9
I: Validating cpio 2.12+dfsg-9
I: Retrieving cron 3.0pl1-134+deb10u1
I: Validating cron 3.0pl1-134+deb10u1
I: Retrieving libcryptsetup12 2:2.1.0-5+deb10u2
I: Validating libcryptsetup12 2:2.1.0-5+deb10u2
I: Retrieving dash 0.5.10.2-5
I: Validating dash 0.5.10.2-5
I: Retrieving libdb5.3 5.3.28+dfsg1-0.5
I: Validating libdb5.3 5.3.28+dfsg1-0.5
I: Retrieving debconf 1.5.71
I: Validating debconf 1.5.71
I: Retrieving debconf-i18n 1.5.71
I: Validating debconf-i18n 1.5.71
I: Retrieving debian-archive-keyring 2019.1
I: Validating debian-archive-keyring 2019.1
I: Retrieving debianutils 4.8.6.1
I: Validating debianutils 4.8.6.1
I: Retrieving diffutils 1:3.7-3
I: Validating diffutils 1:3.7-3
I: Retrieving dmidecode 3.2-1
I: Validating dmidecode 3.2-1
I: Retrieving dpkg 1.19.7
I: Validating dpkg 1.19.7
I: Retrieving e2fsprogs 1.44.5-1+deb10u3
I: Validating e2fsprogs 1.44.5-1+deb10u3
I: Retrieving libcom-err2 1.44.5-1+deb10u3
I: Validating libcom-err2 1.44.5-1+deb10u3
I: Retrieving libext2fs2 1.44.5-1+deb10u3
I: Validating libext2fs2 1.44.5-1+deb10u3
I: Retrieving libss2 1.44.5-1+deb10u3
I: Validating libss2 1.44.5-1+deb10u3
I: Retrieving libelf1 0.176-1.1
I: Validating libelf1 0.176-1.1
I: Retrieving findutils 4.6.0+git+20190209-2
I: Validating findutils 4.6.0+git+20190209-2
I: Retrieving gcc-8-base 8.3.0-6
I: Validating gcc-8-base 8.3.0-6
I: Retrieving libgcc1 1:8.3.0-6
I: Validating libgcc1 1:8.3.0-6
I: Retrieving libstdc++6 8.3.0-6
I: Validating libstdc++6 8.3.0-6
I: Retrieving gdbm-l10n 1.18.1-4
I: Validating gdbm-l10n 1.18.1-4
I: Retrieving libc-bin 2.28-10
I: Validating libc-bin 2.28-10
I: Retrieving libc6 2.28-10
I: Validating libc6 2.28-10
I: Retrieving libgmp10 2:6.1.2+dfsg-4
I: Validating libgmp10 2:6.1.2+dfsg-4
I: Retrieving gpgv 2.2.12-1+deb10u1
I: Validating gpgv 2.2.12-1+deb10u1
I: Retrieving libgnutls30 3.6.7-4+deb10u5
I: Validating libgnutls30 3.6.7-4+deb10u5
I: Retrieving grep 3.3-1
I: Validating grep 3.3-1
I: Retrieving gzip 1.9-3
I: Validating gzip 1.9-3
I: Retrieving hostname 3.21
I: Validating hostname 3.21
I: Retrieving ifupdown 0.8.35
I: Validating ifupdown 0.8.35
I: Retrieving init 1.56+nmu1
I: Validating init 1.56+nmu1
I: Retrieving init-system-helpers 1.56+nmu1
I: Validating init-system-helpers 1.56+nmu1
I: Retrieving iproute2 4.20.0-2
I: Validating iproute2 4.20.0-2
I: Retrieving iptables 1.8.2-4
I: Validating iptables 1.8.2-4
I: Retrieving libip4tc0 1.8.2-4
I: Validating libip4tc0 1.8.2-4
I: Retrieving libip6tc0 1.8.2-4
I: Validating libip6tc0 1.8.2-4
I: Retrieving libiptc0 1.8.2-4
I: Validating libiptc0 1.8.2-4
I: Retrieving libxtables12 1.8.2-4
I: Validating libxtables12 1.8.2-4
I: Retrieving iputils-ping 3:20180629-2+deb10u1
I: Validating iputils-ping 3:20180629-2+deb10u1
I: Retrieving isc-dhcp-client 4.4.1-2
I: Validating isc-dhcp-client 4.4.1-2
I: Retrieving isc-dhcp-common 4.4.1-2
I: Validating isc-dhcp-common 4.4.1-2
I: Retrieving libjson-c3 0.12.1+ds-2+deb10u1
I: Validating libjson-c3 0.12.1+ds-2+deb10u1
I: Retrieving kmod 26-1
I: Validating kmod 26-1
I: Retrieving libkmod2 26-1
I: Validating libkmod2 26-1
I: Retrieving less 487-0.1+b1
I: Validating less 487-0.1+b1
I: Retrieving libbsd0 0.9.1-2
I: Validating libbsd0 0.9.1-2
I: Retrieving libcap-ng0 0.7.9-2
I: Validating libcap-ng0 0.7.9-2
I: Retrieving libcap2 1:2.25-2
I: Validating libcap2 1:2.25-2
I: Retrieving libcap2-bin 1:2.25-2
I: Validating libcap2-bin 1:2.25-2
I: Retrieving libestr0 0.1.10-2.1
I: Validating libestr0 0.1.10-2.1
I: Retrieving libfastjson4 0.99.8-2
I: Validating libfastjson4 0.99.8-2
I: Retrieving libffi6 3.2.1-9
I: Validating libffi6 3.2.1-9
I: Retrieving libgcrypt20 1.8.4-5
I: Validating libgcrypt20 1.8.4-5
I: Retrieving libgpg-error0 1.35-1
I: Validating libgpg-error0 1.35-1
I: Retrieving libidn11 1.33-2.2
I: Validating libidn11 1.33-2.2
I: Retrieving libidn2-0 2.0.5-1+deb10u1
I: Validating libidn2-0 2.0.5-1+deb10u1
I: Retrieving liblocale-gettext-perl 1.07-3+b4
I: Validating liblocale-gettext-perl 1.07-3+b4
I: Retrieving liblognorm5 2.0.5-1
I: Validating liblognorm5 2.0.5-1
I: Retrieving libmnl0 1.0.4-2
I: Validating libmnl0 1.0.4-2
I: Retrieving libnetfilter-conntrack3 1.0.7-1
I: Validating libnetfilter-conntrack3 1.0.7-1
I: Retrieving libnfnetlink0 1.0.1-3+b1
I: Validating libnfnetlink0 1.0.1-3+b1
I: Retrieving libnftnl11 1.1.2-2
I: Validating libnftnl11 1.1.2-2
I: Retrieving libseccomp2 2.3.3-4
I: Validating libseccomp2 2.3.3-4
I: Retrieving libselinux1 2.8-1+b1
I: Validating libselinux1 2.8-1+b1
I: Retrieving libsemanage-common 2.8-2
I: Validating libsemanage-common 2.8-2
I: Retrieving libsemanage1 2.8-2
I: Validating libsemanage1 2.8-2
I: Retrieving libsepol1 2.8-1
I: Validating libsepol1 2.8-1
I: Retrieving libtasn1-6 4.13-3
I: Validating libtasn1-6 4.13-3
I: Retrieving libtext-charwidth-perl 0.04-7.1+b1
I: Validating libtext-charwidth-perl 0.04-7.1+b1
I: Retrieving libtext-iconv-perl 1.7-5+b7
I: Validating libtext-iconv-perl 1.7-5+b7
I: Retrieving libtext-wrapi18n-perl 0.06-7.1
I: Validating libtext-wrapi18n-perl 0.06-7.1
I: Retrieving libunistring2 0.9.10-1
I: Validating libunistring2 0.9.10-1
I: Retrieving libzstd1 1.3.8+dfsg-3
I: Validating libzstd1 1.3.8+dfsg-3
I: Retrieving logrotate 3.14.0-4
I: Validating logrotate 3.14.0-4
I: Retrieving lsb-base 10.2019051400
I: Validating lsb-base 10.2019051400
I: Retrieving dmsetup 2:1.02.155-3
I: Validating dmsetup 2:1.02.155-3
I: Retrieving libdevmapper1.02.1 2:1.02.155-3
I: Validating libdevmapper1.02.1 2:1.02.155-3
I: Retrieving liblz4-1 1.8.3-1
I: Validating liblz4-1 1.8.3-1
I: Retrieving mawk 1.3.3-17+b3
I: Validating mawk 1.3.3-17+b3
I: Retrieving nano 3.2-3
I: Validating nano 3.2-3
I: Retrieving libncurses6 6.1+20181013-2+deb10u2
I: Validating libncurses6 6.1+20181013-2+deb10u2
I: Retrieving libncursesw6 6.1+20181013-2+deb10u2
I: Validating libncursesw6 6.1+20181013-2+deb10u2
I: Retrieving libtinfo6 6.1+20181013-2+deb10u2
I: Validating libtinfo6 6.1+20181013-2+deb10u2
I: Retrieving ncurses-base 6.1+20181013-2+deb10u2
I: Validating ncurses-base 6.1+20181013-2+deb10u2
I: Retrieving ncurses-bin 6.1+20181013-2+deb10u2
I: Validating ncurses-bin 6.1+20181013-2+deb10u2
I: Retrieving netbase 5.6
I: Validating netbase 5.6
I: Retrieving libhogweed4 3.4.1-1
I: Validating libhogweed4 3.4.1-1
I: Retrieving libnettle6 3.4.1-1
I: Validating libnettle6 3.4.1-1
I: Retrieving libnewt0.52 0.52.20-8
I: Validating libnewt0.52 0.52.20-8
I: Retrieving whiptail 0.52.20-8
I: Validating whiptail 0.52.20-8
I: Retrieving libssl1.1 1.1.1d-0+deb10u3
I: Validating libssl1.1 1.1.1d-0+deb10u3
I: Retrieving libp11-kit0 0.23.15-2
I: Validating libp11-kit0 0.23.15-2
I: Retrieving libpam-modules 1.3.1-5
I: Validating libpam-modules 1.3.1-5
I: Retrieving libpam-modules-bin 1.3.1-5
I: Validating libpam-modules-bin 1.3.1-5
I: Retrieving libpam-runtime 1.3.1-5
I: Validating libpam-runtime 1.3.1-5
I: Retrieving libpam0g 1.3.1-5
I: Validating libpam0g 1.3.1-5
I: Retrieving libpcre3 2:8.39-12
I: Validating libpcre3 2:8.39-12
I: Retrieving perl-base 5.28.1-6+deb10u1
I: Validating perl-base 5.28.1-6+deb10u1
I: Retrieving libpopt0 1.16-12
I: Validating libpopt0 1.16-12
I: Retrieving libprocps7 2:3.3.15-2
I: Validating libprocps7 2:3.3.15-2
I: Retrieving procps 2:3.3.15-2
I: Validating procps 2:3.3.15-2
I: Retrieving readline-common 7.0-5
I: Validating readline-common 7.0-5
I: Retrieving rsyslog 8.1901.0-1
I: Validating rsyslog 8.1901.0-1
I: Retrieving sed 4.7-1
I: Validating sed 4.7-1
I: Retrieving sensible-utils 0.0.12
I: Validating sensible-utils 0.0.12
I: Retrieving login 1:4.5-1.1
I: Validating login 1:4.5-1.1
I: Retrieving passwd 1:4.5-1.1
I: Validating passwd 1:4.5-1.1
I: Retrieving libslang2 2.3.2-2
I: Validating libslang2 2.3.2-2
I: Retrieving libsystemd0 241-7~deb10u4
I: Validating libsystemd0 241-7~deb10u4
I: Retrieving libudev1 241-7~deb10u4
I: Validating libudev1 241-7~deb10u4
I: Retrieving systemd 241-7~deb10u4
I: Validating systemd 241-7~deb10u4
I: Retrieving systemd-sysv 241-7~deb10u4
I: Validating systemd-sysv 241-7~deb10u4
I: Retrieving udev 241-7~deb10u4
I: Validating udev 241-7~deb10u4
I: Retrieving sysvinit-utils 2.93-8
I: Validating sysvinit-utils 2.93-8
I: Retrieving tar 1.30+dfsg-6
I: Validating tar 1.30+dfsg-6
I: Retrieving tasksel 3.53
I: Validating tasksel 3.53
I: Retrieving tasksel-data 3.53
I: Validating tasksel-data 3.53
I: Retrieving tzdata 2020a-0+deb10u1
I: Validating tzdata 2020a-0+deb10u1
I: Retrieving bsdutils 1:2.33.1-0.1
I: Validating bsdutils 1:2.33.1-0.1
I: Retrieving fdisk 2.33.1-0.1
I: Validating fdisk 2.33.1-0.1
I: Retrieving libblkid1 2.33.1-0.1
I: Validating libblkid1 2.33.1-0.1
I: Retrieving libfdisk1 2.33.1-0.1
I: Validating libfdisk1 2.33.1-0.1
I: Retrieving libmount1 2.33.1-0.1
I: Validating libmount1 2.33.1-0.1
I: Retrieving libsmartcols1 2.33.1-0.1
I: Validating libsmartcols1 2.33.1-0.1
I: Retrieving libuuid1 2.33.1-0.1
I: Validating libuuid1 2.33.1-0.1
I: Retrieving mount 2.33.1-0.1
I: Validating mount 2.33.1-0.1
I: Retrieving util-linux 2.33.1-0.1
I: Validating util-linux 2.33.1-0.1
I: Retrieving vim-common 2:8.1.0875-5
I: Validating vim-common 2:8.1.0875-5
I: Retrieving vim-tiny 2:8.1.0875-5
I: Validating vim-tiny 2:8.1.0875-5
I: Retrieving xxd 2:8.1.0875-5
I: Validating xxd 2:8.1.0875-5
I: Retrieving liblzma5 5.2.4-1
I: Validating liblzma5 5.2.4-1
I: Retrieving zlib1g 1:1.2.11.dfsg-1
I: Validating zlib1g 1:1.2.11.dfsg-1
I: Chosen extractor for .deb packages: dpkg-deb
I: Extracting libacl1...
I: Extracting adduser...
I: Extracting apt...
I: Extracting libapt-pkg5.0...
I: Extracting libattr1...
I: Extracting libaudit-common...
I: Extracting libaudit1...
I: Extracting base-files...
I: Extracting base-passwd...
I: Extracting bash...
I: Extracting libbz2-1.0...
I: Extracting libdebconfclient0...
I: Extracting coreutils...
I: Extracting dash...
I: Extracting libdb5.3...
I: Extracting debconf...
I: Extracting debian-archive-keyring...
I: Extracting debianutils...
I: Extracting diffutils...
I: Extracting dpkg...
I: Extracting e2fsprogs...
I: Extracting libcom-err2...
I: Extracting libext2fs2...
I: Extracting libss2...
I: Extracting findutils...
I: Extracting gcc-8-base...
I: Extracting libgcc1...
I: Extracting libstdc++6...
I: Extracting libc-bin...
I: Extracting libc6...
I: Extracting libgmp10...
I: Extracting gpgv...
I: Extracting libgnutls30...
I: Extracting grep...
I: Extracting gzip...
I: Extracting hostname...
I: Extracting init-system-helpers...
I: Extracting libcap-ng0...
I: Extracting libffi6...
I: Extracting libgcrypt20...
I: Extracting libgpg-error0...
I: Extracting libidn2-0...
I: Extracting libseccomp2...
I: Extracting libselinux1...
I: Extracting libsemanage-common...
I: Extracting libsemanage1...
I: Extracting libsepol1...
I: Extracting libtasn1-6...
I: Extracting libunistring2...
I: Extracting libzstd1...
I: Extracting liblz4-1...
I: Extracting mawk...
I: Extracting libncursesw6...
I: Extracting libtinfo6...
I: Extracting ncurses-base...
I: Extracting ncurses-bin...
I: Extracting libhogweed4...
I: Extracting libnettle6...
I: Extracting libp11-kit0...
I: Extracting libpam-modules...
I: Extracting libpam-modules-bin...
I: Extracting libpam-runtime...
I: Extracting libpam0g...
I: Extracting libpcre3...
I: Extracting perl-base...
I: Extracting sed...
I: Extracting login...
I: Extracting passwd...
I: Extracting libsystemd0...
I: Extracting libudev1...
I: Extracting sysvinit-utils...
I: Extracting tar...
I: Extracting tzdata...
I: Extracting bsdutils...
I: Extracting fdisk...
I: Extracting libblkid1...
I: Extracting libfdisk1...
I: Extracting libmount1...
I: Extracting libsmartcols1...
I: Extracting libuuid1...
I: Extracting mount...
I: Extracting util-linux...
I: Extracting liblzma5...
I: Extracting zlib1g...
2020-11-18 12:47:56 DEBUG STDERR: W: Host architecture might allow running
target architecture; disabling QEMU support
I: Running command: debootstrap --arch i386 --variant - --components main
buster /tmp/tmpn_q0oc_h http://deb.debian.org/debian
2020-11-18 12:47:56 ERROR Program failed: 1
Traceback (most recent call last):
File "/usr/lib/python3/dist-packages/vmdb/app.py", line 116, in
run_steps_helper
method(values, self.settings, state)
File "/usr/lib/python3/dist-packages/vmdb/plugins/qemudebootstrap_plugin.py",
line 68, in run
vmdb.runcmd(
File "/usr/lib/python3/dist-packages/vmdb/runcmd.py", line 58, in runcmd
raise RuncmdError("Program failed: {}".format(p.returncode))
vmdb.runcmd.RuncmdError: Program failed: 1
2020-11-18 12:47:56 ERROR RuncmdError('Program failed: 1')
Traceback (most recent call last):
File "/usr/lib/python3/dist-packages/vmdb/app.py", line 116, in
run_steps_helper
method(values, self.settings, state)
File "/usr/lib/python3/dist-packages/vmdb/plugins/qemudebootstrap_plugin.py",
line 68, in run
vmdb.runcmd(
File "/usr/lib/python3/dist-packages/vmdb/runcmd.py", line 58, in runcmd
raise RuncmdError("Program failed: {}".format(p.returncode))
vmdb.runcmd.RuncmdError: Program failed: 1
2020-11-18 12:47:56 INFO Something went wrong, cleaning up!
2020-11-18 12:47:56 INFO Running teardown: {'qemu-debootstrap': 'buster',
'arch': 'i386', 'mirror': 'http://deb.debian.org/debian', 'target': '/'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<qemudebootstrap_plugin.QemuDebootstrapStepRunner object at 0x7ff969ac4a90>>
2020-11-18 12:47:56 INFO Running teardown: {'virtual-filesystems': '/'}
2020-11-18 12:47:56 INFO Calling <bound method
VirtualFilesystemMountStepRunner.teardown of
<virtualfs_plugin.VirtualFilesystemMountStepRunner object at 0x7ff969ac49a0>>
2020-11-18 12:47:56 DEBUG unmounting virtuals: ['/tmp/tmpn_q0oc_h/.//proc',
'/tmp/tmpn_q0oc_h/.//dev', '/tmp/tmpn_q0oc_h/.//dev/pts',
'/tmp/tmpn_q0oc_h/.//dev/shm', '/tmp/tmpn_q0oc_h/.//run',
'/tmp/tmpn_q0oc_h/.//run/lock', '/tmp/tmpn_q0oc_h/.//sys']
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//sys and everything on
top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//sys
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//run/lock and
everything on top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//run/lock
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//run and everything on
top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//run
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//dev/shm and everything
on top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//dev/shm
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//dev/pts and everything
on top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//dev/pts
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//dev and everything on
top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//dev
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h/.//proc and everything on
top of it
2020-11-18 12:47:56 WARNING Not mounted: /tmp/tmpn_q0oc_h/.//proc
2020-11-18 12:47:56 INFO Running teardown: {'mount': '/'}
2020-11-18 12:47:56 INFO Calling <bound method MountStepRunner.teardown of
<mount_plugin.MountStepRunner object at 0x7ff969ac4a60>>
2020-11-18 12:47:56 DEBUG Unmounting /tmp/tmpn_q0oc_h and everything on top of
it
2020-11-18 12:47:56 DEBUG Finishd unmounting /tmp/tmpn_q0oc_h
2020-11-18 12:47:56 INFO Running teardown: {'mkfs': 'ext4', 'partition': '/'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<mkfs_plugin.MkfsStepRunner object at 0x7ff969ac4880>>
2020-11-18 12:47:56 INFO Running teardown: {'mkfs': 'vfat', 'partition': 'efi'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<mkfs_plugin.MkfsStepRunner object at 0x7ff969ac4880>>
2020-11-18 12:47:56 INFO Running teardown: {'kpartx': '/tmp/test1.img'}
2020-11-18 12:47:56 INFO Calling <bound method KpartxStepRunner.teardown of
<kpartx_plugin.KpartxStepRunner object at 0x7ff969ac47f0>>
2020-11-18 12:47:56 INFO Exec: ['kpartx', '-dsv', '/tmp/test1.img']
2020-11-18 12:47:56 DEBUG STDOUT: del devmap : loop0p2
del devmap : loop0p1
2020-11-18 12:47:56 DEBUG STDERR: loop deleted : /dev/loop0
2020-11-18 12:47:56 INFO Running teardown: {'mkpart': 'primary', 'device':
'/tmp/test1.img', 'start': '1G', 'end': '100%', 'tag': '/'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<mkpart_plugin.MkpartStepRunner object at 0x7ff969ac4a00>>
2020-11-18 12:47:56 INFO Running teardown: {'mkpart': 'primary', 'device':
'/tmp/test1.img', 'start': '0%', 'end': '1G', 'tag': 'efi'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<mkpart_plugin.MkpartStepRunner object at 0x7ff969ac4a00>>
2020-11-18 12:47:56 INFO Running teardown: {'mklabel': 'gpt', 'device':
'/tmp/test1.img'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<mklabel_plugin.MklabelStepRunner object at 0x7ff969ac49d0>>
2020-11-18 12:47:56 INFO Running teardown: {'mkimg': '/tmp/test1.img', 'size':
'4G'}
2020-11-18 12:47:56 INFO Calling <bound method StepRunnerInterface.teardown of
<mkimg_plugin.MkimgStepRunner object at 0x7ff969ac48b0>>
2020-11-18 12:47:56 ERROR An error occurred, exiting with non-zero exit code
