http://fedorapeople.org/groups/cockpit/logs/pull-909-20191121-093333-1efb558b-weldr-lorax--rhel-7-8-tar/log.html Testing 1efb558bdf3bda8de65db47dcbd51e8ad89767f4 for pull-909-20191121-093333 with rhel-7-8/tar on 1-cockpit-9... 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 9e4717b9072f3296387a8ab3ec06216544823bc8 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. 52b7588e-b71d-487b-bd9f-4c043041e265 + SRPM=lorax-composer-19.7.36-1.fc30.src.rpm + 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 3815 0 --:--:-- --:--:-- --:--:-- 3815 + 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.6 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/repomd.xml: [Errno 12] Timeout on http://csc.mcs.sdsmt.edu/epel/7/x86_64/repodata/repomd.xml: (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.7 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 11 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 50 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-1583 -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-1583 -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-1583 -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 1627 [ -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 33 0 0:00:09 0:00:08 0:00:01 60 100 301 100 301 0 0 33 0 0:00:09 0:00:08 0:00:01 75 "db_supported": true, :: [ 04:40:01 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:40:01 ] :: [ PASS ] :: File /usr/bin/docker should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 0s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:40:01 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 04:40:05 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:40:25 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:40:45 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:41:05 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:41:25 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:41:46 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:42:06 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:42:26 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:42:46 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:43:07 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:43:27 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:43:47 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:44:07 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:44:27 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:44:48 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:45:08 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:45:28 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:45:48 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:46:08 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:46:29 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:46:49 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:47:10 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:47:30 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:47:50 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:48:11 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:48:31 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:48:52 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:12 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:32 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:53 ] :: [ INFO ] :: Waiting for compose to finish ... 0aa22c8e-ec97-49a4-9353-682a70d40237 FINISHED example-http-server 0.0.1 tar 348863020 :: [ 04:49:53 ] :: [ BEGIN ] :: Running 'mkdir -p /var/log//tests/cli/test_compose_tar.sh' :: [ 04:49:53 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 04:49:53 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose logs 0aa22c8e-ec97-49a4-9353-682a70d40237' STDOUT: :: [ 04:49:53 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs 0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :: [ 04:49:53 ] :: [ BEGIN ] :: Running 'mv 0aa22c8e-ec97-49a4-9353-682a70d40237-logs.tar /var/log//tests/cli/test_compose_tar.sh' :: [ 04:49:54 ] :: [ PASS ] :: Command 'mv 0aa22c8e-ec97-49a4-9353-682a70d40237-logs.tar /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 04:49:54 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose image 0aa22c8e-ec97-49a4-9353-682a70d40237' STDOUT: :: [ 04:49:57 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose image 0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 592s :: Assertions: 4 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with Docker :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:49:57 ] :: [ BEGIN ] :: Running 'docker import 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz composer/0aa22c8e-ec97-49a4-9353-682a70d40237:latest' STDERR: Emulate Docker CLI using podman. Create /etc/containers/nodocker to quiet msg. STDERR: Getting image source signatures STDERR: Copying blob sha256:c3fc37ad5fa6d58dc4610afd9899d62454b5dc6453895536efcdd5bc02a98196 STDERR: Copying config sha256:fa7e9e0645d72292905bfa562dd57e4ac41aa9c13f29d2665470b422f1f06deb STDERR: Writing manifest to image destination STDERR: Storing signatures STDOUT: fa7e9e0645d72292905bfa562dd57e4ac41aa9c13f29d2665470b422f1f06deb :: [ 04:50:53 ] :: [ PASS ] :: Command 'docker import 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz composer/0aa22c8e-ec97-49a4-9353-682a70d40237:latest' (Expected 0, got 0) :: [ 04:50:53 ] :: [ BEGIN ] :: Running 'docker run --rm --entrypoint /usr/bin/cat composer/0aa22c8e-ec97-49a4-9353-682a70d40237 /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) :: [ 04:50:55 ] :: [ PASS ] :: Command 'docker run --rm --entrypoint /usr/bin/cat composer/0aa22c8e-ec97-49a4-9353-682a70d40237 /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 58s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with Docker) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with systemd-nspawn :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:50:55 ] :: [ BEGIN ] :: Running 'tar -xJf 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz -C /var/tmp/nspawn.Iit0' :: [ 04:51:21 ] :: [ PASS ] :: Command 'tar -xJf 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz -C /var/tmp/nspawn.Iit0' (Expected 0, got 0) :: [ 04:51:21 ] :: [ BEGIN ] :: Running 'systemd-nspawn -D /var/tmp/nspawn.Iit0 cat /etc/redhat-release' STDERR: Spawning container nspawn.Iit0 on /var/tmp/nspawn.Iit0. STDERR: Press ^] three times within 1s to kill container. STDOUT: Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) STDERR: Container nspawn.Iit0 exited successfully. STDOUT: :: [ 04:51:21 ] :: [ PASS ] :: Command 'systemd-nspawn -D /var/tmp/nspawn.Iit0 cat /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 26s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with systemd-nspawn) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:51:21 ] :: [ BEGIN ] :: Running 'rm -rf 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz /var/tmp/nspawn.Iit0' :: [ 04:51:23 ] :: [ PASS ] :: Command 'rm -rf 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz /var/tmp/nspawn.Iit0' (Expected 0, got 0) :: [ 04:51:23 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose delete 0aa22c8e-ec97-49a4-9353-682a70d40237' :: [ 04:51:23 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete 0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :: [ 04:51:23 ] :: [ BEGIN ] :: Running 'docker rmi composer/0aa22c8e-ec97-49a4-9353-682a70d40237' STDERR: Emulate Docker CLI using podman. Create /etc/containers/nodocker to quiet msg. STDOUT: fa7e9e0645d72292905bfa562dd57e4ac41aa9c13f29d2665470b422f1f06deb :: [ 04:51:25 ] :: [ PASS ] :: Command 'docker rmi composer/0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:51:25 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.UhE7yE/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 684s :: 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-21 04:40:01 EST Test finished : 2019-11-21 04:51:25 EST Test duration : 684 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 :: [ 04:40:01 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:40:01 ] :: [ PASS ] :: File /usr/bin/docker should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 0s :: Assertions: 1 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:40:01 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 04:40:05 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:40:25 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:40:45 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:41:05 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:41:25 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:41:46 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:42:06 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:42:26 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:42:46 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:43:07 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:43:27 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:43:47 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:44:07 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:44:27 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:44:48 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:45:08 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:45:28 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:45:48 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:46:08 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:46:29 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:46:49 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:47:10 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:47:30 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:47:50 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:48:11 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:48:31 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:48:52 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:12 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:32 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:53 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:49:53 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 04:49:53 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs 0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :: [ 04:49:54 ] :: [ PASS ] :: Command 'mv 0aa22c8e-ec97-49a4-9353-682a70d40237-logs.tar /var/log//tests/cli/test_compose_tar.sh' (Expected 0, got 0) :: [ 04:49:57 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose image 0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 592s :: Assertions: 4 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with Docker :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:50:53 ] :: [ PASS ] :: Command 'docker import 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz composer/0aa22c8e-ec97-49a4-9353-682a70d40237:latest' (Expected 0, got 0) :: [ 04:50:55 ] :: [ PASS ] :: Command 'docker run --rm --entrypoint /usr/bin/cat composer/0aa22c8e-ec97-49a4-9353-682a70d40237 /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 58s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with Docker) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify tar image with systemd-nspawn :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:51:21 ] :: [ PASS ] :: Command 'tar -xJf 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz -C /var/tmp/nspawn.Iit0' (Expected 0, got 0) :: [ 04:51:21 ] :: [ PASS ] :: Command 'systemd-nspawn -D /var/tmp/nspawn.Iit0 cat /etc/redhat-release' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 26s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Verify tar image with systemd-nspawn) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:51:23 ] :: [ PASS ] :: Command 'rm -rf 0aa22c8e-ec97-49a4-9353-682a70d40237-root.tar.xz /var/tmp/nspawn.Iit0' (Expected 0, got 0) :: [ 04:51:23 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete 0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :: [ 04:51:25 ] :: [ PASS ] :: Command 'docker rmi composer/0aa22c8e-ec97-49a4-9353-682a70d40237' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 4s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:51:25 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.UhE7yE/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 684s :: 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 0 0 0 0 0 0 0 0 --:--:-- 0:00:09 --:--:-- 0 "db_supported": true, 100 301 100 301 0 0 33 0 0:00:09 0:00:09 --:--:-- 74 Starting virtual machine 'rhel-7-8' Machine is up. Connect to it via: ssh -o ControlPath=/build/tmp/ssh-%h-%p-%r-1801 -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 "db_supported": true, 100 301 100 301 0 0 35 0 0:00:08 0:00:08 --:--:-- 66 :: [ 04:52:27 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:52:27 ] :: [ BEGIN ] :: Running 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.Fykgvd/id_rsa' STDOUT: Generating public/private rsa key pair. STDOUT: Your identification has been saved in /tmp/composer-ssh-keys.Fykgvd/id_rsa. STDOUT: Your public key has been saved in /tmp/composer-ssh-keys.Fykgvd/id_rsa.pub. STDOUT: The key fingerprint is: STDOUT: SHA256:MpK9yalueaNI3UhsxE1TqOTtIHh+1SLKoyvSviulHXE root@m2.cockpit.lan STDOUT: The key's randomart image is: STDOUT: +---[RSA 2048]----+ STDOUT: | oo. | STDOUT: | ..o.. | STDOUT: | . ooo.. | STDOUT: |. +oEo+ . | STDOUT: | + =*=+.S | STDOUT: | B+.=.* | STDOUT: | *.+o.* | STDOUT: |*.o.o.o | STDOUT: |++==+o . | STDOUT: +----[SHA256]-----+ :: [ 04:52:28 ] :: [ PASS ] :: Command 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.Fykgvd/id_rsa' (Expected 0, got 0) :: [ 04:52:28 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli blueprints push /tmp/composer.kdhDR/test-tar.toml' :: [ 04:52:28 ] :: [ PASS ] :: Command '/usr/bin/composer-cli blueprints push /tmp/composer.kdhDR/test-tar.toml' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:52:28 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 04:52:33 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 5s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:52:53 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:53:13 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:53:34 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:53:54 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:54:14 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:54:34 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:54:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:55:15 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:55:35 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:55:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:56:16 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:56:36 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:56:56 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:57:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:57:37 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:57:57 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:58:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:58:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:58:58 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:59:18 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:59:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:59:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:00:20 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:00:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:01:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:01:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:01:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:02:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:02:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:02:43 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:03:04 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:03:24 ] :: [ INFO ] :: Waiting for compose to finish ... 9f269a9c-6c00-4ffd-8890-fb61fab3c4af FINISHED test-tar 0.0.1 tar 376824364 :: [ 05:03:25 ] :: [ BEGIN ] :: Running 'mkdir -p /var/log//tests/cli/test_compose_tar_kickstart.sh' :: [ 05:03:25 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar_kickstart.sh' (Expected 0, got 0) :: [ 05:03:25 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose logs 9f269a9c-6c00-4ffd-8890-fb61fab3c4af' STDOUT: :: [ 05:03:26 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs 9f269a9c-6c00-4ffd-8890-fb61fab3c4af' (Expected 0, got 0) :: [ 05:03:26 ] :: [ BEGIN ] :: Running 'mv 9f269a9c-6c00-4ffd-8890-fb61fab3c4af-logs.tar /var/log//tests/cli/test_compose_tar_kickstart.sh' :: [ 05:03:26 ] :: [ PASS ] :: Command 'mv 9f269a9c-6c00-4ffd-8890-fb61fab3c4af-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 :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:03:26 ] :: [ BEGIN ] :: Running 'mkdir -p /var/tmp/test-results/' :: [ 05:03:26 ] :: [ PASS ] :: Command 'mkdir -p /var/tmp/test-results/' (Expected 0, got 0) :: [ 05:03:26 ] :: [ BEGIN ] :: Running 'fallocate -l 5G /var/tmp/test-results/disk.img' :: [ 05:03:26 ] :: [ PASS ] :: Command 'fallocate -l 5G /var/tmp/test-results/disk.img' (Expected 0, got 0) :: [ 05:03:26 ] :: [ INFO ] :: Starting installation from tar image using anaconda :: [ 05:03:26 ] :: [ BEGIN ] :: Running 'anaconda --image=/var/tmp/test-results/disk.img --kickstart=/tmp/composer.kdhDR/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: 05:03:32 Not asking for VNC because of an automated install STDOUT: 05:03:32 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 12% STDOUT: Installing software 18% STDOUT: Installing software 23% STDOUT: Installing software 28% STDOUT: Installing software 36% STDOUT: Installing software 47% STDOUT: Installing software 49% STDOUT: Installing software 1% STDOUT: Installing software 4% STDOUT: Installing software 10% STDOUT: Installing software 15% STDOUT: Installing software 19% STDOUT: Installing software 26% STDOUT: Installing software 41% STDOUT: Installing software 47% STDOUT: Installing software 52% STDOUT: Installing software 60% STDOUT: Installing software 68% STDOUT: Installing software 74% STDOUT: Installing software 79% STDOUT: Installing software 84% STDOUT: Installing software 88% STDOUT: Installing software 93% STDOUT: Installing software 99% 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: . :: [ 10:05:15 ] :: [ PASS ] :: Command 'anaconda --image=/var/tmp/test-results/disk.img --kickstart=/tmp/composer.kdhDR/test-liveimg.ks' (Expected 0, got 0) :: [ 10:05:15 ] :: [ INFO ] :: Installation of the image finished. :: [ 10:05:15 ] :: [ BEGIN ] :: Running 'cp /tmp/composer-ssh-keys.Fykgvd/* /var/tmp/test-results' :: [ 10:05:15 ] :: [ PASS ] :: Command 'cp /tmp/composer-ssh-keys.Fykgvd/* /var/tmp/test-results' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18109s :: Assertions: 4 good, 0 bad :: RESULT: PASS (Install tar image using kickstart liveimg command) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:05:15 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose delete 9f269a9c-6c00-4ffd-8890-fb61fab3c4af' :: [ 10:05:15 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete 9f269a9c-6c00-4ffd-8890-fb61fab3c4af' (Expected 0, got 0) :: [ 10:05:16 ] :: [ BEGIN ] :: Running 'rm -rf /tmp/composer.kdhDR /tmp/composer-ssh-keys.Fykgvd' :: [ 10:05:16 ] :: [ PASS ] :: Command 'rm -rf /tmp/composer.kdhDR /tmp/composer-ssh-keys.Fykgvd' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:05:16 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.MO0Rq0/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18769s :: 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-21 04:52:27 EST Test finished : 2019-11-21 10:05:16 EST Test duration : 18769 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 :: [ 04:52:27 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:52:28 ] :: [ PASS ] :: Command 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.Fykgvd/id_rsa' (Expected 0, got 0) :: [ 04:52:28 ] :: [ PASS ] :: Command '/usr/bin/composer-cli blueprints push /tmp/composer.kdhDR/test-tar.toml' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:52:28 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 04:52:33 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 5s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:52:53 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:53:13 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:53:34 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:53:54 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:54:14 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:54:34 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:54:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:55:15 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:55:35 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:55:55 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:56:16 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:56:36 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:56:56 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:57:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:57:37 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:57:57 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:58:17 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:58:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:58:58 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:59:18 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:59:38 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 04:59:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:00:20 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:00:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:01:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:01:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:01:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:02:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:02:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:02:43 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:03:04 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:03:24 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 05:03:25 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_compose_tar_kickstart.sh' (Expected 0, got 0) :: [ 05:03:26 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs 9f269a9c-6c00-4ffd-8890-fb61fab3c4af' (Expected 0, got 0) :: [ 05:03:26 ] :: [ PASS ] :: Command 'mv 9f269a9c-6c00-4ffd-8890-fb61fab3c4af-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 :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:03:26 ] :: [ PASS ] :: Command 'mkdir -p /var/tmp/test-results/' (Expected 0, got 0) :: [ 05:03:26 ] :: [ PASS ] :: Command 'fallocate -l 5G /var/tmp/test-results/disk.img' (Expected 0, got 0) :: [ 05:03:26 ] :: [ INFO ] :: Starting installation from tar image using anaconda :: [ 10:05:15 ] :: [ PASS ] :: Command 'anaconda --image=/var/tmp/test-results/disk.img --kickstart=/tmp/composer.kdhDR/test-liveimg.ks' (Expected 0, got 0) :: [ 10:05:15 ] :: [ INFO ] :: Installation of the image finished. :: [ 10:05:15 ] :: [ PASS ] :: Command 'cp /tmp/composer-ssh-keys.Fykgvd/* /var/tmp/test-results' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18109s :: Assertions: 4 good, 0 bad :: RESULT: PASS (Install tar image using kickstart liveimg command) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:05:15 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete 9f269a9c-6c00-4ffd-8890-fb61fab3c4af' (Expected 0, got 0) :: [ 10:05:16 ] :: [ PASS ] :: Command 'rm -rf /tmp/composer.kdhDR /tmp/composer-ssh-keys.Fykgvd' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 1s :: Assertions: 2 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_compose_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:05:16 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.MO0Rq0/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18769s :: 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 100 301 100 301 0 0 33 0 0:00:09 0:00:09 --:--:-- 60 100 301 100 301 0 0 33 0 0:00:09 0:00:09 --:--:-- 75 "db_supported": true, :: [ 10:07:25 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:07:25 ] :: [ INFO ] :: verify_image: SSH_OPTS:'-o StrictHostKeyChecking=no -o BatchMode=yes -p 22' SSH_USER:'root' SSH_MACHINE: 'localhost' :: [ 10:07:25 ] :: [ 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). :: [ 10:07:26 ] :: [ PASS ] :: Can't ssh to 'localhost' as root using password-based auth (Expected 0, got 0) :: [ 10:07:26 ] :: [ 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.) :: [ 10:07:26 ] :: [ PASS ] :: root account is disabled in /etc/shadow (Expected 0, got 0) :: [ 10:07:27 ] :: [ 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(1574348845.478:211): pid=1628 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' :: [ 10:07:27 ] :: [ PASS ] :: audit.log contains entry about unsuccessful root login (Expected 0, got 0) :: [ 10:07:27 ] :: [ 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'' :: [ 10:07:28 ] :: [ PASS ] :: Login with empty passwords is disabled in sshd config file (Expected 1, got 1) :: [ 10:07:28 ] :: [ 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) :: [ 10:07:29 ] :: [ PASS ] :: Command 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost 'cat /etc/redhat-release'' (Expected 0, got 0) :: [ 10:07:30 ] :: [ PASS ] :: File /root/.ssh/authorized_keys should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 5s :: Assertions: 6 good, 0 bad :: RESULT: PASS (Verify VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_boot_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:07:30 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.L6GwC9/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 5s :: 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-21 10:07:25 EST Test finished : 2019-11-21 10:07:30 EST Test duration : 5 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 :: [ 10:07:25 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:07:25 ] :: [ INFO ] :: verify_image: SSH_OPTS:'-o StrictHostKeyChecking=no -o BatchMode=yes -p 22' SSH_USER:'root' SSH_MACHINE: 'localhost' :: [ 10:07:26 ] :: [ PASS ] :: Can't ssh to 'localhost' as root using password-based auth (Expected 0, got 0) :: [ 10:07:26 ] :: [ PASS ] :: root account is disabled in /etc/shadow (Expected 0, got 0) :: [ 10:07:27 ] :: [ PASS ] :: audit.log contains entry about unsuccessful root login (Expected 0, got 0) :: [ 10:07:28 ] :: [ PASS ] :: Login with empty passwords is disabled in sshd config file (Expected 1, got 1) :: [ 10:07:29 ] :: [ PASS ] :: Command 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -p 22 root@localhost 'cat /etc/redhat-release'' (Expected 0, got 0) :: [ 10:07:30 ] :: [ PASS ] :: File /root/.ssh/authorized_keys should exist :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 5s :: Assertions: 6 good, 0 bad :: RESULT: PASS (Verify VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_boot_tar_kickstart.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:07:30 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.L6GwC9/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 5s :: 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-1801 -p 2202 root@127.0.0.2 Waiting for lorax-composer to become ready... Starting virtual machine '/var/tmp/lorax-test.t1v155ej/disk.img' Machine is up. Connect to it via: ssh -o ControlPath=/build/tmp/ssh-%h-%p-%r-1801 -p 2203 root@127.0.0.2 ok 2 TestTar.test_tar_kickstart