http://fedorapeople.org/groups/cockpit/logs/pull-907-20191120-201616-742ef059-weldr-lorax--rhel-7-8-tar/log.html Testing 742ef059bebabb9a2d6fda3c9593eb0f78ca0af5 for pull-907-20191120-201616 with rhel-7-8/tar on 3-cockpit-10... git tag -f HEAD warning: refname 'HEAD' is ambiguous. The archive is in lorax-composer-19.7.36.tar.gz rpmbuild -bs \ --define "_sourcedir /build/cockpit-project/bots/make-checkout-workdir" \ --define "_srcrpmdir /build/cockpit-project/bots/make-checkout-workdir" \ lorax-composer.spec Wrote: /build/cockpit-project/bots/make-checkout-workdir/lorax-composer-19.7.36-1.fc30.src.rpm git clone --quiet --reference-if-able ${XDG_CACHE_HOME:-$HOME/.cache}/cockpit-project/bots https://github.com/cockpit-project/bots.git if [ -n "$COCKPIT_BOTS_REF" ]; then git -C bots fetch --quiet --depth=1 origin "$COCKPIT_BOTS_REF"; git -C bots checkout --quiet FETCH_HEAD; fi checked out bots/ ref b85bee8628488cd69a61275c024b619c0e25b4f4 srpm=lorax-composer-19.7.36-1.fc30.src.rpm ; \ bots/image-customize -v \ --resize 20G \ --upload $srpm:/var/tmp \ --upload /build/cockpit-project/bots/make-checkout-workdir/test/vm.install:/var/tmp/vm.install \ --upload /build/cockpit-project/bots/make-checkout-workdir/tests:/ \ --run-command "chmod +x /var/tmp/vm.install" \ --run-command "cd /var/tmp; /var/tmp/vm.install $srpm" \ rhel-7-8 Image resized. d5a6f22e-aa28-48e3-969d-6ada45cad085 + SRPM=lorax-composer-19.7.36-1.fc30.src.rpm + cat + yum -y update anaconda Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. This system is not registered with an entitlement server. You can use subscription-manager to register. Package(s) anaconda available, but not installed. No packages marked for update + LATEST_REPO=/etc/yum.repos.d/rhel7-rel-eng-latest.repo + '[' '!' -f /etc/yum.repos.d/rhel7-rel-eng-latest.repo ']' + cat + rpm -q beakerlib package beakerlib is not installed ++ . /etc/os-release +++ NAME='Red Hat Enterprise Linux Server' +++ VERSION='7.8 (Maipo)' +++ ID=rhel +++ ID_LIKE=fedora +++ VARIANT=Server +++ VARIANT_ID=server +++ VERSION_ID=7.8 +++ PRETTY_NAME='Red Hat Enterprise Linux Server 7.8 Beta (Maipo)' +++ ANSI_COLOR='0;31' +++ CPE_NAME=cpe:/o:redhat:enterprise_linux:7.8:beta:server +++ HOME_URL=https://www.redhat.com/ +++ BUG_REPORT_URL=https://bugzilla.redhat.com/ +++ REDHAT_BUGZILLA_PRODUCT='Red Hat Enterprise Linux 7' +++ REDHAT_BUGZILLA_PRODUCT_VERSION=7.8 +++ REDHAT_SUPPORT_PRODUCT='Red Hat Enterprise Linux' +++ REDHAT_SUPPORT_PRODUCT_VERSION='7.8 Beta' ++ echo rhel + '[' rhel = rhel ']' + cd /etc/yum.repos.d + curl -O -L http://download.devel.redhat.com/beakerrepos/beaker-client-RedHatEnterpriseLinux.repo % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 271 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 403 100 403 0 0 4033 0 --:--:-- --:--:-- --:--:-- 4033 + sed -i 's/\(gpgcheck=0\)/\1\nsslverify=0/' /etc/yum.repos.d/beaker-client-RedHatEnterpriseLinux.repo + yum install -y beakerlib Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package beakerlib.noarch 0:1.18-7.el7bkr will be installed --> Processing Dependency: wget for package: beakerlib-1.18-7.el7bkr.noarch --> Running transaction check ---> Package wget.x86_64 0:1.14-18.el7_6.1 will be installed --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: beakerlib noarch 1.18-7.el7bkr beaker-client 157 k Installing for dependencies: wget x86_64 1.14-18.el7_6.1 RHEL-7.8 547 k Transaction Summary ================================================================================ Install 1 Package (+1 Dependent package) Total download size: 704 k Installed size: 2.4 M Downloading packages: -------------------------------------------------------------------------------- Total 2.8 MB/s | 704 kB 00:00 Running transaction check Running transaction test Transaction test succeeded Running transaction Installing : wget-1.14-18.el7_6.1.x86_64 1/2 Installing : beakerlib-1.18-7.el7bkr.noarch 2/2 Verifying : wget-1.14-18.el7_6.1.x86_64 1/2 Verifying : beakerlib-1.18-7.el7bkr.noarch 2/2 Installed: beakerlib.noarch 0:1.18-7.el7bkr Dependency Installed: wget.x86_64 0:1.14-18.el7_6.1 Complete! + EPEL_REPO=/etc/yum.repos.d/epel7.repo + '[' '!' -f /etc/yum.repos.d/epel7.repo ']' + cat + rpm -q python2-pip package python2-pip is not installed + yum -y install python2-pip Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. This system is not registered with an entitlement server. You can use subscription-manager to register. http://csc.mcs.sdsmt.edu/epel/7/x86_64/repodata/3af5017447ff76f93f0fbabc935fedd5ec97787bebed8c2a9fe15c5156e20fe7-updateinfo.xml.bz2: [Errno 12] Timeout on http://csc.mcs.sdsmt.edu/epel/7/x86_64/repodata/3af5017447ff76f93f0fbabc935fedd5ec97787bebed8c2a9fe15c5156e20fe7-updateinfo.xml.bz2: (28, 'Operation too slow. Less than 1000 bytes/sec transferred the last 30 seconds') Trying other mirror. Resolving Dependencies --> Running transaction check ---> Package python2-pip.noarch 0:8.1.2-10.el7 will be installed --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: python2-pip noarch 8.1.2-10.el7 epel7 1.7 M Transaction Summary ================================================================================ Install 1 Package Total download size: 1.7 M Installed size: 7.2 M Downloading packages: Running transaction check Running transaction test Transaction test succeeded Running transaction Installing : python2-pip-8.1.2-10.el7.noarch 1/1 Verifying : python2-pip-8.1.2-10.el7.noarch 1/1 Installed: python2-pip.noarch 0:8.1.2-10.el7 Complete! + rpm -q python-virtualenv package python-virtualenv is not installed + yum -y install python-virtualenv Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package python-virtualenv.noarch 0:15.1.0-2.el7 will be installed --> Processing Dependency: python2-devel for package: python-virtualenv-15.1.0-2.el7.noarch --> Running transaction check ---> Package python-devel.x86_64 0:2.7.5-88.el7 will be installed --> Processing Dependency: python2-rpm-macros > 3-30 for package: python-devel-2.7.5-88.el7.x86_64 --> Processing Dependency: python-rpm-macros > 3-30 for package: python-devel-2.7.5-88.el7.x86_64 --> Running transaction check ---> Package python-rpm-macros.noarch 0:3-32.el7 will be installed ---> Package python2-rpm-macros.noarch 0:3-32.el7 will be installed --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: python-virtualenv noarch 15.1.0-2.el7 RHEL-7.8 1.7 M Installing for dependencies: python-devel x86_64 2.7.5-88.el7 RHEL-7.8 398 k python-rpm-macros noarch 3-32.el7 RHEL-7.8 8.9 k python2-rpm-macros noarch 3-32.el7 RHEL-7.8 7.8 k Transaction Summary ================================================================================ Install 1 Package (+3 Dependent packages) Total download size: 2.1 M Installed size: 3.1 M Downloading packages: -------------------------------------------------------------------------------- Total 5.5 MB/s | 2.1 MB 00:00 Running transaction check Running transaction test Transaction test succeeded Running transaction Installing : python-rpm-macros-3-32.el7.noarch 1/4 Installing : python2-rpm-macros-3-32.el7.noarch 2/4 Installing : python-devel-2.7.5-88.el7.x86_64 3/4 Installing : python-virtualenv-15.1.0-2.el7.noarch 4/4 Verifying : python-devel-2.7.5-88.el7.x86_64 1/4 Verifying : python2-rpm-macros-3-32.el7.noarch 2/4 Verifying : python-virtualenv-15.1.0-2.el7.noarch 3/4 Verifying : python-rpm-macros-3-32.el7.noarch 4/4 Installed: python-virtualenv.noarch 0:15.1.0-2.el7 Dependency Installed: python-devel.x86_64 0:2.7.5-88.el7 python-rpm-macros.noarch 0:3-32.el7 python2-rpm-macros.noarch 0:3-32.el7 Complete! + rm -f /etc/yum.repos.d/epel7.repo + rpm -q python2-cryptography python2-cryptography-1.7.2-2.el7.x86_64 + yum remove -y python2-cryptography Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package python2-cryptography.x86_64 0:1.7.2-2.el7 will be erased --> Processing Dependency: python-cryptography >= 1.5 for package: python-jwcrypto-0.4.2-1.el7.noarch --> Processing Dependency: python2-cryptography >= 1.4 for package: python2-ipalib-4.6.6-9.el7.noarch --> Running transaction check ---> Package python-jwcrypto.noarch 0:0.4.2-1.el7 will be erased ---> Package python2-ipalib.noarch 0:4.6.6-9.el7 will be erased --> Processing Dependency: python2-ipalib = 4.6.6-9.el7 for package: python2-ipaclient-4.6.6-9.el7.noarch --> Running transaction check ---> Package python2-ipaclient.noarch 0:4.6.6-9.el7 will be erased --> Processing Dependency: python2-ipaclient = 4.6.6-9.el7 for package: ipa-client-4.6.6-9.el7.x86_64 --> Running transaction check ---> Package ipa-client.x86_64 0:4.6.6-9.el7 will be erased --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Removing: python2-cryptography x86_64 1.7.2-2.el7 @RHEL-7.8 2.6 M Removing for dependencies: ipa-client x86_64 4.6.6-9.el7 @RHEL-7.8 254 k python-jwcrypto noarch 0.4.2-1.el7 @RHEL-7.8 249 k python2-ipaclient noarch 4.6.6-9.el7 @RHEL-7.8 7.7 M python2-ipalib noarch 4.6.6-9.el7 @RHEL-7.8 2.7 M Transaction Summary ================================================================================ Remove 1 Package (+4 Dependent packages) Installed size: 14 M Downloading packages: Running transaction check Running transaction test Transaction test succeeded Running transaction Erasing : ipa-client-4.6.6-9.el7.x86_64 1/5 Erasing : python2-ipaclient-4.6.6-9.el7.noarch 2/5 Erasing : python2-ipalib-4.6.6-9.el7.noarch 3/5 Erasing : python-jwcrypto-0.4.2-1.el7.noarch 4/5 Erasing : python2-cryptography-1.7.2-2.el7.x86_64 5/5 Verifying : python2-ipalib-4.6.6-9.el7.noarch 1/5 Verifying : ipa-client-4.6.6-9.el7.x86_64 2/5 Verifying : python2-cryptography-1.7.2-2.el7.x86_64 3/5 Verifying : python2-ipaclient-4.6.6-9.el7.noarch 4/5 Verifying : python-jwcrypto-0.4.2-1.el7.noarch 5/5 Removed: python2-cryptography.x86_64 0:1.7.2-2.el7 Dependency Removed: ipa-client.x86_64 0:4.6.6-9.el7 python-jwcrypto.noarch 0:0.4.2-1.el7 python2-ipaclient.noarch 0:4.6.6-9.el7 python2-ipalib.noarch 0:4.6.6-9.el7 Complete! + rpm -q pyparsing pyparsing-1.5.6-9.el7.noarch + yum remove -y pyparsing Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package pyparsing.noarch 0:1.5.6-9.el7 will be erased --> Processing Dependency: pyparsing for package: 1:python-configshell-1.1.fb25-1.el7.noarch --> Running transaction check ---> Package python-configshell.noarch 1:1.1.fb25-1.el7 will be erased --> Processing Dependency: python-configshell for package: targetcli-2.1.fb49-1.el7.noarch --> Running transaction check ---> Package targetcli.noarch 0:2.1.fb49-1.el7 will be erased --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Removing: pyparsing noarch 1.5.6-9.el7 @RHEL-7.8 367 k Removing for dependencies: python-configshell noarch 1:1.1.fb25-1.el7 @RHEL-7.8 495 k targetcli noarch 2.1.fb49-1.el7 @RHEL-7.8 233 k Transaction Summary ================================================================================ Remove 1 Package (+2 Dependent packages) Installed size: 1.1 M Downloading packages: Running transaction check Running transaction test Transaction test succeeded Running transaction Erasing : targetcli-2.1.fb49-1.el7.noarch 1/3 Erasing : 1:python-configshell-1.1.fb25-1.el7.noarch 2/3 Erasing : pyparsing-1.5.6-9.el7.noarch 3/3 Verifying : pyparsing-1.5.6-9.el7.noarch 1/3 Verifying : targetcli-2.1.fb49-1.el7.noarch 2/3 Verifying : 1:python-configshell-1.1.fb25-1.el7.noarch 3/3 Removed: pyparsing.noarch 0:1.5.6-9.el7 Dependency Removed: python-configshell.noarch 1:1.1.fb25-1.el7 targetcli.noarch 0:2.1.fb49-1.el7 Complete! + rpm -q git package git is not installed + yum install -y git Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package git.x86_64 0:1.8.3.1-20.el7 will be installed --> Processing Dependency: perl-Git = 1.8.3.1-20.el7 for package: git-1.8.3.1-20.el7.x86_64 --> Processing Dependency: perl(Term::ReadKey) for package: git-1.8.3.1-20.el7.x86_64 --> Processing Dependency: perl(Git) for package: git-1.8.3.1-20.el7.x86_64 --> Processing Dependency: perl(Error) for package: git-1.8.3.1-20.el7.x86_64 --> Running transaction check ---> Package perl-Error.noarch 1:0.17020-2.el7 will be installed ---> Package perl-Git.noarch 0:1.8.3.1-20.el7 will be installed ---> Package perl-TermReadKey.x86_64 0:2.30-20.el7 will be installed --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: git x86_64 1.8.3.1-20.el7 RHEL-7.8 4.4 M Installing for dependencies: perl-Error noarch 1:0.17020-2.el7 RHEL-7.8 32 k perl-Git noarch 1.8.3.1-20.el7 RHEL-7.8 55 k perl-TermReadKey x86_64 2.30-20.el7 RHEL-7.8 31 k Transaction Summary ================================================================================ Install 1 Package (+3 Dependent packages) Total download size: 4.5 M Installed size: 22 M Downloading packages: -------------------------------------------------------------------------------- Total 15 MB/s | 4.5 MB 00:00 Running transaction check Running transaction test Transaction test succeeded Running transaction Installing : 1:perl-Error-0.17020-2.el7.noarch 1/4 Installing : perl-TermReadKey-2.30-20.el7.x86_64 2/4 Installing : git-1.8.3.1-20.el7.x86_64 3/4 Installing : perl-Git-1.8.3.1-20.el7.noarch 4/4 Verifying : perl-Git-1.8.3.1-20.el7.noarch 1/4 Verifying : 1:perl-Error-0.17020-2.el7.noarch 2/4 Verifying : git-1.8.3.1-20.el7.x86_64 3/4 Verifying : perl-TermReadKey-2.30-20.el7.x86_64 4/4 Installed: git.x86_64 0:1.8.3.1-20.el7 Dependency Installed: perl-Error.noarch 1:0.17020-2.el7 perl-Git.noarch 0:1.8.3.1-20.el7 perl-TermReadKey.x86_64 0:2.30-20.el7 Complete! + parted --script /dev/vda resizepart 2 100% + partprobe Warning: Unable to open /dev/sr0 read-write (Read-only file system). /dev/sr0 has been opened read-only. + pvs --noheadings -opv_name + xargs pvresize Physical volume "/dev/vda2" changed 1 physical volume(s) resized or updated / 0 physical volume(s) not resized ++ findmnt --noheadings -oSOURCE / + rootlv=/dev/mapper/rhel-root + lvresize /dev/mapper/rhel-root -l+100%FREE -r Size of logical volume rhel/root changed from 9.79 GiB (2507 extents) to <17.80 GiB (4556 extents). Logical volume rhel/root successfully resized. meta-data=/dev/mapper/rhel-root isize=512 agcount=4, agsize=641792 blks = sectsz=512 attr=2, projid32bit=1 = crc=1 finobt=0 spinodes=0 data = bsize=4096 blocks=2567168, imaxpct=25 = sunit=0 swidth=0 blks naming =version 2 bsize=4096 ascii-ci=0 ftype=1 log =internal bsize=4096 blocks=2560, version=2 = sectsz=512 sunit=0 blks, lazy-count=1 realtime =none extsz=4096 blocks=0, rtextents=0 data blocks changed from 2567168 to 4665344 + rm -rf build-results + su builder -c '/usr/bin/mock --no-clean --resultdir build-results --rebuild lorax-composer-19.7.36-1.fc30.src.rpm' INFO: mock.py version 1.4.20 starting (python version = 3.6.8)... Start: init plugins INFO: selinux enabled Finish: init plugins INFO: Signal handler active Start: run INFO: Start(lorax-composer-19.7.36-1.fc30.src.rpm) Config(epel-7-x86_64) Start: chroot init INFO: calling preinit hooks INFO: enabled root cache INFO: enabled yum cache Start: cleaning yum metadata Finish: cleaning yum metadata INFO: enabled HW Info plugin Mock Version: 1.4.20 INFO: Mock Version: 1.4.20 Finish: chroot init Start: build phase for lorax-composer-19.7.36-1.fc30.src.rpm Start: build setup for lorax-composer-19.7.36-1.fc30.src.rpm Finish: build setup for lorax-composer-19.7.36-1.fc30.src.rpm Start: rpmbuild lorax-composer-19.7.36-1.fc30.src.rpm Start: Outputting list of installed packages Finish: Outputting list of installed packages Finish: rpmbuild lorax-composer-19.7.36-1.fc30.src.rpm Finish: build phase for lorax-composer-19.7.36-1.fc30.src.rpm INFO: Done(lorax-composer-19.7.36-1.fc30.src.rpm) Config(default) 0 minutes 51 seconds INFO: Results and/or logs in: build-results INFO: Cleaning up build root ('cleanup_on_success=True') Start: clean chroot Finish: clean chroot Finish: run ++ find build-results -name '*.rpm' -not -name '*.src.rpm' + packages='build-results/lorax-composer-19.7.36-1.el7.x86_64.rpm build-results/composer-cli-19.7.36-1.el7.x86_64.rpm' ++ basename -a build-results/lorax-composer-19.7.36-1.el7.x86_64.rpm build-results/composer-cli-19.7.36-1.el7.x86_64.rpm ++ sed 's/-[0-9].*.rpm$//' + rpm -e --verbose lorax-composer composer-cli error: package composer-cli is not installed + true + yum install -y build-results/lorax-composer-19.7.36-1.el7.x86_64.rpm build-results/composer-cli-19.7.36-1.el7.x86_64.rpm Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. Examining build-results/lorax-composer-19.7.36-1.el7.x86_64.rpm: lorax-composer-19.7.36-1.el7.x86_64 Marking build-results/lorax-composer-19.7.36-1.el7.x86_64.rpm as an update to lorax-composer-19.7.35-1.el7.x86_64 Examining build-results/composer-cli-19.7.36-1.el7.x86_64.rpm: composer-cli-19.7.36-1.el7.x86_64 Marking build-results/composer-cli-19.7.36-1.el7.x86_64.rpm to be installed Resolving Dependencies --> Running transaction check ---> Package composer-cli.x86_64 0:19.7.36-1.el7 will be installed ---> Package lorax-composer.x86_64 0:19.7.35-1.el7 will be updated ---> Package lorax-composer.x86_64 0:19.7.36-1.el7 will be an update --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: composer-cli x86_64 19.7.36-1.el7 /composer-cli-19.7.36-1.el7.x86_64 152 k Updating: lorax-composer x86_64 19.7.36-1.el7 /lorax-composer-19.7.36-1.el7.x86_64 3.6 M Transaction Summary ================================================================================ Install 1 Package Upgrade 1 Package Total size: 3.7 M Downloading packages: Running transaction check Running transaction test Transaction test succeeded Running transaction Installing : composer-cli-19.7.36-1.el7.x86_64 1/3 Updating : lorax-composer-19.7.36-1.el7.x86_64 2/3 Cleanup : lorax-composer-19.7.35-1.el7.x86_64 3/3 Verifying : lorax-composer-19.7.36-1.el7.x86_64 1/3 Verifying : composer-cli-19.7.36-1.el7.x86_64 2/3 Verifying : lorax-composer-19.7.35-1.el7.x86_64 3/3 Installed: composer-cli.x86_64 0:19.7.36-1.el7 Updated: lorax-composer.x86_64 0:19.7.36-1.el7 Complete! + systemctl enable lorax-composer.socket Created symlink from /etc/systemd/system/sockets.target.wants/lorax-composer.socket to /usr/lib/systemd/system/lorax-composer.socket. + '[' -f /usr/bin/docker ']' ++ rpm -qf /usr/bin/docker + yum remove -y docker-common-1.13.1-104.git4ef4b30.el7.x86_64 Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package docker-common.x86_64 2:1.13.1-104.git4ef4b30.el7 will be erased --> Processing Dependency: docker-common for package: 2:docker-client-1.13.1-104.git4ef4b30.el7.x86_64 --> Processing Dependency: docker-common = 2:1.13.1-104.git4ef4b30.el7 for package: 2:docker-1.13.1-104.git4ef4b30.el7.x86_64 --> Running transaction check ---> Package docker.x86_64 2:1.13.1-104.git4ef4b30.el7 will be erased ---> Package docker-client.x86_64 2:1.13.1-104.git4ef4b30.el7 will be erased --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Removing: docker-common x86_64 2:1.13.1-104.git4ef4b30.el7 @EXTRAS-7.8 4.4 k Removing for dependencies: docker x86_64 2:1.13.1-104.git4ef4b30.el7 @EXTRAS-7.8 65 M docker-client x86_64 2:1.13.1-104.git4ef4b30.el7 @EXTRAS-7.8 13 M Transaction Summary ================================================================================ Remove 1 Package (+2 Dependent packages) Installed size: 77 M Downloading packages: Running transaction check Running transaction test Transaction test succeeded Running transaction Erasing : 2:docker-1.13.1-104.git4ef4b30.el7.x86_64 1/3 warning: /etc/sysconfig/docker-storage saved as /etc/sysconfig/docker-storage.rpmsave Erasing : 2:docker-client-1.13.1-104.git4ef4b30.el7.x86_64 2/3 Erasing : 2:docker-common-1.13.1-104.git4ef4b30.el7.x86_64 3/3 Verifying : 2:docker-client-1.13.1-104.git4ef4b30.el7.x86_64 1/3 Verifying : 2:docker-1.13.1-104.git4ef4b30.el7.x86_64 2/3 Verifying : 2:docker-common-1.13.1-104.git4ef4b30.el7.x86_64 3/3 Removed: docker-common.x86_64 2:1.13.1-104.git4ef4b30.el7 Dependency Removed: docker.x86_64 2:1.13.1-104.git4ef4b30.el7 docker-client.x86_64 2:1.13.1-104.git4ef4b30.el7 Complete! + rpm -q podman-docker package podman-docker is not installed + yum install -y podman-docker Loaded plugins: product-id, search-disabled-repos, subscription-manager This system is not registered with an entitlement server. You can use subscription-manager to register. This system is not registered with an entitlement server. You can use subscription-manager to register. Resolving Dependencies --> Running transaction check ---> Package podman-docker.noarch 0:1.4.4-4.el7 will be installed --> Processing Dependency: podman = 1.4.4-4.el7 for package: podman-docker-1.4.4-4.el7.noarch --> Running transaction check ---> Package podman.x86_64 0:1.4.4-4.el7 will be installed --> Processing Dependency: containernetworking-plugins >= 0.8.1-1 for package: podman-1.4.4-4.el7.x86_64 --> Running transaction check ---> Package containernetworking-plugins.x86_64 0:0.8.1-2.el7 will be installed --> Finished Dependency Resolution Dependencies Resolved ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: podman-docker noarch 1.4.4-4.el7 EXTRAS-7.8 26 k Installing for dependencies: containernetworking-plugins x86_64 0.8.1-2.el7 EXTRAS-7.8 16 M podman x86_64 1.4.4-4.el7 EXTRAS-7.8 9.3 M Transaction Summary ================================================================================ Install 1 Package (+2 Dependent packages) Total download size: 26 M Installed size: 103 M Downloading packages: -------------------------------------------------------------------------------- Total 40 MB/s | 26 MB 00:00 Running transaction check Running transaction test Transaction test succeeded Running transaction Installing : containernetworking-plugins-0.8.1-2.el7.x86_64 1/3 Installing : podman-1.4.4-4.el7.x86_64 2/3 Installing : podman-docker-1.4.4-4.el7.noarch 3/3 Verifying : podman-1.4.4-4.el7.x86_64 1/3 Verifying : podman-docker-1.4.4-4.el7.noarch 2/3 Verifying : containernetworking-plugins-0.8.1-2.el7.x86_64 3/3 Installed: podman-docker.noarch 0:1.4.4-4.el7 Dependency Installed: containernetworking-plugins.x86_64 0:0.8.1-2.el7 podman.x86_64 0:1.4.4-4.el7 Complete! + ! test -f /run/nologin && cat /proc/sys/kernel/random/boot_id Uploading /build/cockpit-project/bots/make-checkout-workdir/lorax-composer-19.7.36-1.fc30.src.rpm scp -B -r -p -P 2201 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ControlPath=/build/tmp/ssh-%h-%p-%r-520 -o BatchMode=yes /build/cockpit-project/bots/make-checkout-workdir/lorax-composer-19.7.36-1.fc30.src.rpm root@[127.0.0.2]:/var/tmp Uploading /build/cockpit-project/bots/make-checkout-workdir/test/vm.install scp -B -r -p -P 2201 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ControlPath=/build/tmp/ssh-%h-%p-%r-520 -o BatchMode=yes /build/cockpit-project/bots/make-checkout-workdir/test/vm.install root@[127.0.0.2]:/var/tmp/vm.install Uploading /build/cockpit-project/bots/make-checkout-workdir/tests scp -B -r -p -P 2201 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o ControlPath=/build/tmp/ssh-%h-%p-%r-520 -o BatchMode=yes /build/cockpit-project/bots/make-checkout-workdir/tests root@[127.0.0.2]:/ + chmod +x /var/tmp/vm.install + cd /var/tmp; /var/tmp/vm.install lorax-composer-19.7.36-1.fc30.src.rpm killing ssh master process 588 [ -f ~/.config/lorax-test-env ] && bots/image-customize \ --upload ~/.config/lorax-test-env:/var/tmp/lorax-test-env \ rhel-7-8 || echo echo /build/cockpit-project/bots/make-checkout-workdir/test/images/rhel-7-8 /build/cockpit-project/bots/make-checkout-workdir/test/images/rhel-7-8 1..2 # ---------------------------------------------------------------------- # TestTar.test_tar % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 100 301 100 301 0 0 35 0 0:00:08 0:00:08 --:--:-- 66 "db_supported": true, :: [ 15:22:11 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:22:11 ] :: [ PASS ] :: File /usr/bin/docker should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 0s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:22:11 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 15:22:15 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:22:35 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:22:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:23:15 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:23:35 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:23:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:24:16 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:24:36 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:24:56 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:25:16 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:25:36 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:25:56 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:26:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:26:37 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:26:57 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:27:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:27:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:27:58 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:28:18 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:28:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:28:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:29:19 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:29:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:30:00 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:30:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:30:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:42 ] :: [ INFO ] :: Waiting for compose to finish ... d0eb1df7-bbd3-4bdc-bddf-71cc7890842a FINISHED example-http-server 0.0.1 tar 348876872 :: [ 15:31:43 ] :: [ BEGIN ] :: Running 'mkdir -p /var/log//tests/cli/test_compose_tar.sh' :: [ 15:31:43 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 15:31:43 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose logs d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' STDOUT: :: [ 15:31:43 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :: [ 15:31:43 ] :: [ BEGIN ] :: Running 'mv d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-logs.tar /var/log//tests/cli/test_compose_tar.sh' :: [ 15:31:43 ] :: [ PASS ] :: Command 'mv d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-logs.tar /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 15:31:43 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose image d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' STDOUT: :: [ 15:31:46 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose image d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 571s :: Assertions: 4 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with Docker :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:31:46 ] :: [ BEGIN ] :: Running 'docker import d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a:latest' STDERR: Emulate Docker CLI using podman. Create /etc/containers/nodocker to quiet msg. STDERR: Getting image source signatures STDERR: Copying blob sha256:105c93eb3dd2bab0347180582295e8a0724c4a24fa500a4709729641fe091ae5 STDERR: Copying config sha256:3db12424c052e277d76321ac0eb07ed620607d2b13c1fe68cb9ab85c985943d2 STDERR: Writing manifest to image destination STDERR: Storing signatures STDOUT: 3db12424c052e277d76321ac0eb07ed620607d2b13c1fe68cb9ab85c985943d2 :: [ 15:32:40 ] :: [ PASS ] :: Command 'docker import d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a:latest' (Expected 0, got 0) :: [ 15:32:40 ] :: [ BEGIN ] :: Running 'docker run --rm --entrypoint /usr/bin/cat composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a /etc/redhat-release' STDERR: Emulate Docker CLI using podman. Create /etc/containers/nodocker to quiet msg. STDOUT: Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) :: [ 15:32:42 ] :: [ PASS ] :: Command 'docker run --rm --entrypoint /usr/bin/cat composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 56s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with Docker) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with systemd-nspawn :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:32:42 ] :: [ BEGIN ] :: Running 'tar -xJf d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz -C /var/tmp/nspawn.WHez' :: [ 15:33:08 ] :: [ PASS ] :: Command 'tar -xJf d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz -C /var/tmp/nspawn.WHez' (Expected 0, got 0) :: [ 15:33:08 ] :: [ BEGIN ] :: Running 'systemd-nspawn -D /var/tmp/nspawn.WHez cat /etc/redhat-release' STDERR: Spawning container nspawn.WHez on /var/tmp/nspawn.WHez. STDERR: Press ^] three times within 1s to kill container. STDOUT: Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) STDERR: Container nspawn.WHez exited successfully. STDOUT: :: [ 15:33:08 ] :: [ PASS ] :: Command 'systemd-nspawn -D /var/tmp/nspawn.WHez cat /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 26s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with systemd-nspawn) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:33:08 ] :: [ BEGIN ] :: Running 'rm -rf d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz /var/tmp/nspawn.WHez' :: [ 15:33:10 ] :: [ PASS ] :: Command 'rm -rf d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz /var/tmp/nspawn.WHez' (Expected 0, got 0) :: [ 15:33:10 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose delete d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' :: [ 15:33:10 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :: [ 15:33:10 ] :: [ BEGIN ] :: Running 'docker rmi composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' STDERR: Emulate Docker CLI using podman. Create /etc/containers/nodocker to quiet msg. STDOUT: 3db12424c052e277d76321ac0eb07ed620607d2b13c1fe68cb9ab85c985943d2 :: [ 15:33:11 ] :: [ PASS ] :: Command 'docker rmi composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 3s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:33:11 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.zWZtZ2/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 660s :: Phases: 6 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_compose_tar.sh) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: TEST PROTOCOL :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: Package : cli beakerlib RPM : beakerlib-1.18-7.el7bkr.noarch Test name : /tests/cli/test_compose_tar.sh Test started : 2019-11-20 15:22:11 EST Test finished : 2019-11-20 15:33:11 EST Test duration : 660 seconds Distro : Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) Hostname : m1.cockpit.lan Architecture : x86_64 CPUs : 2 x Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz RAM size : 1837 MB HDD size : 18.77 GB :: [ 15:22:11 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:22:11 ] :: [ PASS ] :: File /usr/bin/docker should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 0s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:22:11 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 15:22:15 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:22:35 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:22:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:23:15 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:23:35 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:23:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:24:16 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:24:36 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:24:56 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:25:16 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:25:36 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:25:56 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:26:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:26:37 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:26:57 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:27:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:27:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:27:58 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:28:18 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:28:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:28:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:29:19 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:29:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:30:00 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:30:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:30:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:31:43 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 15:31:43 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :: [ 15:31:43 ] :: [ PASS ] :: Command 'mv d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-logs.tar /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 15:31:46 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose image d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 571s :: Assertions: 4 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with Docker :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:32:40 ] :: [ PASS ] :: Command 'docker import d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a:latest' (Expected 0, got 0) :: [ 15:32:42 ] :: [ PASS ] :: Command 'docker run --rm --entrypoint /usr/bin/cat composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 56s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with Docker) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with systemd-nspawn :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:33:08 ] :: [ PASS ] :: Command 'tar -xJf d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz -C /var/tmp/nspawn.WHez' (Expected 0, got 0) :: [ 15:33:08 ] :: [ PASS ] :: Command 'systemd-nspawn -D /var/tmp/nspawn.WHez cat /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 26s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with systemd-nspawn) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:33:10 ] :: [ PASS ] :: Command 'rm -rf d0eb1df7-bbd3-4bdc-bddf-71cc7890842a-root.tar.xz /var/tmp/nspawn.WHez' (Expected 0, got 0) :: [ 15:33:10 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :: [ 15:33:11 ] :: [ PASS ] :: Command 'docker rmi composer/d0eb1df7-bbd3-4bdc-bddf-71cc7890842a' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 3s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:33:11 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.zWZtZ2/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 660s :: Phases: 6 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_compose_tar.sh) % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 "db_supported": true, 100 301 100 301 0 0 33 0 0:00:09 0:00:08 0:00:01 61 100 301 100 301 0 0 33 0 0:00:09 0:00:08 0:00:01 77 Starting virtual machine 'rhel-7-8' Machine is up. Connect to it via: ssh -o ControlPath=/build/tmp/ssh-%h-%p-%r-804 -p 2201 root@127.0.0.2 Waiting for lorax-composer to become ready... ok 1 TestTar.test_tar # ---------------------------------------------------------------------- # TestTar.test_tar_kickstart % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 100 301 100 301 0 0 35 0 0:00:08 0:00:08 --:--:-- 66 100 301 100 301 0 0 35 0 0:00:08 0:00:08 --:--:-- 84 "db_supported": true, :: [ 15:34:13 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:34:13 ] :: [ BEGIN ] :: Running 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.3UDqd5/id_rsa' STDOUT: Generating public/private rsa key pair. STDOUT: Your identification has been saved in /tmp/composer-ssh-keys.3UDqd5/id_rsa. STDOUT: Your public key has been saved in /tmp/composer-ssh-keys.3UDqd5/id_rsa.pub. STDOUT: The key fingerprint is: STDOUT: SHA256:llTdLBsTbngRtVdqTauvqmjy6k4f+vVaSe0595RFnjo root@m2.cockpit.lan STDOUT: The key's randomart image is: STDOUT: +---[RSA 2048]----+ STDOUT: | ..+*. o| STDOUT: | . o=.o=o| STDOUT: | . . +=+.+| STDOUT: | . . oo..+.| STDOUT: | S . o .o| STDOUT: | . . o + o| STDOUT: | . . . o E +.| STDOUT: | ..o.+ o =..| STDOUT: | o=*= ooo.. .| STDOUT: +----[SHA256]-----+ :: [ 15:34:13 ] :: [ PASS ] :: Command 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.3UDqd5/id_rsa' (Expected 0, got 0) :: [ 15:34:13 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli blueprints push /tmp/composer.fInkN/test-tar.toml' :: [ 15:34:14 ] :: [ PASS ] :: Command '/usr/bin/composer-cli blueprints push /tmp/composer.fInkN/test-tar.toml' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:34:14 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 15:34:18 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:34:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:34:58 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:35:18 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:35:39 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:35:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:36:19 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:36:39 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:37:00 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:37:20 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:37:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:38:00 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:38:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:38:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:39:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:39:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:39:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:40:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:40:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:40:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:41:03 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:41:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:41:43 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:42:04 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:42:24 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:42:45 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:43:06 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:43:26 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:43:47 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:44:07 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:44:28 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:44:48 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:45:09 ] :: [ INFO ] :: Waiting for compose to finish ... 639aeea5-3760-40f2-99d5-b965e0bb98ea FINISHED test-tar 0.0.1 tar 376861020 :: [ 15:45:10 ] :: [ BEGIN ] :: Running 'mkdir -p /var/log//tests/cli/test_compose_tar_kickstart.sh' :: [ 15:45:10 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar_kickstart.sh' (Expected 0, got 0) :: [ 15:45:10 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose logs 639aeea5-3760-40f2-99d5-b965e0bb98ea' STDOUT: :: [ 15:45:11 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs 639aeea5-3760-40f2-99d5-b965e0bb98ea' (Expected 0, got 0) :: [ 15:45:11 ] :: [ BEGIN ] :: Running 'mv 639aeea5-3760-40f2-99d5-b965e0bb98ea-logs.tar /var/log//tests/cli/test_compose_tar_kickstart.sh' :: [ 15:45:11 ] :: [ PASS ] :: Command 'mv 639aeea5-3760-40f2-99d5-b965e0bb98ea-logs.tar /var/log//tests/cli/test_compose_tar_kickstart.sh' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 653s :: Assertions: 3 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Install tar image using kickstart liveimg command :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:45:11 ] :: [ BEGIN ] :: Running 'mkdir -p /var/tmp/test-results/' :: [ 15:45:11 ] :: [ PASS ] :: Command 'mkdir -p /var/tmp/test-results/' (Expected 0, got 0) :: [ 15:45:11 ] :: [ BEGIN ] :: Running 'fallocate -l 5G /var/tmp/test-results/disk.img' :: [ 15:45:11 ] :: [ PASS ] :: Command 'fallocate -l 5G /var/tmp/test-results/disk.img' (Expected 0, got 0) :: [ 15:45:11 ] :: [ INFO ] :: Starting installation from tar image using anaconda :: [ 15:45:11 ] :: [ BEGIN ] :: Running 'anaconda --image=/var/tmp/test-results/disk.img --kickstart=/tmp/composer.fInkN/test-liveimg.ks' STDOUT: Starting installer, one moment... STDOUT: anaconda argparse: terminal size detection failed, using default width STDOUT: [Errno 25] Inappropriate ioctl for device STDOUT: anaconda 21.48.22.158-1 for anaconda bluesky (pre-release) started. STDOUT: 15:45:18 Not asking for VNC because of an automated install STDOUT: 15:45:18 Not asking for VNC because we don't have Xvnc STDOUT: Starting automated install STDOUT: Generating updated storage configuration STDOUT: Checking storage configuration... STDOUT: STDOUT: ================================================================================ STDOUT: ================================================================================ STDOUT: Installation STDOUT: STDOUT: 1) [x] Language settings 2) [x] Time settings STDOUT: (English (United States)) (America/New_York timezone) STDOUT: 3) [x] Installation Destination 4) [x] Network configuration STDOUT: (Automatic partitioning (Wired (eth0) connected) STDOUT: selected) STDOUT: 5) [ ] User creation STDOUT: (No user will be created) STDOUT: ================================================================================ STDOUT: ================================================================================ STDOUT: Progress STDOUT: Setting up the installation environment STDOUT: . STDOUT: Creating disklabel on /dev/mapper/disk STDOUT: . STDOUT: Creating ext4 on /dev/mapper/disk1 STDOUT: . STDOUT: Creating lvmpv on /dev/mapper/disk2 STDOUT: . STDOUT: Creating swap on /dev/mapper/anaconda_image-swap STDOUT: . STDOUT: Creating ext4 on /dev/mapper/anaconda_image-root STDOUT: . STDOUT: Running pre-installation scripts STDOUT: . STDOUT: Installing software 2% STDOUT: Installing software 6% STDOUT: Installing software 11% STDOUT: Installing software 16% STDOUT: Installing software 21% STDOUT: Installing software 25% STDOUT: Installing software 29% STDOUT: Installing software 35% STDOUT: Installing software 47% STDOUT: Installing software 48% STDOUT: Installing software 0% STDOUT: Installing software 3% STDOUT: Installing software 7% STDOUT: Installing software 14% STDOUT: Installing software 17% STDOUT: Installing software 20% STDOUT: Installing software 30% STDOUT: Installing software 44% STDOUT: Installing software 45% STDOUT: Installing software 49% STDOUT: Installing software 54% STDOUT: Installing software 61% STDOUT: Installing software 69% STDOUT: Installing software 74% STDOUT: Installing software 79% STDOUT: Installing software 84% STDOUT: Installing software 88% STDOUT: Installing software 93% STDOUT: Installing software 97% STDOUT: Installing software 100% STDOUT: Installing boot loader STDOUT: . STDOUT: Performing post-installation setup tasks STDOUT: Performing post-installation setup tasks STDOUT: . STDOUT: STDOUT: Configuring installed system STDOUT: . STDOUT: Creating users STDOUT: . STDOUT: Configuring addons STDOUT: . STDOUT: Generating initramfs STDOUT: . STDOUT: Running post-installation scripts STDOUT: . :: [ 20:47:11 ] :: [ PASS ] :: Command 'anaconda --image=/var/tmp/test-results/disk.img --kickstart=/tmp/composer.fInkN/test-liveimg.ks' (Expected 0, got 0) :: [ 20:47:11 ] :: [ INFO ] :: Installation of the image finished. :: [ 20:47:11 ] :: [ BEGIN ] :: Running 'cp /tmp/composer-ssh-keys.3UDqd5/* /var/tmp/test-results' :: [ 20:47:11 ] :: [ PASS ] :: Command 'cp /tmp/composer-ssh-keys.3UDqd5/* /var/tmp/test-results' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18120s :: Assertions: 4 good, 0 bad :: RESULT: PASS (Install tar image using kickstart liveimg command) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:47:11 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose delete 639aeea5-3760-40f2-99d5-b965e0bb98ea' :: [ 20:47:12 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete 639aeea5-3760-40f2-99d5-b965e0bb98ea' (Expected 0, got 0) :: [ 20:47:12 ] :: [ BEGIN ] :: Running 'rm -rf /tmp/composer.fInkN /tmp/composer-ssh-keys.3UDqd5' :: [ 20:47:12 ] :: [ PASS ] :: Command 'rm -rf /tmp/composer.fInkN /tmp/composer-ssh-keys.3UDqd5' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:47:12 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.p5fBq4/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18780s :: Phases: 5 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_compose_tar_kickstart.sh) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: TEST PROTOCOL :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: Package : cli beakerlib RPM : beakerlib-1.18-7.el7bkr.noarch Test name : /tests/cli/test_compose_tar_kickstart.sh Test started : 2019-11-20 15:34:12 EST Test finished : 2019-11-20 20:47:12 EST Test duration : 18780 seconds Distro : Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) Hostname : m2.cockpit.lan Architecture : x86_64 CPUs : 2 x Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz RAM size : 1837 MB HDD size : 18.77 GB :: [ 15:34:13 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:34:13 ] :: [ PASS ] :: Command 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.3UDqd5/id_rsa' (Expected 0, got 0) :: [ 15:34:14 ] :: [ PASS ] :: Command '/usr/bin/composer-cli blueprints push /tmp/composer.fInkN/test-tar.toml' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:34:14 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 15:34:18 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:34:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:34:58 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:35:18 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:35:39 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:35:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:36:19 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:36:39 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:37:00 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:37:20 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:37:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:38:00 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:38:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:38:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:39:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:39:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:39:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:40:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:40:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:40:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:41:03 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:41:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:41:43 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:42:04 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:42:24 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:42:45 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:43:06 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:43:26 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:43:47 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:44:07 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:44:28 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:44:48 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:45:09 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 15:45:10 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar_kickstart.sh' (Expected 0, got 0) :: [ 15:45:11 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs 639aeea5-3760-40f2-99d5-b965e0bb98ea' (Expected 0, got 0) :: [ 15:45:11 ] :: [ PASS ] :: Command 'mv 639aeea5-3760-40f2-99d5-b965e0bb98ea-logs.tar /var/log//tests/cli/test_compose_tar_kickstart.sh' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 653s :: Assertions: 3 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Install tar image using kickstart liveimg command :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 15:45:11 ] :: [ PASS ] :: Command 'mkdir -p /var/tmp/test-results/' (Expected 0, got 0) :: [ 15:45:11 ] :: [ PASS ] :: Command 'fallocate -l 5G /var/tmp/test-results/disk.img' (Expected 0, got 0) :: [ 15:45:11 ] :: [ INFO ] :: Starting installation from tar image using anaconda :: [ 20:47:11 ] :: [ PASS ] :: Command 'anaconda --image=/var/tmp/test-results/disk.img --kickstart=/tmp/composer.fInkN/test-liveimg.ks' (Expected 0, got 0) :: [ 20:47:11 ] :: [ INFO ] :: Installation of the image finished. :: [ 20:47:11 ] :: [ PASS ] :: Command 'cp /tmp/composer-ssh-keys.3UDqd5/* /var/tmp/test-results' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18120s :: Assertions: 4 good, 0 bad :: RESULT: PASS (Install tar image using kickstart liveimg command) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:47:12 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete 639aeea5-3760-40f2-99d5-b965e0bb98ea' (Expected 0, got 0) :: [ 20:47:12 ] :: [ PASS ] :: Command 'rm -rf /tmp/composer.fInkN /tmp/composer-ssh-keys.3UDqd5' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:47:12 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.p5fBq4/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18780s :: Phases: 5 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_compose_tar_kickstart.sh) % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:02 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:03 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:05 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:06 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:08 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0 "db_supported": true, 100 301 100 301 0 0 32 0 0:00:09 0:00:09 --:--:-- 68 100 301 100 301 0 0 32 0 0:00:09 0:00:09 --:--:-- 89 :: [ 20:49:17 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:49:17 ] :: [ INFO ] :: verify_image: SSH_OPTS:'-o StrictHostKeyChecking=no -o BatchMode=yes -p 22' SSH_USER:'root' SSH_MACHINE: 'localhost' :: [ 20:49:17 ] :: [ BEGIN ] :: Can't ssh to 'localhost' as root using password-based auth :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 -o PubkeyAuthentication=no root@localhost 2>&1 | grep -i 'permission denied ('' STDOUT: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). :: [ 20:49:17 ] :: [ PASS ] :: Can't ssh to 'localhost' as root using password-based auth (Expected 0, got 0) :: [ 20:49:17 ] :: [ BEGIN ] :: root account is disabled in /etc/shadow :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost " passwd --status root | grep -E '^root\s+LK?'"' STDOUT: root LK 1969-12-30 0 99999 7 -1 (Password locked.) :: [ 20:49:17 ] :: [ PASS ] :: root account is disabled in /etc/shadow (Expected 0, got 0) :: [ 20:49:17 ] :: [ BEGIN ] :: audit.log contains entry about unsuccessful root login :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost " grep 'USER_LOGIN.*acct=\"root\".*terminal=ssh.*res=failed' /var/log/audit/audit.log"' STDOUT: type=USER_LOGIN msg=audit(1574300957.259:211): pid=1631 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:sshd_t:s0-s0:c0.c1023 msg='op=login acct="root" exe="/usr/sbin/sshd" hostname=? addr=::1 terminal=ssh res=failed' :: [ 20:49:18 ] :: [ PASS ] :: audit.log contains entry about unsuccessful root login (Expected 0, got 0) :: [ 20:49:18 ] :: [ BEGIN ] :: Login with empty passwords is disabled in sshd config file :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost ' grep -E "^[[:blank:]]*PermitEmptyPasswords[[:blank:]]*yes" /etc/ssh/sshd_config'' :: [ 20:49:18 ] :: [ PASS ] :: Login with empty passwords is disabled in sshd config file (Expected 1, got 1) :: [ 20:49:18 ] :: [ BEGIN ] :: Running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost 'cat /etc/redhat-release'' STDOUT: Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) :: [ 20:49:19 ] :: [ PASS ] :: Command 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost 'cat /etc/redhat-release'' (Expected 0, got 0) :: [ 20:49:19 ] :: [ PASS ] :: File /root/.ssh/authorized_keys should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 2s :: Assertions: 6 good, 0 bad :: RESULT: PASS (Verify VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_boot_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:49:19 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.jF6Cwj/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 3s :: Phases: 1 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_boot_tar_kickstart.sh) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: TEST PROTOCOL :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: Package : cli beakerlib RPM : beakerlib-1.18-7.el7bkr.noarch Test name : /tests/cli/test_boot_tar_kickstart.sh Test started : 2019-11-20 20:49:16 EST Test finished : 2019-11-20 20:49:19 EST Test duration : 3 seconds Distro : Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) Hostname : m3.cockpit.lan Architecture : x86_64 CPUs : 2 x Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz RAM size : 1998 MB HDD size : 4.33 GB :: [ 20:49:17 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:49:17 ] :: [ INFO ] :: verify_image: SSH_OPTS:'-o StrictHostKeyChecking=no -o BatchMode=yes -p 22' SSH_USER:'root' SSH_MACHINE: 'localhost' :: [ 20:49:17 ] :: [ PASS ] :: Can't ssh to 'localhost' as root using password-based auth (Expected 0, got 0) :: [ 20:49:17 ] :: [ PASS ] :: root account is disabled in /etc/shadow (Expected 0, got 0) :: [ 20:49:18 ] :: [ PASS ] :: audit.log contains entry about unsuccessful root login (Expected 0, got 0) :: [ 20:49:18 ] :: [ PASS ] :: Login with empty passwords is disabled in sshd config file (Expected 1, got 1) :: [ 20:49:19 ] :: [ PASS ] :: Command 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost 'cat /etc/redhat-release'' (Expected 0, got 0) :: [ 20:49:19 ] :: [ PASS ] :: File /root/.ssh/authorized_keys should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 2s :: Assertions: 6 good, 0 bad :: RESULT: PASS (Verify VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_boot_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 20:49:19 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.jF6Cwj/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 3s :: Phases: 1 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_boot_tar_kickstart.sh) scp: /var/log/tests: No such file or directory scp: /var/log/tests: No such file or directory Starting virtual machine 'rhel-7-8' Machine is up. Connect to it via: ssh -o ControlPath=/build/tmp/ssh-%h-%p-%r-804 -p 2202 root@127.0.0.2 Waiting for lorax-composer to become ready... Starting virtual machine '/var/tmp/lorax-test.zd0vcifx/disk.img' Machine is up. Connect to it via: ssh -o ControlPath=/build/tmp/ssh-%h-%p-%r-804 -p 2203 root@127.0.0.2 ok 2 TestTar.test_tar_kickstart