Certainly, /tmp/imgbased.log is:
2018-08-18 02:49:47,528 [DEBUG] (MainThread) Version: imgbased-1.0.22
2018-08-18 02:49:47,534 [DEBUG] (MainThread) Arguments: Namespace(FILENAME='/usr/share/ovirt-node-ng/image// ovirt-node-ng-4.2.0-0. 20180731.0.el7.squashfs.img', command='update', debug=True, experimental=False, format='liveimg', stream='Image')
2018-08-18 02:49:47,534 [INFO] (MainThread) Extracting image '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0. 20180731.0.el7.squashfs.img'
2018-08-18 02:49:47,534 [DEBUG] (MainThread) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}
2018-08-18 02:49:47,534 [DEBUG] (MainThread) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:47,538 [DEBUG] (MainThread) Returned: /tmp/mnt.SHCgv
2018-08-18 02:49:47,539 [DEBUG] (MainThread) Calling binary: (['mount', '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0. 20180731.0.el7.squashfs.img', u'/tmp/mnt.SHCgv'],) {}
2018-08-18 02:49:47,539 [DEBUG] (MainThread) Calling: (['mount', '/usr/share/ovirt-node-ng/image//ovirt-node-ng-4.2.0-0. 20180731.0.el7.squashfs.img', u'/tmp/mnt.SHCgv'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:47,547 [DEBUG] (MainThread) Returned:
2018-08-18 02:49:47,548 [DEBUG] (MainThread) Mounted squashfs
2018-08-18 02:49:47,549 [DEBUG] (MainThread) Found fsimage at '/tmp/mnt.SHCgv/LiveOS/rootfs.img'
2018-08-18 02:49:47,549 [DEBUG] (MainThread) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}
2018-08-18 02:49:47,550 [DEBUG] (MainThread) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:47,553 [DEBUG] (MainThread) Returned: /tmp/mnt.gL7FO
2018-08-18 02:49:47,554 [DEBUG] (MainThread) Calling binary: (['mount', u'/tmp/mnt.SHCgv/LiveOS/rootfs.img', u'/tmp/mnt.gL7FO'],) {}
2018-08-18 02:49:47,554 [DEBUG] (MainThread) Calling: (['mount', u'/tmp/mnt.SHCgv/LiveOS/rootfs.img', u'/tmp/mnt.gL7FO'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:47,598 [DEBUG] (MainThread) Returned:
2018-08-18 02:49:47,610 [DEBUG] (MainThread) Using nvr: ovirt-node-ng-4.2.5.1-0.20180731.0
2018-08-18 02:49:47,611 [DEBUG] (MainThread) Fetching image for '/'
2018-08-18 02:49:47,611 [DEBUG] (MainThread) Calling binary: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {}
2018-08-18 02:49:47,611 [DEBUG] (MainThread) Calling: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:50,247 [DEBUG] (MainThread) Returned: /dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1
2018-08-18 02:49:50,248 [DEBUG] (MainThread) Found '/dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1'
2018-08-18 02:49:50,248 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-ovg_name,lv_name', u'/dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,248 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-ovg_name,lv_name', u'/dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,445 [DEBUG] (MainThread) Returned: onn ovirt-node-ng-4.2.4-0.20180626.0+1
2018-08-18 02:49:50,445 [DEBUG] (MainThread) Found LV for path /dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1: onn ovirt-node-ng-4.2.4-0. 20180626.0+1
2018-08-18 02:49:50,446 [DEBUG] (MainThread) Found LV 'ovirt-node-ng-4.2.4-0.20180626.0+1' for path '/dev/mapper/onn-ovirt--node-- ng--4.2.4--0.20180626.0+1'
2018-08-18 02:49:50,447 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,447 [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 0x7fc139daeed0>}
2018-08-18 02:49:50,481 [DEBUG] (MainThread) Returned: onn
2018-08-18 02:49:50,482 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-osize', '--units', 'B', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,482 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-osize', '--units', 'B', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,583 [DEBUG] (MainThread) Returned: 6442450944B
2018-08-18 02:49:50,584 [DEBUG] (MainThread) Recommeneded base size: 6442450944B
2018-08-18 02:49:50,584 [INFO] (MainThread) Starting base creation
2018-08-18 02:49:50,584 [INFO] (MainThread) New base will be: ovirt-node-ng-4.2.5.1-0.20180731.0
2018-08-18 02:49:50,585 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,585 [DEBUG] (MainThread) Calling: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,718 [DEBUG] (MainThread) Returned: onn/pool00
2018-08-18 02:49:50,719 [DEBUG] (MainThread) Pool: <LV 'onn/pool00' />
2018-08-18 02:49:50,720 [DEBUG] (MainThread) Calling binary: (['lvcreate', '--thin', '--virtualsize', u'6442450944B', '--name', 'ovirt-node-ng-4.2.5.1-0.20180731.0', u'onn/pool00'],) {}
2018-08-18 02:49:50,720 [DEBUG] (MainThread) Calling: (['lvcreate', '--thin', '--virtualsize', u'6442450944B', '--name', 'ovirt-node-ng-4.2.5.1-0.20180731.0', u'onn/pool00'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:50,878 [DEBUG] (MainThread) Returned: WARNING: Sum of all thin volume sizes (73.00 GiB) exceeds the size of thin pool onn/pool00 and the size of whole volume group (46.65 GiB).
Logical volume "ovirt-node-ng-4.2.5.1-0.20180731.0" created.
2018-08-18 02:49:50,879 [DEBUG] (MainThread) Calling binary: (['lvchange', '--addtag', 'imgbased:base', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,879 [DEBUG] (MainThread) Calling: (['lvchange', '--addtag', 'imgbased:base', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,963 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:49:50,963 [INFO] (MainThread) New LV is: <LV 'onn/ovirt-node-ng-4.2.5.1-0.20180731.0' />
2018-08-18 02:49:50,963 [DEBUG] (MainThread) Calling binary: (['lvchange', '--permission', 'r', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:50,963 [DEBUG] (MainThread) Calling: (['lvchange', '--permission', 'r', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,104 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:49:51,105 [DEBUG] (MainThread) Calling binary: (['lvchange', '--setactivationskip', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,105 [DEBUG] (MainThread) Calling: (['lvchange', '--setactivationskip', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,202 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:49:51,202 [DEBUG] (MainThread) Calling binary: (['lvchange', '--activate', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,202 [DEBUG] (MainThread) Calling: (['lvchange', '--activate', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,319 [DEBUG] (MainThread) Returned:
2018-08-18 02:49:51,320 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,320 [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 0x7fc139daeed0>}
2018-08-18 02:49:51,354 [DEBUG] (MainThread) Returned: onn
2018-08-18 02:49:51,355 [DEBUG] (MainThread) Calling binary: (['lvchange', '--permission', 'rw', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,355 [DEBUG] (MainThread) Calling: (['lvchange', '--permission', 'rw', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,440 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:49:51,440 [DEBUG] (MainThread) Calling binary: (['lvchange', '--setactivationskip', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,440 [DEBUG] (MainThread) Calling: (['lvchange', '--setactivationskip', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,527 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:49:51,527 [DEBUG] (MainThread) Calling binary: (['lvchange', '--activate', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,527 [DEBUG] (MainThread) Calling: (['lvchange', '--activate', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,597 [DEBUG] (MainThread) Returned:
2018-08-18 02:49:51,597 [INFO] (MainThread) Creating new filesystem on base
2018-08-18 02:49:51,597 [DEBUG] (MainThread) Calling binary: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {}
2018-08-18 02:49:51,597 [DEBUG] (MainThread) Calling: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:51,604 [DEBUG] (MainThread) Returned: /dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1
2018-08-18 02:49:51,652 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,652 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:51,686 [DEBUG] (MainThread) Returned: /dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0
2018-08-18 02:49:51,687 [DEBUG] (MainThread) Running: ['mkfs.ext4', '-E', 'discard', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '-q']
2018-08-18 02:49:51,687 [DEBUG] (MainThread) Calling: (['mkfs.ext4', '-E', 'discard', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '-q'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:52,945 [INFO] (MainThread) Writing tree to base
2018-08-18 02:49:52,946 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:52,946 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:49:53,113 [DEBUG] (MainThread) Returned: /dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0
2018-08-18 02:49:53,114 [DEBUG] (MainThread) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}
2018-08-18 02:49:53,114 [DEBUG] (MainThread) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:53,118 [DEBUG] (MainThread) Returned: /tmp/mnt.0X0kb
2018-08-18 02:49:53,118 [DEBUG] (MainThread) Calling binary: (['mount', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0', u'/tmp/mnt.0X0kb'],) {}
2018-08-18 02:49:53,118 [DEBUG] (MainThread) Calling: (['mount', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0', u'/tmp/mnt.0X0kb'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:49:53,129 [DEBUG] (MainThread) Returned:
2018-08-18 02:49:53,129 [DEBUG] (MainThread) Calling binary: ['tar', 'cf', '-', '--selinux', '--xattrs', '--acls', '--xattrs-include=*', '-C', u'/tmp/mnt.gL7FO', '.']
2018-08-18 02:49:53,132 [DEBUG] (MainThread) Calling binary: ['tar', 'xBf', '-', '--selinux', '--xattrs', '--acls', '--xattrs-include=*', '-C', u'/tmp/mnt.0X0kb/']
2018-08-18 02:50:52,740 [DEBUG] (MainThread) Done syncing new filesystem
2018-08-18 02:50:52,741 [DEBUG] (MainThread) Trying to copy prev fstab
2018-08-18 02:50:52,741 [DEBUG] (MainThread) Calling binary: (['umount', '-l', u'/tmp/mnt.0X0kb'],) {}
2018-08-18 02:50:52,742 [DEBUG] (MainThread) Calling: (['umount', '-l', u'/tmp/mnt.0X0kb'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:50:59,062 [DEBUG] (MainThread) Returned:
2018-08-18 02:50:59,063 [DEBUG] (MainThread) Calling binary: (['rmdir', u'/tmp/mnt.0X0kb'],) {}
2018-08-18 02:50:59,063 [DEBUG] (MainThread) Calling: (['rmdir', u'/tmp/mnt.0X0kb'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:50:59,072 [DEBUG] (MainThread) Returned:
2018-08-18 02:50:59,073 [DEBUG] (MainThread) Calling binary: (['lvchange', '--permission', 'r', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,073 [DEBUG] (MainThread) Calling: (['lvchange', '--permission', 'r', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,437 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:50:59,438 [DEBUG] (MainThread) Calling binary: (['lvchange', '--setactivationskip', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,438 [DEBUG] (MainThread) Calling: (['lvchange', '--setactivationskip', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,547 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:50:59,548 [DEBUG] (MainThread) Calling binary: (['lvchange', '--activate', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,548 [DEBUG] (MainThread) Calling: (['lvchange', '--activate', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,621 [DEBUG] (MainThread) Returned:
2018-08-18 02:50:59,621 [INFO] (MainThread) Adding a new layer after <Base ovirt-node-ng-4.2.5.1-0.20180731.0 [] />
2018-08-18 02:50:59,621 [INFO] (MainThread) Adding a new layer after <Base ovirt-node-ng-4.2.5.1-0.20180731.0 [] />
2018-08-18 02:50:59,622 [DEBUG] (MainThread) Basing new layer on previous: <Base ovirt-node-ng-4.2.5.1-0.20180731.0 [] />
2018-08-18 02:50:59,622 [DEBUG] (MainThread) Finding next layer based on <Base ovirt-node-ng-4.2.5.1-0.20180731.0 [] />
2018-08-18 02:50:59,622 [DEBUG] (MainThread) Suggesting for layer for base ovirt-node-ng-4.2.5.1-0.20180731.0
2018-08-18 02:50:59,622 [DEBUG] (MainThread) ... without layers
2018-08-18 02:50:59,622 [INFO] (MainThread) New layer will be: <Layer ovirt-node-ng-4.2.5.1-0.20180731.0+1 />
2018-08-18 02:50:59,622 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,623 [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 0x7fc139daeed0>}
2018-08-18 02:50:59,657 [DEBUG] (MainThread) Returned: onn
2018-08-18 02:50:59,657 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,657 [DEBUG] (MainThread) Calling: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,725 [DEBUG] (MainThread) Returned: onn/pool00
2018-08-18 02:50:59,725 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '--nosuffix', '--units', 'm', '-o', 'metadata_percent,lv_metadata_size', u'onn/pool00'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,726 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '--nosuffix', '--units', 'm', '-o', 'metadata_percent,lv_metadata_size', u'onn/pool00'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,939 [DEBUG] (MainThread) Returned: 0.64 1024.00
2018-08-18 02:50:59,939 [DEBUG] (MainThread) Pool: onn/pool00, metadata size=1024.0M (0.64%)
2018-08-18 02:50:59,940 [DEBUG] (MainThread) Calling binary: (['lvchange', '--activate', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:50:59,940 [DEBUG] (MainThread) Calling: (['lvchange', '--activate', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0', '--ignoreactivationskip'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,054 [DEBUG] (MainThread) Returned:
2018-08-18 02:51:00,054 [DEBUG] (MainThread) Calling binary: (['lvcreate', '--snapshot', '--name', 'ovirt-node-ng-4.2.5.1-0.20180731.0+1', u'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0'],) {}
2018-08-18 02:51:00,054 [DEBUG] (MainThread) Calling: (['lvcreate', '--snapshot', '--name', 'ovirt-node-ng-4.2.5.1-0.20180731.0+1', u'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:00,186 [DEBUG] (MainThread) Returned: WARNING: Sum of all thin volume sizes (79.00 GiB) exceeds the size of thin pool onn/pool00 and the size of whole volume group (46.65 GiB).
Logical volume "ovirt-node-ng-4.2.5.1-0.20180731.0+1" created.
2018-08-18 02:51:00,187 [DEBUG] (MainThread) Calling binary: (['lvchange', '--activate', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1', '--ignoreactivationskip'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,187 [DEBUG] (MainThread) Calling: (['lvchange', '--activate', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1', '--ignoreactivationskip'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,254 [DEBUG] (MainThread) Returned:
2018-08-18 02:51:00,254 [DEBUG] (MainThread) Calling binary: (['lvchange', '--addtag', 'imgbased:layer', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,254 [DEBUG] (MainThread) Calling: (['lvchange', '--addtag', 'imgbased:layer', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,338 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1 changed.
2018-08-18 02:51:00,339 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,339 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,423 [DEBUG] (MainThread) Returned: /dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1
2018-08-18 02:51:00,432 [DEBUG] (MainThread) Running: ['tune2fs', '-U', 'random', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1']
2018-08-18 02:51:00,432 [DEBUG] (MainThread) Calling: (['tune2fs', '-U', 'random', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:00,448 [DEBUG] (MainThread) Calling binary: (['lvchange', '--setactivationskip', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,448 [DEBUG] (MainThread) Calling: (['lvchange', '--setactivationskip', 'n', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,493 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1 changed.
2018-08-18 02:51:00,494 [DEBUG] (MainThread) Calling binary: (['lvchange', '--setactivationskip', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,494 [DEBUG] (MainThread) Calling: (['lvchange', '--setactivationskip', 'y', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,542 [DEBUG] (MainThread) Returned: Logical volume onn/ovirt-node-ng-4.2.5.1-0.20180731.0 changed.
2018-08-18 02:51:00,542 [DEBUG] (MainThread) Got: <LV 'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1' /> and <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0' />
2018-08-18 02:51:00,542 [DEBUG] (MainThread) Calling binary: (['vgchange', '-ay', '--select', 'vg_tags = imgbased:vg'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,542 [DEBUG] (MainThread) Calling: (['vgchange', '-ay', '--select', 'vg_tags = imgbased:vg'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,587 [DEBUG] (MainThread) Returned: 13 logical volume(s) in volume group "onn" now active
2018-08-18 02:51:00,588 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-o', 'lv_full_name', '--select', 'lv_tags = imgbased:base || lv_tags = imgbased:layer'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,588 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-o', 'lv_full_name', '--select', 'lv_tags = imgbased:base || lv_tags = imgbased:layer'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:00,623 [DEBUG] (MainThread) Returned: onn/ovirt-node-ng-4.2.2-0.20180405.0
onn/ovirt-node-ng-4.2.2-0.20180405.0+1
onn/ovirt-node-ng-4.2.4-0.20180626.0
onn/ovirt-node-ng-4.2.4-0.20180626.0+1
onn/ovirt-node-ng-4.2.5.1-0.20180731.0
onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1
2018-08-18 02:51:00,623 [DEBUG] (MainThread) All LV names: [u'onn/ovirt-node-ng-4.2.2-0.20180405.0', u'onn/ovirt-node-ng-4.2.2-0. 20180405.0+1', u'onn/ovirt-node-ng-4.2.4-0. 20180626.0', u'onn/ovirt-node-ng-4.2.4-0. 20180626.0+1', u'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0', u'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0+1']
2018-08-18 02:51:00,623 [DEBUG] (MainThread) All LVS: [<LV 'onn/ovirt-node-ng-4.2.2-0.20180405.0' />, <LV 'onn/ovirt-node-ng-4.2.2-0. 20180405.0+1' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0+1' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0+1' />]
2018-08-18 02:51:00,624 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,624 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,673 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,673 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,673 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,713 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,714 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,714 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,748 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,748 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,748 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,797 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,797 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,798 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,841 [DEBUG] (MainThread) Returned: imgbased:base
2018-08-18 02:51:00,841 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,841 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,877 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,877 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,878 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,919 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,920 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,920 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,953 [DEBUG] (MainThread) Returned: imgbased:base
2018-08-18 02:51:00,953 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,954 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,988 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:00,988 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:00,989 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360e90c0>}
2018-08-18 02:51:01,036 [DEBUG] (MainThread) Returned: imgbased:layer
2018-08-18 02:51:01,037 [DEBUG] (MainThread) Our LVS: [<LV 'onn/ovirt-node-ng-4.2.2-0.20180405.0' />, <LV 'onn/ovirt-node-ng-4.2.2-0. 20180405.0+1' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0+1' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0+1' />]
2018-08-18 02:51:01,037 [DEBUG] (MainThread) Names: [u'ovirt-node-ng-4.2.2-0.20180405.0', u'ovirt-node-ng-4.2.2-0. 20180405.0+1', u'ovirt-node-ng-4.2.4-0. 20180626.0', u'ovirt-node-ng-4.2.4-0. 20180626.0+1', u'ovirt-node-ng-4.2.5.1-0. 20180731.0', u'ovirt-node-ng-4.2.5.1-0. 20180731.0+1']
2018-08-18 02:51:01,037 [DEBUG] (MainThread) Images: [<Base ovirt-node-ng-4.2.2-0.20180405.0 [] />, <Layer ovirt-node-ng-4.2.2-0. 20180405.0+1 />, <Base ovirt-node-ng-4.2.4-0. 20180626.0 [] />, <Layer ovirt-node-ng-4.2.4-0. 20180626.0+1 />, <Base ovirt-node-ng-4.2.5.1-0. 20180731.0 [] />, <Layer ovirt-node-ng-4.2.5.1-0. 20180731.0+1 />]
2018-08-18 02:51:01,037 [DEBUG] (MainThread) Bases: [<Base ovirt-node-ng-4.2.4-0.20180626.0 [<Layer ovirt-node-ng-4.2.4-0. 20180626.0+1 />] />, <Base ovirt-node-ng-4.2.5.1-0. 20180731.0 [<Layer ovirt-node-ng-4.2.5.1-0. 20180731.0+1 />] />, <Base ovirt-node-ng-4.2.2-0. 20180405.0 [<Layer ovirt-node-ng-4.2.2-0. 20180405.0+1 />] />]
2018-08-18 02:51:01,038 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,038 [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 0x7fc139daeed0>}
2018-08-18 02:51:01,086 [DEBUG] (MainThread) Returned: onn
2018-08-18 02:51:01,086 [DEBUG] (MainThread) Fetching image for '/'
2018-08-18 02:51:01,086 [DEBUG] (MainThread) Calling binary: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {}
2018-08-18 02:51:01,087 [DEBUG] (MainThread) Calling: (['findmnt', '--noheadings', '-o', 'SOURCE', '/'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,093 [DEBUG] (MainThread) Returned: /dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1
2018-08-18 02:51:01,094 [DEBUG] (MainThread) Found '/dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1'
2018-08-18 02:51:01,094 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-ovg_name,lv_name', u'/dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,094 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-ovg_name,lv_name', u'/dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,129 [DEBUG] (MainThread) Returned: onn ovirt-node-ng-4.2.4-0.20180626.0+1
2018-08-18 02:51:01,130 [DEBUG] (MainThread) Found LV for path /dev/mapper/onn-ovirt--node--ng--4.2.4--0.20180626.0+1: onn ovirt-node-ng-4.2.4-0. 20180626.0+1
2018-08-18 02:51:01,130 [DEBUG] (MainThread) Found LV 'ovirt-node-ng-4.2.4-0.20180626.0+1' for path '/dev/mapper/onn-ovirt--node-- ng--4.2.4--0.20180626.0+1'
2018-08-18 02:51:01,130 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,131 [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 0x7fc139daeed0>}
2018-08-18 02:51:01,171 [DEBUG] (MainThread) Returned: onn
2018-08-18 02:51:01,172 [DEBUG] (MainThread) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,172 [DEBUG] (MainThread) Calling: (['vgs', '--noheadings', '--ignoreskippedcluster', '@imgbased:pool', '-o', 'lv_full_name'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,224 [DEBUG] (MainThread) Returned: onn/pool00
2018-08-18 02:51:01,224 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '--separator', '$', '-o', 'lv_profile', u'onn/pool00'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,224 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '--separator', '$', '-o', 'lv_profile', u'onn/pool00'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,264 [DEBUG] (MainThread) Returned: imgbased-pool
2018-08-18 02:51:01,265 [DEBUG] (MainThread) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,265 [DEBUG] (MainThread) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,300 [DEBUG] (MainThread) Returned: /dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1
2018-08-18 02:51:01,300 [DEBUG] (MainThread) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}
2018-08-18 02:51:01,300 [DEBUG] (MainThread) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,304 [DEBUG] (MainThread) Returned: /tmp/mnt.Kq1LK
2018-08-18 02:51:01,305 [DEBUG] (MainThread) Calling binary: (['mount', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1', u'/tmp/mnt.Kq1LK'],) {}
2018-08-18 02:51:01,305 [DEBUG] (MainThread) Calling: (['mount', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1', u'/tmp/mnt.Kq1LK'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,319 [DEBUG] (MainThread) Returned:
2018-08-18 02:51:01,320 [DEBUG] (MainThread) Calling binary: (['mknod', '/tmp/mnt.Kq1LK/dev/urandom', 'c', '1', '9'],) {}
2018-08-18 02:51:01,320 [DEBUG] (MainThread) Calling: (['mknod', '/tmp/mnt.Kq1LK/dev/urandom', 'c', '1', '9'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,326 [DEBUG] (MainThread) Returned:
2018-08-18 02:51:01,326 [DEBUG] (MainThread) Calling binary: (['umount', '-l', u'/tmp/mnt.Kq1LK'],) {}
2018-08-18 02:51:01,327 [DEBUG] (MainThread) Calling: (['umount', '-l', u'/tmp/mnt.Kq1LK'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,348 [DEBUG] (MainThread) Returned:
2018-08-18 02:51:01,348 [DEBUG] (MainThread) Calling binary: (['rmdir', u'/tmp/mnt.Kq1LK'],) {}
2018-08-18 02:51:01,348 [DEBUG] (MainThread) Calling: (['rmdir', u'/tmp/mnt.Kq1LK'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,351 [DEBUG] (MainThread) Returned:
2018-08-18 02:51:01,354 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-o', 'lv_full_name', '--select', 'lv_tags = imgbased:base || lv_tags = imgbased:layer'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,354 [DEBUG] (migrate_var) Syncing items present in the new /var which are not present in the existing FS
2018-08-18 02:51:01,354 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-o', 'lv_full_name', '--select', 'lv_tags = imgbased:base || lv_tags = imgbased:layer'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,355 [DEBUG] (migrate_var) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daef60>}
2018-08-18 02:51:01,355 [DEBUG] (migrate_var) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daef60>}
2018-08-18 02:51:01,400 [DEBUG] (migrate_var) Returned: /dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1
2018-08-18 02:51:01,401 [DEBUG] (migrate_var) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}
2018-08-18 02:51:01,401 [DEBUG] (migrate_var) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,404 [DEBUG] (migrate_var) Returned: /tmp/mnt.dpqLx
2018-08-18 02:51:01,405 [DEBUG] (migrate_var) Calling binary: (['mount', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1', u'/tmp/mnt.dpqLx'],) {}
2018-08-18 02:51:01,405 [DEBUG] (remediate_etc) Returned: onn/ovirt-node-ng-4.2.2-0.20180405.0
onn/ovirt-node-ng-4.2.2-0.20180405.0+1
onn/ovirt-node-ng-4.2.4-0.20180626.0
onn/ovirt-node-ng-4.2.4-0.20180626.0+1
onn/ovirt-node-ng-4.2.5.1-0.20180731.0
onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1
2018-08-18 02:51:01,405 [DEBUG] (migrate_var) Calling: (['mount', u'/dev/onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1', u'/tmp/mnt.dpqLx'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,406 [DEBUG] (remediate_etc) All LV names: [u'onn/ovirt-node-ng-4.2.2-0.20180405.0', u'onn/ovirt-node-ng-4.2.2-0. 20180405.0+1', u'onn/ovirt-node-ng-4.2.4-0. 20180626.0', u'onn/ovirt-node-ng-4.2.4-0. 20180626.0+1', u'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0', u'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0+1']
2018-08-18 02:51:01,407 [DEBUG] (remediate_etc) All LVS: [<LV 'onn/ovirt-node-ng-4.2.2-0.20180405.0' />, <LV 'onn/ovirt-node-ng-4.2.2-0. 20180405.0+1' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0+1' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0+1' />]
2018-08-18 02:51:01,408 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,408 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,421 [DEBUG] (migrate_var) Returned:
2018-08-18 02:51:01,451 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,451 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,451 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,504 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,505 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,505 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,523 [DEBUG] (migrate_var) Copying /tmp/mnt.dpqLx//var/tmp/yum-root-xcKanx to /var/tmp/yum-root-xcKanx
2018-08-18 02:51:01,525 [DEBUG] (migrate_var) Copying /tmp/mnt.dpqLx//var/tmp/sssd_is_running to /var/tmp/sssd_is_running
2018-08-18 02:51:01,535 [DEBUG] (migrate_var) Copying /tmp/mnt.dpqLx//var/lib/ngn-vdsm-need-configure to /var/lib/ngn-vdsm-need- configure
2018-08-18 02:51:01,553 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,553 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,553 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,595 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,596 [DEBUG] (migrate_var) Calling binary: (['umount', '-l', u'/tmp/mnt.dpqLx'],) {}
2018-08-18 02:51:01,597 [DEBUG] (migrate_var) Calling: (['umount', '-l', u'/tmp/mnt.dpqLx'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,597 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,597 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,628 [DEBUG] (migrate_var) Returned:
2018-08-18 02:51:01,629 [DEBUG] (migrate_var) Calling binary: (['rmdir', u'/tmp/mnt.dpqLx'],) {}
2018-08-18 02:51:01,629 [DEBUG] (migrate_var) Calling: (['rmdir', u'/tmp/mnt.dpqLx'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,633 [DEBUG] (migrate_var) Returned:
2018-08-18 02:51:01,647 [DEBUG] (remediate_etc) Returned: imgbased:base
2018-08-18 02:51:01,647 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,647 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,684 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,684 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,685 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.4-0.20180626.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,719 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,720 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,720 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,761 [DEBUG] (remediate_etc) Returned: imgbased:base
2018-08-18 02:51:01,761 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,761 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,796 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,797 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,797 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_tags', u'onn/ovirt-node-ng-4.2.5.1-0.20180731.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc1360ee0c0>}
2018-08-18 02:51:01,845 [DEBUG] (remediate_etc) Returned: imgbased:layer
2018-08-18 02:51:01,845 [DEBUG] (remediate_etc) Our LVS: [<LV 'onn/ovirt-node-ng-4.2.2-0.20180405.0' />, <LV 'onn/ovirt-node-ng-4.2.2-0. 20180405.0+1' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0' />, <LV 'onn/ovirt-node-ng-4.2.4-0. 20180626.0+1' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0' />, <LV 'onn/ovirt-node-ng-4.2.5.1-0. 20180731.0+1' />]
2018-08-18 02:51:01,845 [DEBUG] (remediate_etc) Names: [u'ovirt-node-ng-4.2.2-0.20180405.0', u'ovirt-node-ng-4.2.2-0. 20180405.0+1', u'ovirt-node-ng-4.2.4-0. 20180626.0', u'ovirt-node-ng-4.2.4-0. 20180626.0+1', u'ovirt-node-ng-4.2.5.1-0. 20180731.0', u'ovirt-node-ng-4.2.5.1-0. 20180731.0+1']
2018-08-18 02:51:01,846 [DEBUG] (remediate_etc) Images: [<Base ovirt-node-ng-4.2.2-0.20180405.0 [] />, <Layer ovirt-node-ng-4.2.2-0. 20180405.0+1 />, <Base ovirt-node-ng-4.2.4-0. 20180626.0 [] />, <Layer ovirt-node-ng-4.2.4-0. 20180626.0+1 />, <Base ovirt-node-ng-4.2.5.1-0. 20180731.0 [] />, <Layer ovirt-node-ng-4.2.5.1-0. 20180731.0+1 />]
2018-08-18 02:51:01,846 [DEBUG] (remediate_etc) Bases: [<Base ovirt-node-ng-4.2.4-0.20180626.0 [<Layer ovirt-node-ng-4.2.4-0. 20180626.0+1 />] />, <Base ovirt-node-ng-4.2.5.1-0. 20180731.0 [<Layer ovirt-node-ng-4.2.5.1-0. 20180731.0+1 />] />, <Base ovirt-node-ng-4.2.2-0. 20180405.0 [<Layer ovirt-node-ng-4.2.2-0. 20180405.0+1 />] />]
2018-08-18 02:51:01,846 [DEBUG] (remediate_etc) Checking ovirt-node-ng-4.2.2-0.20180405.0+1
2018-08-18 02:51:01,847 [DEBUG] (remediate_etc) Calling binary: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,847 [DEBUG] (remediate_etc) Calling: (['vgs', '--noheadings', '--ignoreskippedcluster', '--select', 'vg_tags = imgbased:vg', '-o', 'vg_name'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,889 [DEBUG] (remediate_etc) Returned: onn
2018-08-18 02:51:01,890 [DEBUG] (remediate_etc) Calling binary: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,890 [DEBUG] (remediate_etc) Calling: (['lvs', '--noheadings', '--ignoreskippedcluster', '-olv_path', u'onn/ovirt-node-ng-4.2.2-0.20180405.0+1'],) {'close_fds': True, 'stderr': <open file '/dev/null', mode 'w' at 0x7fc139daeed0>}
2018-08-18 02:51:01,923 [DEBUG] (remediate_etc) Returned: /dev/onn/ovirt-node-ng-4.2.2-0.20180405.0+1
2018-08-18 02:51:01,924 [DEBUG] (remediate_etc) Calling binary: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {}
2018-08-18 02:51:01,924 [DEBUG] (remediate_etc) Calling: (['mktemp', '-d', '--tmpdir', 'mnt.XXXXX'],) {'close_fds': True, 'stderr': -2}
2018-08-18 02:51:01,928 [DEBUG] (remediate_etc) Returned: /tmp/mnt.vl6ZM
2018-08-18 02:51:01,928 [DEBUG] (remediate_etc) Calling binary: (['mount', u'/dev/onn/ovirt-node-ng-4.2.2-0.20180405.0+1', u'/tmp/mnt.vl6ZM'],) {}