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