[ovirt-users] Issue when updating node to 4.2.4

Thursday, 12 July 2018

Error in the scripts as part of doing a yum update on the host:

/var/log/messages
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

/tmp/imgbased.log

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

How would i move forward on this?

Regards,
Callum

--

Callum Smith
Research Computing Core
Wellcome Trust Centre for Human Genetics
University of Oxford
e. callum@well.ox.ac.uk<mailto:callum@well.ox.ac.uk>

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

[ovirt-users] Issue when updating node to 4.2.4