Jul 12 12:07:00 virtA003 imgbased: 2018-07-12 12:07:00,170 [INFO] (MainThread) Extracting image '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0.20180626.0.el7.squashfs.img'

Jul 12 12:07:00 virtA003 kernel: EXT4-fs (loop3): mounted filesystem with ordered data mode. Opts: (null)

Jul 12 12:07:00 virtA003 imgbased: 2018-07-12 12:07:00,295 [INFO] (MainThread) Starting base creation

Jul 12 12:07:00 virtA003 imgbased: 2018-07-12 12:07:00,295 [INFO] (MainThread) New base will be: ovirt-node-ng-4.2.4-0.20180626.0

Jul 12 12:07:00 virtA003 python: detected unhandled Python exception in '/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/__main__.py'

Jul 12 12:07:00 virtA003 abrt-server: Executable '/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/__main__.py' doesn't belong to any package and ProcessUnpackaged is set to 'no'

Jul 12 12:07:00 virtA003 abrt-server: 'post-create' on '/var/tmp/abrt/Python-2018-07-12-12:07:00-4688' exited with 1

Jul 12 12:07:00 virtA003 abrt-server: Deleting problem directory '/var/tmp/abrt/Python-2018-07-12-12:07:00-4688'

Jul 12 12:07:00 virtA003 yum[4558]: Updated: ovirt-node-ng-image-update-4.2.4-1.el7.noarch

2018-07-12 12:07:00,166 [DEBUG] (MainThread) Version: imgbased-1.0.20

2018-07-12 12:07:00,170 [DEBUG] (MainThread) Arguments: Namespace(FILENAME='/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0.20180626.0.el7.squashfs.img', command='update', debug=True, experimental=False, format='liveimg', stream='Image')

2018-07-12 12:07:00,170 [INFO] (MainThread) Extracting image '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0.20180626.0.el7.squashfs.img'

2018-07-12 12:07:00,170 [DEBUG] (MainThread) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}

2018-07-12 12:07:00,170 [DEBUG] (MainThread) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,173 [DEBUG] (MainThread) Returned: /tmp/mnt.qTRik

2018-07-12 12:07:00,173 [DEBUG] (MainThread) Calling binary: (['mount', '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0.20180626.0.el7.squashfs.img', u'/tmp/mnt.qTRik'],) {}

2018-07-12 12:07:00,174 [DEBUG] (MainThread) Calling: (['mount', '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0.20180626.0.el7.squashfs.img', u'/tmp/mnt.qTRik'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,180 [DEBUG] (MainThread) Returned:

2018-07-12 12:07:00,180 [DEBUG] (MainThread) Mounted squashfs

2018-07-12 12:07:00,180 [DEBUG] (MainThread) Found fsimage at '/tmp/mnt.qTRik/LiveOS/rootfs.img'

2018-07-12 12:07:00,180 [DEBUG] (MainThread) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}

2018-07-12 12:07:00,181 [DEBUG] (MainThread) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,184 [DEBUG] (MainThread) Returned: /tmp/mnt.SIk9q

2018-07-12 12:07:00,184 [DEBUG] (MainThread) Calling binary: (['mount', u'/tmp/mnt.qTRik/LiveOS/rootfs.img', u'/tmp/mnt.SIk9q'],) {}

2018-07-12 12:07:00,184 [DEBUG] (MainThread) Calling: (['mount', u'/tmp/mnt.qTRik/LiveOS/rootfs.img', u'/tmp/mnt.SIk9q'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,199 [DEBUG] (MainThread) Returned:

2018-07-12 12:07:00,212 [DEBUG] (MainThread) Using nvr: ovirt-node-ng-4.2.4-0.20180626.0

2018-07-12 12:07:00,212 [DEBUG] (MainThread) Fetching image for '/'

2018-07-12 12:07:00,212 [DEBUG] (MainThread) Calling binary: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {}

2018-07-12 12:07:00,212 [DEBUG] (MainThread) Calling: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,217 [DEBUG] (MainThread) Returned: /dev/mapper/onn_virta003-ovirt--node--ng--4.2.3.1--0.20180530.0+1

2018-07-12 12:07:00,218 [DEBUG] (MainThread) Found '/dev/mapper/onn_virta003-ovirt--node--ng--4.2.3.1--0.20180530.0+1'

2018-07-12 12:07:00,218 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-ovg_name,lv_name', u'/dev/mapper/onn_virta003-ovirt--node--ng--4.2.3.1--0.20180530.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,218 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-ovg_name,lv_name', u'/dev/mapper/onn_virta003-ovirt--node--ng--4.2.3.1--0.20180530.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,248 [DEBUG] (MainThread) Returned: onn_virta003 ovirt-node-ng-4.2.3.1-0.20180530.0+1

2018-07-12 12:07:00,248 [DEBUG] (MainThread) Found LV for path /dev/mapper/onn_virta003-ovirt--node--ng--4.2.3.1--0.20180530.0+1: onn_virta003 ovirt-node-ng-4.2.3.1-0.20180530.0+1

2018-07-12 12:07:00,248 [DEBUG] (MainThread) Found LV 'ovirt-node-ng-4.2.3.1-0.20180530.0+1' for path '/dev/mapper/onn_virta003-ovirt--node--ng--4.2.3.1--0.20180530.0+1'

2018-07-12 12:07:00,249 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,249 [DEBUG] (MainThread) Calling: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,271 [DEBUG] (MainThread) Returned: onn_virta003

2018-07-12 12:07:00,271 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-osize', '--units', 'B', u'onn_virta003/ovirt-node-ng-4.2.3.1-0.20180530.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,271 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-osize', '--units', 'B', u'onn_virta003/ovirt-node-ng-4.2.3.1-0.20180530.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,294 [DEBUG] (MainThread) Returned: 349121282048B

2018-07-12 12:07:00,295 [DEBUG] (MainThread) Recommeneded base size: 349121282048B

2018-07-12 12:07:00,295 [INFO] (MainThread) Starting base creation

2018-07-12 12:07:00,295 [INFO] (MainThread) New base will be: ovirt-node-ng-4.2.4-0.20180626.0

2018-07-12 12:07:00,295 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,295 [DEBUG] (MainThread) Calling: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7f5e75cdbf60>}

2018-07-12 12:07:00,318 [DEBUG] (MainThread) Returned: onn_virta003/pool00

2018-07-12 12:07:00,318 [DEBUG] (MainThread) Pool: <LV 'onn_virta003/pool00' />

2018-07-12 12:07:00,318 [DEBUG] (MainThread) Calling binary: (['lvcreate', '--thin', '--virtualsize', u'349121282048B', '--name', 'ovirt-node-ng-4.2.4-0.20180626.0', u'onn_virta003/pool00'],) {}

2018-07-12 12:07:00,318 [DEBUG] (MainThread) Calling: (['lvcreate', '--thin', '--virtualsize', u'349121282048B', '--name', 'ovirt-node-ng-4.2.4-0.20180626.0', u'onn_virta003/pool00'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,344 [DEBUG] (MainThread) Exception!   Logical Volume "ovirt-node-ng-4.2.4-0.20180626.0" already exists in volume group "onn_virta003"

2018-07-12 12:07:00,344 [DEBUG] (MainThread) Calling binary: (['umount', '-l', u'/tmp/mnt.SIk9q'],) {}

2018-07-12 12:07:00,344 [DEBUG] (MainThread) Calling: (['umount', '-l', u'/tmp/mnt.SIk9q'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,360 [DEBUG] (MainThread) Returned:

2018-07-12 12:07:00,360 [DEBUG] (MainThread) Calling binary: (['rmdir', u'/tmp/mnt.SIk9q'],) {}

2018-07-12 12:07:00,360 [DEBUG] (MainThread) Calling: (['rmdir', u'/tmp/mnt.SIk9q'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,364 [DEBUG] (MainThread) Returned:

2018-07-12 12:07:00,364 [DEBUG] (MainThread) Calling binary: (['umount', '-l', u'/tmp/mnt.qTRik'],) {}

2018-07-12 12:07:00,364 [DEBUG] (MainThread) Calling: (['umount', '-l', u'/tmp/mnt.qTRik'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,374 [DEBUG] (MainThread) Returned:

2018-07-12 12:07:00,374 [DEBUG] (MainThread) Calling binary: (['rmdir', u'/tmp/mnt.qTRik'],) {}

2018-07-12 12:07:00,374 [DEBUG] (MainThread) Calling: (['rmdir', u'/tmp/mnt.qTRik'],) {'close_fds': True, 'stderr': -2}

2018-07-12 12:07:00,377 [DEBUG] (MainThread) Returned:

Traceback (most recent call last):

  File "/usr/lib64/python2.7/runpy.py", line 162, in _run_module_as_main

    "__main__", fname, loader, pkg_name)

  File "/usr/lib64/python2.7/runpy.py", line 72, in _run_code

    exec code in run_globals

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/__main__.py", line 53, in <module>

    CliApplication()

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/__init__.py", line 82, in CliApplication

    app.hooks.emit("post-arg-parse", args)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/hooks.py", line 120, in emit

    cb(self.context, *args)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/plugins/update.py", line 56, in post_argparse

    base_lv, _ = LiveimgExtractor(app.imgbase).extract(args.FILENAME)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/plugins/update.py", line 118, in extract

    "%s" % size, nvr)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/plugins/update.py", line 84, in add_base_with_tree

    lvs)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/imgbase.py", line 310, in add_base

    new_base_lv = pool.create_thinvol(new_base.lv_name, size)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/lvm.py", line 324, in create_thinvol

    self.lvm_name])

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/utils.py", line 390, in lvcreate

    return self.call(["lvcreate"] + args, **kwargs)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/utils.py", line 378, in call

    stdout = call(*args, **kwargs)

  File "/tmp/tmp.awWEfeJStR/usr/lib/python2.7/site-packages/imgbased/utils.py", line 153, in call

    return subprocess.check_output(*args, **kwargs).strip()

  File "/usr/lib64/python2.7/subprocess.py", line 575, in check_output

    raise CalledProcessError(retcode, cmd, output=output)

subprocess.CalledProcessError: Command '['lvcreate', '--thin', '--virtualsize', u'349121282048B', '--name', 'ovirt-node-ng-4.2.4-0.20180626.0', u'onn_virta003/pool00']' returned non-zero exit status 5