http://fedorapeople.org/groups/cockpit/logs/pull-909-20191121-093333-1efb558b-weldr-lorax--rhel-7-8-azure/log.html Testing 1efb558bdf3bda8de65db47dcbd51e8ad89767f4 for pull-909-20191121-093333 with rhel-7-8/azure on 3-cockpit-8... 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. ac75af09-fb6a-4aa6-b28a-bde12d3a1499 + 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 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 0 271 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 403 100 403 0 0 5518 0 --:--:-- --:--:-- --:--:-- 5518 + 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 1.7 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/fe6b87bb242232c28935154404a147bc9f6104926f030885108ebfd92f9b0678-updateinfo.xml.bz2: [Errno 12] Timeout on http://csc.mcs.sdsmt.edu/epel/7/x86_64/repodata/fe6b87bb242232c28935154404a147bc9f6104926f030885108ebfd92f9b0678-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 6.3 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 12 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 43 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-883 -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-883 -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-883 -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 952 [ -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..1 # ---------------------------------------------------------------------- # TestCloud.test_azure % 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 37 0 0:00:08 0:00:08 --:--:-- 74 "db_supported": true, :: [ 04:39:55 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:39:55 ] :: [ INFO ] :: AZURE_SUBSCRIPTION_ID is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_TENANT is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_CLIENT_ID is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_SECRET is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_RESOURCE_GROUP=composer :: [ 04:39:55 ] :: [ INFO ] :: AZURE_STORAGE_ACCOUNT=composerredhat :: [ 04:39:55 ] :: [ INFO ] :: AZURE_STORAGE_CONTAINER=composerredhat python2-pip-8.1.2-10.el7.noarch :: [ 04:39:55 ] :: [ LOG ] :: Package python2-pip is present :: [ 04:39:55 ] :: [ LOG ] :: Package versions: :: [ 04:39:55 ] :: [ LOG ] :: python2-pip-8.1.2-10.el7.noarch python-virtualenv-15.1.0-2.el7.noarch :: [ 04:39:55 ] :: [ PASS ] :: Checking for the presence of python-virtualenv rpm :: [ 04:39:55 ] :: [ LOG ] :: Package versions: :: [ 04:39:55 ] :: [ LOG ] :: python-virtualenv-15.1.0-2.el7.noarch :: [ 04:39:55 ] :: [ BEGIN ] :: Running 'virtualenv /tmp/venv.KHk' STDOUT: New python executable in /tmp/venv.KHk/bin/python STDOUT: Installing setuptools, pip, wheel...done. :: [ 04:39:58 ] :: [ PASS ] :: Command 'virtualenv /tmp/venv.KHk' (Expected 0, got 0) :: [ 04:39:58 ] :: [ BEGIN ] :: Running 'pip install --upgrade pip setuptools' STDOUT: Collecting pip STDOUT: Downloading https://files.pythonhosted.org/packages/00/b6/9cfa56b4081ad13874b0c6f96af8ce16cfbc1cb06bedf8e9164ce5551ec1/pip-19.3.1-py2.py3-none-any.whl (1.4MB) STDOUT: Collecting setuptools STDOUT: Downloading https://files.pythonhosted.org/packages/d9/de/554b6310ac87c5b921bc45634b07b11394fe63bc4cb5176f5240addf18ab/setuptools-41.6.0-py2.py3-none-any.whl (582kB) STDOUT: Installing collected packages: pip, setuptools STDOUT: Found existing installation: pip 9.0.1 STDOUT: Uninstalling pip-9.0.1: STDOUT: Successfully uninstalled pip-9.0.1 STDOUT: Found existing installation: setuptools 28.8.0 STDOUT: Uninstalling setuptools-28.8.0: STDOUT: Successfully uninstalled setuptools-28.8.0 STDOUT: Successfully installed pip-19.3.1 setuptools-41.6.0 :: [ 04:40:05 ] :: [ PASS ] :: Command 'pip install --upgrade pip setuptools' (Expected 0, got 0) :: [ 04:40:05 ] :: [ BEGIN ] :: Running 'pip install ansible[azure] futures' STDERR: DEPRECATION: Python 2.7 will reach the end of its life on January 1st, 2020. Please upgrade your Python as Python 2.7 won't be maintained after that date. A future version of pip will drop support for Python 2.7. More details about Python 2 support in pip, can be found at https://pip.pypa.io/en/latest/development/release-process/#python-2-support STDOUT: Collecting ansible[azure] STDOUT: Downloading https://files.pythonhosted.org/packages/4b/c5/fb70a2d9817eb48c358a67952ed3a1869e5cda8a5e4f1911cffc75f42375/ansible-2.9.1.tar.gz (14.1MB) STDOUT: Collecting futures STDOUT: Downloading https://files.pythonhosted.org/packages/d8/a6/f46ae3f1da0cd4361c344888f59ec2f5785e69c872e175a748ef6071cdb5/futures-3.3.0-py2-none-any.whl STDOUT: Collecting jinja2 STDOUT: Downloading https://files.pythonhosted.org/packages/65/e0/eb35e762802015cab1ccee04e8a277b03f1d8e53da3ec3106882ec42558b/Jinja2-2.10.3-py2.py3-none-any.whl (125kB) STDOUT: Collecting PyYAML STDOUT: Downloading https://files.pythonhosted.org/packages/e3/e8/b3212641ee2718d556df0f23f78de8303f068fe29cdaa7a91018849582fe/PyYAML-5.1.2.tar.gz (265kB) STDOUT: Collecting cryptography STDOUT: Downloading https://files.pythonhosted.org/packages/c8/52/ad7f2cbe3b6e9340526dc401b38bb67c06160449446e15b0bf5f947fa168/cryptography-2.8-cp27-cp27mu-manylinux2010_x86_64.whl (2.3MB) STDOUT: Collecting packaging STDOUT: Downloading https://files.pythonhosted.org/packages/cf/94/9672c2d4b126e74c4496c6b3c58a8b51d6419267be9e70660ba23374c875/packaging-19.2-py2.py3-none-any.whl STDOUT: Collecting requests[security] STDOUT: Downloading https://files.pythonhosted.org/packages/51/bd/23c926cd341ea6b7dd0b2a00aba99ae0f828be89d72b2190f27c11d4b7fb/requests-2.22.0-py2.py3-none-any.whl (57kB) STDOUT: Collecting xmltodict STDOUT: Downloading https://files.pythonhosted.org/packages/28/fd/30d5c1d3ac29ce229f6bdc40bbc20b28f716e8b363140c26eff19122d8a5/xmltodict-0.12.0-py2.py3-none-any.whl STDOUT: Collecting azure-cli-core==2.0.35 STDOUT: Downloading https://files.pythonhosted.org/packages/ee/81/561473d6614d15f450eba6b7c8e0e1fbbaf34bf117fe77c1188010870e24/azure_cli_core-2.0.35-py2.py3-none-any.whl (90kB) STDOUT: Collecting azure-cli-nspkg==3.0.2 STDOUT: Downloading https://files.pythonhosted.org/packages/7c/94/cf884b92a870422f02c3f1f86573d04d5cc1abdc2ac51b8419c7ee2e2a00/azure_cli_nspkg-3.0.2-py2.py3-none-any.whl STDOUT: Collecting azure-common==1.1.11 STDOUT: Downloading https://files.pythonhosted.org/packages/97/3b/2c7cda25382c3bb566008c5c8f8aa28663fd15a80a6204c76ae0035de107/azure_common-1.1.11-py2.py3-none-any.whl STDOUT: Collecting azure-mgmt-authorization==0.51.1 STDOUT: Downloading https://files.pythonhosted.org/packages/a1/71/9a20913e92771b3c23564f1bea54d376d09fb30a75585087c70b769d75c8/azure_mgmt_authorization-0.51.1-py2.py3-none-any.whl (111kB) STDOUT: Collecting azure-mgmt-batch==5.0.1 STDOUT: Downloading https://files.pythonhosted.org/packages/97/81/a9eb3fd2ab070159105b4cfe9640c24410ac8195286729d62bfdf871de94/azure_mgmt_batch-5.0.1-py2.py3-none-any.whl (87kB) STDOUT: Collecting azure-mgmt-cdn==3.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/ab/17/1684f274bd57ff81b0ac9000030d5796bf88c9735a093ba8b693c39ca6fd/azure_mgmt_cdn-3.0.0-py2.py3-none-any.whl (108kB) STDOUT: Collecting azure-mgmt-compute==4.4.0 STDOUT: Downloading https://files.pythonhosted.org/packages/b7/c4/2ba0af13ecfcd8a3dc2a43cb7306d8b1fe6881b93028afb3820458a1a3e2/azure_mgmt_compute-4.4.0-py2.py3-none-any.whl (2.5MB) STDOUT: Collecting azure-mgmt-containerinstance==1.4.0 STDOUT: Downloading https://files.pythonhosted.org/packages/77/50/f7f419e0ac788d41d080d5f25daf988b993063aab3bfd90def54e93e72d7/azure_mgmt_containerinstance-1.4.0-py2.py3-none-any.whl (87kB) STDOUT: Collecting azure-mgmt-containerregistry==2.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/c5/b6/926aba2129e87976a00c4742aa1dba4fea09708c0871ab48ec7937f9ac99/azure_mgmt_containerregistry-2.0.0-py2.py3-none-any.whl (274kB) STDOUT: Collecting azure-mgmt-containerservice==4.4.0 STDOUT: Downloading https://files.pythonhosted.org/packages/5b/2f/0eba2dbd5d3f66c076a66b0020ccfe9c7e78534ac132afaa104c138680c1/azure_mgmt_containerservice-4.4.0-py2.py3-none-any.whl (206kB) STDOUT: Collecting azure-mgmt-dns==2.1.0 STDOUT: Downloading https://files.pythonhosted.org/packages/c7/d7/0f986a64b06db93cf29b76f9a188f5778eb959624a00ed6aedc335ee58d2/azure_mgmt_dns-2.1.0-py2.py3-none-any.whl (134kB) STDOUT: Collecting azure-mgmt-keyvault==1.1.0 STDOUT: Downloading https://files.pythonhosted.org/packages/49/de/0d69aedae7c5f6428314640b65947203ab80409c12b5d4e66fb5b7a4182e/azure_mgmt_keyvault-1.1.0-py2.py3-none-any.whl (111kB) STDOUT: Collecting azure-mgmt-marketplaceordering==0.1.0 STDOUT: Downloading https://files.pythonhosted.org/packages/a8/cb/13502fdbaf520d08fb280eb31ecfe5d926b9cf92259c22280bbde96b307d/azure_mgmt_marketplaceordering-0.1.0-py2.py3-none-any.whl STDOUT: Collecting azure-mgmt-monitor==0.5.2 STDOUT: Downloading https://files.pythonhosted.org/packages/6a/3b/a8b95ee25f1c209ad82ad06de39f4efbfb9dc8a8dc5da5a7a48d7897bf3e/azure_mgmt_monitor-0.5.2-py2.py3-none-any.whl (247kB) STDOUT: Collecting azure-mgmt-network==2.3.0 STDOUT: Downloading https://files.pythonhosted.org/packages/59/36/611dca31be00288f13fb632b4a3397791cf4cdd2c21f331829ec3e086c52/azure_mgmt_network-2.3.0-py2.py3-none-any.whl (7.8MB) STDOUT: Collecting azure-mgmt-nspkg==2.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/c9/e3/48b98f929290b0cd0aaa4707ecabb8aaeb267e8ef628af907f1d1c506ec7/azure_mgmt_nspkg-2.0.0-py2.py3-none-any.whl STDOUT: Collecting azure-mgmt-redis==5.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/90/a7/42342a984b2916972c5c5e24df94e3cd5e4377a8dc465a83415706d9be6f/azure_mgmt_redis-5.0.0-py2.py3-none-any.whl (43kB) STDOUT: Collecting azure-mgmt-resource==2.1.0 STDOUT: Downloading https://files.pythonhosted.org/packages/2b/2e/e79a278bedfc21308ab0c632759cfda5d7ff02d62260bcc4632449937dcf/azure_mgmt_resource-2.1.0-py2.py3-none-any.whl (757kB) STDOUT: Collecting azure-mgmt-rdbms==1.4.1 STDOUT: Downloading https://files.pythonhosted.org/packages/2a/6c/9b8446c20bc2deff03f148e757d4d397abd67e1d4fdaca45e1a8ada9e7e2/azure_mgmt_rdbms-1.4.1-py2.py3-none-any.whl (243kB) STDOUT: Collecting azure-mgmt-servicebus==0.5.3 STDOUT: Downloading https://files.pythonhosted.org/packages/28/ad/9e90f8bab40a9682410e57ed08a799be113c5e470bec247b099038c6389e/azure_mgmt_servicebus-0.5.3-py2.py3-none-any.whl (112kB) STDOUT: Collecting azure-mgmt-sql==0.10.0 STDOUT: Downloading https://files.pythonhosted.org/packages/5d/88/4513a4c3ac2779c284eeaa6cb225e6c43005abbd6d0395b15477ac8a66f1/azure_mgmt_sql-0.10.0-py2.py3-none-any.whl (541kB) STDOUT: Collecting azure-mgmt-storage==3.1.0 STDOUT: Downloading https://files.pythonhosted.org/packages/e8/d9/496b29857a252bc3fcc4bbda069c0eb64b537c8e8f7e342abb4053ba920f/azure_mgmt_storage-3.1.0-py2.py3-none-any.whl (696kB) STDOUT: Collecting azure-mgmt-trafficmanager==0.50.0 STDOUT: Downloading https://files.pythonhosted.org/packages/ed/4f/c322d72dc92dde543471bc23f9ac0eb6b9d0dc2441bb5ce938250040e307/azure_mgmt_trafficmanager-0.50.0-py2.py3-none-any.whl (52kB) STDOUT: Collecting azure-mgmt-web==0.41.0 STDOUT: Downloading https://files.pythonhosted.org/packages/17/23/ba8a043dc657a9cf4820b69cc5d890870a8bfbc5b2241997994d8898bb15/azure_mgmt_web-0.41.0-py2.py3-none-any.whl (645kB) STDOUT: Collecting azure-nspkg==2.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/cd/a0/76ca6659ae9afd7567fdbb5b9c85e9c9a0b48238cfcacd92525484408f66/azure_nspkg-2.0.0-py2.py3-none-any.whl STDOUT: Collecting azure-storage==0.35.1 STDOUT: Downloading https://files.pythonhosted.org/packages/76/bf/647b73450c70d4c5efbf57b35edf1b45928695a8b20dce2a4577dee0d539/azure_storage-0.35.1-py2.py3-none-any.whl (190kB) STDOUT: Collecting msrest==0.6.1 STDOUT: Downloading https://files.pythonhosted.org/packages/dc/8e/05c45ff0c0f293e8d044030a0007a16ece0d39dbc7fa6bb911a2f53b649a/msrest-0.6.1-py2.py3-none-any.whl (81kB) STDOUT: Collecting msrestazure==0.5.0 STDOUT: Downloading https://files.pythonhosted.org/packages/a4/48/cfa54772365b4d0b8f3e7af16354db768b0317b3273265b37c14f71b705b/msrestazure-0.5.0-py2.py3-none-any.whl STDOUT: Collecting azure-keyvault==1.0.0a1 STDOUT: Downloading https://files.pythonhosted.org/packages/03/f3/fe18493d4ce781368f23d05701a8203344fdc15dbf9cfee4450652776d1a/azure_keyvault-1.0.0a1-py2.py3-none-any.whl (109kB) STDOUT: Collecting azure-graphrbac==0.40.0 STDOUT: Downloading https://files.pythonhosted.org/packages/89/0a/29f7e2914033e2536026b8f0d7f8deb1edda68c9a93ce4757b2b1e39568b/azure_graphrbac-0.40.0-py2.py3-none-any.whl (63kB) STDOUT: Collecting azure-mgmt-cosmosdb==0.5.2 STDOUT: Downloading https://files.pythonhosted.org/packages/71/69/b21344bd4cebec03118db5582418a290468113c6cd05a0a76a66175110e2/azure_mgmt_cosmosdb-0.5.2-py2.py3-none-any.whl (101kB) STDOUT: Collecting azure-mgmt-hdinsight==0.1.0 STDOUT: Downloading https://files.pythonhosted.org/packages/14/8c/394f790d71193b3252b7fc13c9931df737436fc3495072455680d5a617a9/azure_mgmt_hdinsight-0.1.0-py2.py3-none-any.whl (117kB) STDOUT: Collecting azure-mgmt-devtestlabs==3.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/9b/e0/583e30ffbfc26d0f950b3d364195ca31beb7806b26fc4cbc940f7fad61b0/azure_mgmt_devtestlabs-3.0.0-py2.py3-none-any.whl (381kB) STDOUT: Collecting azure-mgmt-loganalytics==0.2.0 STDOUT: Downloading https://files.pythonhosted.org/packages/70/40/c9b77bf82916e963aa701fb396673f7ddc4cdab95524b6d2edf927b05630/azure_mgmt_loganalytics-0.2.0-py2.py3-none-any.whl (89kB) STDOUT: Collecting azure-mgmt-automation==0.1.1 STDOUT: Downloading https://files.pythonhosted.org/packages/b0/a5/e3e1dd8b1cad0e97df3a274360fddc2e036a9eeb77653aff4ab8a09ad28d/azure_mgmt_automation-0.1.1-py2.py3-none-any.whl (380kB) STDOUT: Collecting azure-mgmt-iothub==0.7.0 STDOUT: Downloading https://files.pythonhosted.org/packages/9c/c8/333e4f03eef95832f90534c2aea3b6809c2b5d50ca087b7ce4e125527ea9/azure_mgmt_iothub-0.7.0-py2.py3-none-any.whl (131kB) STDOUT: Collecting MarkupSafe>=0.23 STDOUT: Downloading https://files.pythonhosted.org/packages/fb/40/f3adb7cf24a8012813c5edb20329eb22d5d8e2a0ecf73d21d6b85865da11/MarkupSafe-1.1.1-cp27-cp27mu-manylinux1_x86_64.whl STDOUT: Collecting enum34; python_version < "3" STDOUT: Downloading https://files.pythonhosted.org/packages/c5/db/e56e6b4bbac7c4a06de1c50de6fe1ef3810018ae11732a50f15f62c7d050/enum34-1.1.6-py2-none-any.whl STDOUT: Collecting cffi!=1.11.3,>=1.8 STDOUT: Downloading https://files.pythonhosted.org/packages/93/5d/c4f950891251e478929036ca07b22f0b10324460c1d0a4434c584481db51/cffi-1.13.2-cp27-cp27mu-manylinux1_x86_64.whl (384kB) STDOUT: Collecting six>=1.4.1 STDOUT: Downloading https://files.pythonhosted.org/packages/65/26/32b8464df2a97e6dd1b656ed26b2c194606c16fe163c695a992b36c11cdf/six-1.13.0-py2.py3-none-any.whl STDOUT: Collecting ipaddress; python_version < "3" STDOUT: Downloading https://files.pythonhosted.org/packages/c2/f8/49697181b1651d8347d24c095ce46c7346c37335ddc7d255833e7cde674d/ipaddress-1.0.23-py2.py3-none-any.whl STDOUT: Collecting pyparsing>=2.0.2 STDOUT: Downloading https://files.pythonhosted.org/packages/c0/0c/fc2e007d9a992d997f04a80125b0f183da7fb554f1de701bbb70a8e7d479/pyparsing-2.4.5-py2.py3-none-any.whl (67kB) STDOUT: Collecting urllib3!=1.25.0,!=1.25.1,<1.26,>=1.21.1 STDOUT: Downloading https://files.pythonhosted.org/packages/b4/40/a9837291310ee1ccc242ceb6ebfd9eb21539649f193a7c8c86ba15b98539/urllib3-1.25.7-py2.py3-none-any.whl (125kB) STDOUT: Collecting certifi>=2017.4.17 STDOUT: Downloading https://files.pythonhosted.org/packages/18/b0/8146a4f8dd402f60744fa380bc73ca47303cccf8b9190fd16a827281eac2/certifi-2019.9.11-py2.py3-none-any.whl (154kB) STDOUT: Collecting chardet<3.1.0,>=3.0.2 STDOUT: Downloading https://files.pythonhosted.org/packages/bc/a9/01ffebfb562e4274b6487b4bb1ddec7ca55ec7510b22e4c51f14098443b8/chardet-3.0.4-py2.py3-none-any.whl (133kB) STDOUT: Collecting idna<2.9,>=2.5 STDOUT: Downloading https://files.pythonhosted.org/packages/14/2c/cd551d81dbe15200be1cf41cd03869a46fe7226e7450af7a6545bfc474c9/idna-2.8-py2.py3-none-any.whl (58kB) STDOUT: Collecting pyOpenSSL>=0.14; extra == "security" STDOUT: Downloading https://files.pythonhosted.org/packages/9e/de/f8342b68fa9e981d348039954657bdf681b2ab93de27443be51865ffa310/pyOpenSSL-19.1.0-py2.py3-none-any.whl (53kB) STDOUT: Collecting paramiko STDOUT: Downloading https://files.pythonhosted.org/packages/4b/80/74dace9e48b0ef923633dfb5e48798f58a168e4734bca8ecfaf839ba051a/paramiko-2.6.0-py2.py3-none-any.whl (199kB) STDOUT: Collecting colorama>=0.3.9 STDOUT: Downloading https://files.pythonhosted.org/packages/4f/a6/728666f39bfff1719fc94c481890b2106837da9318031f71a8424b662e12/colorama-0.4.1-py2.py3-none-any.whl STDOUT: Collecting wheel==0.30.0 STDOUT: Downloading https://files.pythonhosted.org/packages/0c/80/16a85b47702a1f47a63c104c91abdd0a6704ee8ae3b4ce4afc49bc39f9d9/wheel-0.30.0-py2.py3-none-any.whl (49kB) STDOUT: Collecting humanfriendly>=4.7 STDOUT: Downloading https://files.pythonhosted.org/packages/90/df/88bff450f333114680698dc4aac7506ff7cab164b794461906de31998665/humanfriendly-4.18-py2.py3-none-any.whl (73kB) STDOUT: Collecting applicationinsights>=0.11.1 STDOUT: Downloading https://files.pythonhosted.org/packages/a1/53/234c53004f71f0717d8acd37876e0b65c121181167057b9ce1b1795f96a0/applicationinsights-0.11.9-py2.py3-none-any.whl (58kB) STDOUT: Collecting tabulate<=0.8.2,>=0.7.7 STDOUT: Downloading https://files.pythonhosted.org/packages/12/c2/11d6845db5edf1295bc08b2f488cf5937806586afe42936c3f34c097ebdc/tabulate-0.8.2.tar.gz (45kB) STDOUT: Collecting PyJWT STDOUT: Downloading https://files.pythonhosted.org/packages/87/8b/6a9f14b5f781697e51259d81657e6048fd31a113229cf346880bb7545565/PyJWT-1.7.1-py2.py3-none-any.whl STDOUT: Collecting pygments STDOUT: Downloading https://files.pythonhosted.org/packages/5c/73/1dfa428150e3ccb0fa3e68db406e5be48698f2a979ccbcec795f28f44048/Pygments-2.4.2-py2.py3-none-any.whl (883kB) STDOUT: Collecting argcomplete>=1.8.0 STDOUT: Downloading https://files.pythonhosted.org/packages/b8/3e/370189084cdb7b4a8adb18d179f957d9115f8ee06aa5a1d85b56651a59bf/argcomplete-1.10.2-py2.py3-none-any.whl STDOUT: Requirement already satisfied: pip in /tmp/venv.KHk/lib/python2.7/site-packages (from azure-cli-core==2.0.35->ansible[azure]) (19.3.1) STDOUT: Collecting knack==0.3.3 STDOUT: Downloading https://files.pythonhosted.org/packages/92/aa/d5563dfb2724a5d7899170461f8c70e88f8f9422bd1c44a2a7528e2e6235/knack-0.3.3-py2.py3-none-any.whl (42kB) STDOUT: Collecting jmespath STDOUT: Downloading https://files.pythonhosted.org/packages/83/94/7179c3832a6d45b266ddb2aac329e101367fbdb11f425f13771d27f225bb/jmespath-0.9.4-py2.py3-none-any.whl STDOUT: Collecting python-dateutil STDOUT: Downloading https://files.pythonhosted.org/packages/d4/70/d60450c3dd48ef87586924207ae8907090de0b306af2bce5d134d78615cb/python_dateutil-2.8.1-py2.py3-none-any.whl (227kB) STDOUT: Collecting isodate>=0.6.0 STDOUT: Downloading https://files.pythonhosted.org/packages/9b/9f/b36f7774ff5ea8e428fdcfc4bb332c39ee5b9362ddd3d40d9516a55221b2/isodate-0.6.0-py2.py3-none-any.whl (45kB) STDOUT: Collecting requests-oauthlib>=0.5.0 STDOUT: Downloading https://files.pythonhosted.org/packages/a3/12/b92740d845ab62ea4edf04d2f4164d82532b5a0b03836d4d4e71c6f3d379/requests_oauthlib-1.3.0-py2.py3-none-any.whl STDOUT: Collecting typing; python_version < "3.5" STDOUT: Downloading https://files.pythonhosted.org/packages/22/30/64ca29543375759dc589ade14a6cd36382abf2bec17d67de8481bc9814d7/typing-3.7.4.1-py2-none-any.whl STDOUT: Collecting adal<2.0.0,>=0.6.0 STDOUT: Downloading https://files.pythonhosted.org/packages/4f/b5/3ea9ae3d1096b9ff31e8f1846c47d49f3129a12464ac0a73b602de458298/adal-1.2.2-py2.py3-none-any.whl (53kB) STDOUT: Collecting pycparser STDOUT: Downloading https://files.pythonhosted.org/packages/68/9e/49196946aee219aead1290e00d1e7fdeab8567783e83e1b9ab5585e6206a/pycparser-2.19.tar.gz (158kB) STDOUT: Collecting bcrypt>=3.1.3 STDOUT: Downloading https://files.pythonhosted.org/packages/ad/36/9a0227d048e98409f012570f7bef8a8c2373b9c9c5dfbf82963cbae05ede/bcrypt-3.1.7-cp27-cp27mu-manylinux1_x86_64.whl (59kB) STDOUT: Collecting pynacl>=1.0.1 STDOUT: Downloading https://files.pythonhosted.org/packages/b3/25/e605574f24948a8a53b497744e93f061eb1dbe7c44b6465fc1c172d591aa/PyNaCl-1.3.0-cp27-cp27mu-manylinux1_x86_64.whl (762kB) STDOUT: Collecting monotonic; python_version == "2.6" or python_version == "2.7" or python_version == "3.0" or python_version == "3.1" or python_version == "3.2" STDOUT: Downloading https://files.pythonhosted.org/packages/ac/aa/063eca6a416f397bd99552c534c6d11d57f58f2e94c14780f3bbf818c4cf/monotonic-1.5-py2.py3-none-any.whl STDOUT: Collecting oauthlib>=3.0.0 STDOUT: Downloading https://files.pythonhosted.org/packages/05/57/ce2e7a8fa7c0afb54a0581b14a65b56e62b5759dbc98e80627142b8a3704/oauthlib-3.1.0-py2.py3-none-any.whl (147kB) STDOUT: Building wheels for collected packages: ansible, PyYAML, tabulate, pycparser STDOUT: Building wheel for ansible (setup.py): started STDOUT: Building wheel for ansible (setup.py): finished with status 'done' STDOUT: Created wheel for ansible: filename=ansible-2.9.1-cp27-none-any.whl size=16130459 sha256=ae0aa8678160812adab9d6a8ac72870a09113593432e5c8a4adce6b040893d59 STDOUT: Stored in directory: /root/.cache/pip/wheels/5b/77/78/7b71e95b6489fb4d5bda764e40798cf379fba3228f576bb1ad STDOUT: Building wheel for PyYAML (setup.py): started STDOUT: Building wheel for PyYAML (setup.py): finished with status 'done' STDOUT: Created wheel for PyYAML: filename=PyYAML-5.1.2-cp27-cp27mu-linux_x86_64.whl size=46204 sha256=6b4014052d8496b5516c4e0f2cf85a1efe95323efc8fafbeb347450f32dadf20 STDOUT: Stored in directory: /root/.cache/pip/wheels/d9/45/dd/65f0b38450c47cf7e5312883deb97d065e030c5cca0a365030 STDOUT: Building wheel for tabulate (setup.py): started STDOUT: Building wheel for tabulate (setup.py): finished with status 'done' STDOUT: Created wheel for tabulate: filename=tabulate-0.8.2-cp27-none-any.whl size=31240 sha256=9291549b1b46f6cc5c92fb32e8788cc7f1de48b20d383ebf32326b58cb5d0a3f STDOUT: Stored in directory: /root/.cache/pip/wheels/2a/85/33/2f6da85d5f10614cbe5a625eab3b3aebfdf43e7b857f25f829 STDOUT: Building wheel for pycparser (setup.py): started STDOUT: Building wheel for pycparser (setup.py): finished with status 'done' STDOUT: Created wheel for pycparser: filename=pycparser-2.19-py2.py3-none-any.whl size=112038 sha256=210ac5f27cef7d5410634c3b52e71a3fd654265289984bcef68f59149ced53e4 STDOUT: Stored in directory: /root/.cache/pip/wheels/f2/9a/90/de94f8556265ddc9d9c8b271b0f63e57b26fb1d67a45564511 STDOUT: Successfully built ansible PyYAML tabulate pycparser STDOUT: Installing collected packages: MarkupSafe, jinja2, PyYAML, enum34, pycparser, cffi, six, ipaddress, cryptography, pyparsing, packaging, urllib3, certifi, chardet, idna, pyOpenSSL, requests, xmltodict, isodate, oauthlib, requests-oauthlib, typing, msrest, PyJWT, python-dateutil, adal, msrestazure, bcrypt, pynacl, paramiko, colorama, azure-nspkg, azure-cli-nspkg, wheel, monotonic, humanfriendly, applicationinsights, tabulate, pygments, argcomplete, jmespath, knack, azure-cli-core, azure-common, azure-mgmt-nspkg, azure-mgmt-authorization, azure-mgmt-batch, azure-mgmt-cdn, azure-mgmt-compute, azure-mgmt-containerinstance, azure-mgmt-containerregistry, azure-mgmt-containerservice, azure-mgmt-dns, azure-mgmt-keyvault, azure-mgmt-marketplaceordering, azure-mgmt-monitor, azure-mgmt-network, azure-mgmt-redis, azure-mgmt-resource, azure-mgmt-rdbms, azure-mgmt-servicebus, azure-mgmt-sql, azure-mgmt-storage, azure-mgmt-trafficmanager, azure-mgmt-web, azure-storage, azure-keyvault, azure-graphrbac, azure-mgmt-cosmosdb, azure-mgmt-hdinsight, azure-mgmt-devtestlabs, azure-mgmt-loganalytics, azure-mgmt-automation, azure-mgmt-iothub, ansible, futures STDOUT: Found existing installation: wheel 0.29.0 STDOUT: Uninstalling wheel-0.29.0: STDOUT: Successfully uninstalled wheel-0.29.0 STDOUT: Successfully installed MarkupSafe-1.1.1 PyJWT-1.7.1 PyYAML-5.1.2 adal-1.2.2 ansible-2.9.1 applicationinsights-0.11.9 argcomplete-1.10.2 azure-cli-core-2.0.35 azure-cli-nspkg-3.0.2 azure-common-1.1.11 azure-graphrbac-0.40.0 azure-keyvault-1.0.0a1 azure-mgmt-authorization-0.51.1 azure-mgmt-automation-0.1.1 azure-mgmt-batch-5.0.1 azure-mgmt-cdn-3.0.0 azure-mgmt-compute-4.4.0 azure-mgmt-containerinstance-1.4.0 azure-mgmt-containerregistry-2.0.0 azure-mgmt-containerservice-4.4.0 azure-mgmt-cosmosdb-0.5.2 azure-mgmt-devtestlabs-3.0.0 azure-mgmt-dns-2.1.0 azure-mgmt-hdinsight-0.1.0 azure-mgmt-iothub-0.7.0 azure-mgmt-keyvault-1.1.0 azure-mgmt-loganalytics-0.2.0 azure-mgmt-marketplaceordering-0.1.0 azure-mgmt-monitor-0.5.2 azure-mgmt-network-2.3.0 azure-mgmt-nspkg-2.0.0 azure-mgmt-rdbms-1.4.1 azure-mgmt-redis-5.0.0 azure-mgmt-resource-2.1.0 azure-mgmt-servicebus-0.5.3 azure-mgmt-sql-0.10.0 azure-mgmt-storage-3.1.0 azure-mgmt-trafficmanager-0.50.0 azure-mgmt-web-0.41.0 azure-nspkg-2.0.0 azure-storage-0.35.1 bcrypt-3.1.7 certifi-2019.9.11 cffi-1.13.2 chardet-3.0.4 colorama-0.4.1 cryptography-2.8 enum34-1.1.6 futures-3.3.0 humanfriendly-4.18 idna-2.8 ipaddress-1.0.23 isodate-0.6.0 jinja2-2.10.3 jmespath-0.9.4 knack-0.3.3 monotonic-1.5 msrest-0.6.1 msrestazure-0.5.0 oauthlib-3.1.0 packaging-19.2 paramiko-2.6.0 pyOpenSSL-19.1.0 pycparser-2.19 pygments-2.4.2 pynacl-1.3.0 pyparsing-2.4.5 python-dateutil-2.8.1 requests-2.22.0 requests-oauthlib-1.3.0 six-1.13.0 tabulate-0.8.2 typing-3.7.4.1 urllib3-1.25.7 wheel-0.30.0 xmltodict-0.12.0 :: [ 04:41:35 ] :: [ PASS ] :: Command 'pip install ansible[azure] futures' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 100s :: Assertions: 4 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:41:35 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 04:41:38 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 3s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 09:41:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:42:19 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:42:39 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:42:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:43:20 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:43:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:44:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:44:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:44:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:45:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:45:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:45:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:46:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:46:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:46:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:47:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:47:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:47:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:48:03 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:48:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:48:43 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:03 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:45 ] :: [ INFO ] :: Waiting for compose to finish ... ad1c870a-e111-4518-bf56-6e598899ba3c FINISHED example-http-server 0.0.1 vhd 4294967808 :: [ 09:49:45 ] :: [ BEGIN ] :: Running 'mkdir -p /var/log//tests/cli/test_build_and_deploy_azure.sh' :: [ 09:49:45 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_build_and_deploy_azure.sh' (Expected 0, got 0) :: [ 09:49:45 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose logs ad1c870a-e111-4518-bf56-6e598899ba3c' STDOUT: :: [ 09:49:46 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs ad1c870a-e111-4518-bf56-6e598899ba3c' (Expected 0, got 0) :: [ 09:49:46 ] :: [ BEGIN ] :: Running 'mv ad1c870a-e111-4518-bf56-6e598899ba3c-logs.tar /var/log//tests/cli/test_build_and_deploy_azure.sh' :: [ 09:49:46 ] :: [ PASS ] :: Command 'mv ad1c870a-e111-4518-bf56-6e598899ba3c-logs.tar /var/log//tests/cli/test_build_and_deploy_azure.sh' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18488s :: Assertions: 3 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Upload image to Azure :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 09:49:46 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose image ad1c870a-e111-4518-bf56-6e598899ba3c' STDOUT: :: [ 09:50:18 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose image ad1c870a-e111-4518-bf56-6e598899ba3c' (Expected 0, got 0) :: [ 09:50:18 ] :: [ BEGIN ] :: Running 'ansible localhost -m azure_rm_storageblob -a 'resource_group=composer storage_account_name=composerredhat container=composerredhat blob=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd src=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd blob_type=page'' STDERR: [WARNING]: No inventory was parsed, only implicit localhost is available STDOUT: localhost | CHANGED => { STDOUT: "blob": { STDOUT: "content_length": 4294967808, STDOUT: "content_settings": { STDOUT: "cache_control": null, STDOUT: "content_disposition": null, STDOUT: "content_encoding": null, STDOUT: "content_language": null, STDOUT: "content_md5": null, STDOUT: "content_type": "application/octet-stream" STDOUT: }, STDOUT: "last_modified": "21-Nov-2019 09:56:27 +0000", STDOUT: "name": "ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd", STDOUT: "tags": {}, STDOUT: "type": "PageBlob" STDOUT: }, STDOUT: "changed": true, STDOUT: "check_mode": false, STDOUT: "container": { STDOUT: "last_mdoified": "09-Oct-2019 09:29:04 +0000", STDOUT: "name": "composerredhat", STDOUT: "tags": {} STDOUT: } STDOUT: } :: [ 09:56:27 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_storageblob -a 'resource_group=composer storage_account_name=composerredhat container=composerredhat blob=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd src=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd blob_type=page'' (Expected 0, got 0) :: [ 09:56:27 ] :: [ BEGIN ] :: Running 'ansible localhost -m azure_rm_image -a 'resource_group=composer name=Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import os_type=Linux location=eastus source=https://composerredhat.blob.core.windows.net/composerredhat/ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd'' STDERR: [WARNING]: No inventory was parsed, only implicit localhost is available STDOUT: localhost | CHANGED => { STDOUT: "changed": true, STDOUT: "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/images/Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import" STDOUT: } :: [ 09:57:11 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_image -a 'resource_group=composer name=Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import os_type=Linux location=eastus source=https://composerredhat.blob.core.windows.net/composerredhat/ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd'' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 445s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Upload image to Azure) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Start VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 09:57:11 ] :: [ BEGIN ] :: Running 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.6CSAGU/id_rsa' STDOUT: Generating public/private rsa key pair. STDOUT: Your identification has been saved in /tmp/composer-ssh-keys.6CSAGU/id_rsa. STDOUT: Your public key has been saved in /tmp/composer-ssh-keys.6CSAGU/id_rsa.pub. STDOUT: The key fingerprint is: STDOUT: SHA256:U3MPum5yCMEH3C1535EWEVyV1V+UW/E0x+F//+begLM root@m1.cockpit.lan STDOUT: The key's randomart image is: STDOUT: +---[RSA 2048]----+ STDOUT: | . . o .+%/| STDOUT: | o + o *+O| STDOUT: | . . oo.oo o*| STDOUT: | o .. +.o..o| STDOUT: | oS . . o| STDOUT: | . . . . o| STDOUT: | . .. o . .| STDOUT: | o.o o .+| STDOUT: | +. E +=| STDOUT: +----[SHA256]-----+ :: [ 09:57:11 ] :: [ PASS ] :: Command 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.6CSAGU/id_rsa' (Expected 0, got 0) :: [ 09:57:11 ] :: [ BEGIN ] :: Running 'ansible-playbook /tmp/composer-azure.GiiSz/azure-playbook.yaml' STDERR: [WARNING]: No inventory was parsed, only implicit localhost is available STDERR: [WARNING]: provided hosts list is empty, only localhost is available. Note that STDERR: the implicit localhost does not match 'all' STDOUT: STDOUT: PLAY [localhost] *************************************************************** STDOUT: STDOUT: TASK [Gathering Facts] ********************************************************* STDOUT: ok: [localhost] STDOUT: STDOUT: TASK [Create a VM] ************************************************************* STDOUT: changed: [localhost] STDOUT: STDOUT: PLAY RECAP ********************************************************************* STDOUT: localhost : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 STDOUT: :: [ 10:00:56 ] :: [ PASS ] :: Command 'ansible-playbook /tmp/composer-azure.GiiSz/azure-playbook.yaml' (Expected 0, got 0) [WARNING]: No inventory was parsed, only implicit localhost is available :: [ 10:01:02 ] :: [ PASS ] :: Received VM info successfully (Assert: expected 0, got 0) :: [ 10:01:02 ] :: [ INFO ] :: localhost | SUCCESS => { "ansible_facts": { "azure_vm": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/virtualMachines/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "location": "eastus", "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "powerstate": "running", "properties": { "diagnosticsProfile": { "bootDiagnostics": { "enabled": false, "storageUri": null } }, "hardwareProfile": { "vmSize": "Standard_B2s" }, "instanceView": { "computerName": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "disks": [ { "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd", "statuses": [ { "code": "ProvisioningState/succeeded", "displayStatus": "Provisioning succeeded", "level": "Info", "time": "2019-11-21T09:58:12.538557Z" } ] } ], "osName": "redhat", "osVersion": "7.8", "statuses": [ { "code": "ProvisioningState/succeeded", "displayStatus": "Provisioning succeeded", "level": "Info", "time": "2019-11-21T10:00:33.663569Z" }, { "code": "PowerState/running", "displayStatus": "VM running", "level": "Info" } ], "vmAgent": { "extensionHandlers": [], "statuses": [ { "code": "ProvisioningState/succeeded", "displayStatus": "Ready", "level": "Info", "message": "Guest Agent is running", "time": "2019-11-21T10:01:01.000Z" } ], "vmAgentVersion": "2.2.45" } }, "networkProfile": { "networkInterfaces": [ { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkInterfaces/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "properties": { "dnsSettings": { "appliedDnsServers": [], "dnsServers": [], "internalDomainNameSuffix": "erlta3flxnyepcwscb53gqcc3g.bx.internal.cloudapp.net" }, "enableAcceleratedNetworking": false, "enableIPForwarding": false, "hostedWorkloads": [], "ipConfigurations": [ { "etag": "W/\"2e392b46-7fde-417f-a85d-3fe34a77065b\"", "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkInterfaces/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01/ipConfigurations/default", "name": "default", "properties": { "primary": true, "privateIPAddress": "10.0.1.5", "privateIPAddressVersion": "IPv4", "privateIPAllocationMethod": "Dynamic", "provisioningState": "Succeeded", "publicIPAddress": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/publicIPAddresses/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "properties": { "idleTimeoutInMinutes": 4, "ipAddress": "13.68.138.74", "ipConfiguration": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkInterfaces/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01/ipConfigurations/default" }, "ipTags": [], "provisioningState": "Succeeded", "publicIPAddressVersion": "IPv4", "publicIPAllocationMethod": "Static", "resourceGuid": "1b3ac0d8-9d86-4de1-b6fb-062ab74b794b" } }, "subnet": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/virtualNetworks/composervnet378/subnets/default" } } } ], "macAddress": "00-0D-3A-8D-BF-09", "networkSecurityGroup": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkSecurityGroups/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01" }, "primary": true, "provisioningState": "Succeeded", "resourceGuid": "a6c0691c-cfbe-4135-95f9-db72e382b06a", "tapConfigurations": [], "virtualMachine": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/virtualMachines/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c" } } } ] }, "osProfile": { "adminUsername": "azure-user", "allowExtensionOperations": true, "computerName": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "linuxConfiguration": { "disablePasswordAuthentication": true, "provisionVMAgent": true, "ssh": { "publicKeys": [ { "keyData": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCtixZjHLPLI/JBJ9VRFQnFLip5ZQ/t3huXKCdiv4tbyCplL3L3jVmYOXFNhGkiOTnb3AnqtVhPxuRHhm4HT0cxP6SUBE+8oxZJ7zsdeRuh8m5hsVsQbeVZhdf2YbYHBG0e6Tu94NZksUGaKI+9nySxqxe5ZXHEpdF7Jjfe765EU5WFLAGK22tOOd/ew/zBa+L+sk1BbmGcDXydk0ETNuSG78BZaMV6nPdATrV3K711lSijDFhsSLT9lcwj8PkyVswf9IyImhGCjloxwzzUdAVwxRvkB7EJGpjwpgKm0yGCn7ZRa51oqW1jNMlC+1SUk6i+HUH+iIBHwc11W59YLXTn root@m1.cockpit.lan", "path": "/home/azure-user/.ssh/authorized_keys" } ] } }, "secrets": [] }, "provisioningState": "Succeeded", "storageProfile": { "dataDisks": [], "imageReference": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/images/Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import" }, "osDisk": { "caching": "ReadOnly", "createOption": "FromImage", "diskSizeGB": 4, "managedDisk": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/disks/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd", "storageAccountType": "Standard_LRS" }, "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd", "osType": "Linux" } }, "vmId": "2f4adadb-f71e-4917-bb40-cd7623e07594" }, "tags": { "_own_nic_": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "_own_nsg_": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "_own_pip_": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "first_seen": "2019-11-21T14:57:11" }, "type": "Microsoft.Compute/virtualMachines", "zones": null } }, "changed": false, "powerstate_change": null } :: [ 10:01:02 ] :: [ INFO ] :: Running instance IP_ADDRESS=13.68.138.74 :: [ 10:01:02 ] :: [ INFO ] :: Waiting 60sec for instance to initialize ... :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 291s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Start VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:02:03 ] :: [ INFO ] :: verify_image: SSH_OPTS:'-o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa' SSH_USER:'azure-user' SSH_MACHINE: '13.68.138.74' :: [ 10:02:03 ] :: [ BEGIN ] :: Can't ssh to '13.68.138.74' as root using password-based auth :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa -o PubkeyAuthentication=no root@13.68.138.74 2>&1 | grep -i 'permission denied ('' STDOUT: Permission denied (publickey,gssapi-keyex,gssapi-with-mic). :: [ 10:02:03 ] :: [ PASS ] :: Can't ssh to '13.68.138.74' as root using password-based auth (Expected 0, got 0) :: [ 10:02:04 ] :: [ BEGIN ] :: root account is disabled in /etc/shadow :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 "sudo passwd --status root | grep -E '^root\s+LK?'"' STDOUT: root LK 2009-12-22 -1 -1 -1 -1 (Alternate authentication scheme in use.) :: [ 10:02:06 ] :: [ PASS ] :: root account is disabled in /etc/shadow (Expected 0, got 0) :: [ 10:02:06 ] :: [ BEGIN ] :: audit.log contains entry about unsuccessful root login :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 "sudo grep 'USER_LOGIN.*acct=\"root\".*terminal=ssh.*res=failed' /var/log/audit/audit.log"' STDOUT: type=USER_LOGIN msg=audit(1574330525.162:180): pid=1520 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=66.187.233.206 terminal=ssh res=failed' :: [ 10:02:07 ] :: [ PASS ] :: audit.log contains entry about unsuccessful root login (Expected 0, got 0) :: [ 10:02:07 ] :: [ BEGIN ] :: Login with empty passwords is disabled in sshd config file :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 'sudo grep -E "^[[:blank:]]*PermitEmptyPasswords[[:blank:]]*yes" /etc/ssh/sshd_config'' :: [ 10:02:08 ] :: [ PASS ] :: Login with empty passwords is disabled in sshd config file (Expected 1, got 1) :: [ 10:02:08 ] :: [ BEGIN ] :: Running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 'cat /etc/redhat-release'' STDOUT: Red Hat Enterprise Linux Server release 7.8 Beta (Maipo) :: [ 10:02:09 ] :: [ PASS ] :: Command 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 'cat /etc/redhat-release'' (Expected 0, got 0) :: [ 10:02:09 ] :: [ BEGIN ] :: System booted from the image contains specified parameter on kernel command line :: actually running 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 'grep custom_cmdline_arg /proc/cmdline'' STDOUT: BOOT_IMAGE=/vmlinuz-3.10.0-1111.el7.x86_64 root=UUID=83569536-9bc5-4de5-b764-08766d39b51c ro no_timer_check console=ttyS0,115200n8 earlyprintk=ttyS0,115200 rootdelay=300 custom_cmdline_arg biosdevname=0 net.ifnames=0 console=ttyS0,115200 :: [ 10:02:10 ] :: [ PASS ] :: System booted from the image contains specified parameter on kernel command line (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 7s :: Assertions: 6 good, 0 bad :: RESULT: PASS (Verify VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:02:10 ] :: [ BEGIN ] :: Running 'ansible localhost -m azure_rm_virtualmachine -a 'resource_group=composer name=Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c location=eastus state=absent'' STDERR: [WARNING]: No inventory was parsed, only implicit localhost is available STDOUT: localhost | CHANGED => { STDOUT: "ansible_facts": { STDOUT: "azure_vm": null STDOUT: }, STDOUT: "changed": true, STDOUT: "deleted_managed_disk_ids": [ STDOUT: "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/disks/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd" STDOUT: ], STDOUT: "deleted_network_interfaces": [ STDOUT: { STDOUT: "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", STDOUT: "resource_group": "composer" STDOUT: } STDOUT: ], STDOUT: "deleted_public_ips": [ STDOUT: { STDOUT: "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", STDOUT: "resource_group": "composer" STDOUT: } STDOUT: ], STDOUT: "deleted_vhd_uris": [], STDOUT: "powerstate_change": null STDOUT: } :: [ 10:04:39 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_virtualmachine -a 'resource_group=composer name=Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c location=eastus state=absent'' (Expected 0, got 0) :: [ 10:04:39 ] :: [ BEGIN ] :: Running 'ansible localhost -m azure_rm_image -a 'resource_group=composer name=Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import state=absent'' STDERR: [WARNING]: No inventory was parsed, only implicit localhost is available STDOUT: localhost | CHANGED => { STDOUT: "changed": true, STDOUT: "id": null STDOUT: } :: [ 10:05:15 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_image -a 'resource_group=composer name=Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import state=absent'' (Expected 0, got 0) :: [ 10:05:15 ] :: [ BEGIN ] :: Running 'ansible localhost -m azure_rm_storageblob -a 'resource_group=composer storage_account_name=composerredhat container=composerredhat blob=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd state=absent'' STDERR: [WARNING]: No inventory was parsed, only implicit localhost is available STDOUT: localhost | CHANGED => { STDOUT: "blob": {}, STDOUT: "changed": true, STDOUT: "check_mode": false, STDOUT: "container": { STDOUT: "last_mdoified": "09-Oct-2019 09:29:04 +0000", STDOUT: "name": "composerredhat", STDOUT: "tags": {} STDOUT: } STDOUT: } :: [ 10:05:20 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_storageblob -a 'resource_group=composer storage_account_name=composerredhat container=composerredhat blob=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd state=absent'' (Expected 0, got 0) :: [ 10:05:20 ] :: [ BEGIN ] :: Running '/usr/bin/composer-cli compose delete ad1c870a-e111-4518-bf56-6e598899ba3c' :: [ 10:05:20 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete ad1c870a-e111-4518-bf56-6e598899ba3c' (Expected 0, got 0) :: [ 10:05:20 ] :: [ BEGIN ] :: Running 'rm -rf ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd /tmp/composer-ssh-keys.6CSAGU /tmp/composer-azure.GiiSz /tmp/venv.KHk' :: [ 10:05:21 ] :: [ PASS ] :: Command 'rm -rf ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd /tmp/composer-ssh-keys.6CSAGU /tmp/composer-azure.GiiSz /tmp/venv.KHk' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 191s :: Assertions: 5 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_build_and_deploy_azure.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:05:22 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.3WeB6c/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 19527s :: Phases: 7 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_build_and_deploy_azure.sh) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: TEST PROTOCOL :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: Package : cli beakerlib RPM : beakerlib-1.18-7.el7bkr.noarch Test name : /tests/cli/test_build_and_deploy_azure.sh Test started : 2019-11-21 04:39:55 EST Test finished : 2019-11-21 10:05:22 EST Test duration : 19527 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:39:55 ] :: [ INFO ] :: skipping xml journal creation :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Setup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:39:55 ] :: [ INFO ] :: AZURE_SUBSCRIPTION_ID is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_TENANT is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_CLIENT_ID is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_SECRET is configured :: [ 04:39:55 ] :: [ INFO ] :: AZURE_RESOURCE_GROUP=composer :: [ 04:39:55 ] :: [ INFO ] :: AZURE_STORAGE_ACCOUNT=composerredhat :: [ 04:39:55 ] :: [ INFO ] :: AZURE_STORAGE_CONTAINER=composerredhat :: [ 04:39:55 ] :: [ LOG ] :: Package python2-pip is present :: [ 04:39:55 ] :: [ LOG ] :: Package versions: :: [ 04:39:55 ] :: [ LOG ] :: python2-pip-8.1.2-10.el7.noarch :: [ 04:39:55 ] :: [ PASS ] :: Checking for the presence of python-virtualenv rpm :: [ 04:39:55 ] :: [ LOG ] :: Package versions: :: [ 04:39:55 ] :: [ LOG ] :: python-virtualenv-15.1.0-2.el7.noarch :: [ 04:39:58 ] :: [ PASS ] :: Command 'virtualenv /tmp/venv.KHk' (Expected 0, got 0) :: [ 04:40:05 ] :: [ PASS ] :: Command 'pip install --upgrade pip setuptools' (Expected 0, got 0) :: [ 04:41:35 ] :: [ PASS ] :: Command 'pip install ansible[azure] futures' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 100s :: Assertions: 4 good, 0 bad :: RESULT: PASS (Setup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose start :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 04:41:35 ] :: [ PASS ] :: SELinux operates in enforcing mode (Assert: 'Enforcing' should equal 'Enforcing') :: [ 04:41:38 ] :: [ PASS ] :: exit code should be zero (Assert: '0' should equal '0') :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 3s :: Assertions: 2 good, 0 bad :: RESULT: PASS (compose start) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: compose finished :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 09:41:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:42:19 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:42:39 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:42:59 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:43:20 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:43:40 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:44:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:44:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:44:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:45:01 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:45:21 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:45:41 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:46:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:46:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:46:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:47:02 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:47:22 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:47:42 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:48:03 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:48:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:48:43 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:03 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:23 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:45 ] :: [ INFO ] :: Waiting for compose to finish ... :: [ 09:49:45 ] :: [ PASS ] :: Command 'mkdir -p /var/log//tests/cli/test_build_and_deploy_azure.sh' (Expected 0, got 0) :: [ 09:49:46 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose logs ad1c870a-e111-4518-bf56-6e598899ba3c' (Expected 0, got 0) :: [ 09:49:46 ] :: [ PASS ] :: Command 'mv ad1c870a-e111-4518-bf56-6e598899ba3c-logs.tar /var/log//tests/cli/test_build_and_deploy_azure.sh' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 18488s :: Assertions: 3 good, 0 bad :: RESULT: PASS (compose finished) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Upload image to Azure :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 09:50:18 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose image ad1c870a-e111-4518-bf56-6e598899ba3c' (Expected 0, got 0) :: [ 09:56:27 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_storageblob -a 'resource_group=composer storage_account_name=composerredhat container=composerredhat blob=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd src=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd blob_type=page'' (Expected 0, got 0) :: [ 09:57:11 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_image -a 'resource_group=composer name=Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import os_type=Linux location=eastus source=https://composerredhat.blob.core.windows.net/composerredhat/ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd'' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 445s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Upload image to Azure) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Start VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 09:57:11 ] :: [ PASS ] :: Command 'ssh-keygen -t rsa -N '' -f /tmp/composer-ssh-keys.6CSAGU/id_rsa' (Expected 0, got 0) :: [ 10:00:56 ] :: [ PASS ] :: Command 'ansible-playbook /tmp/composer-azure.GiiSz/azure-playbook.yaml' (Expected 0, got 0) :: [ 10:01:02 ] :: [ PASS ] :: Received VM info successfully (Assert: expected 0, got 0) :: [ 10:01:02 ] :: [ INFO ] :: localhost | SUCCESS => { "ansible_facts": { "azure_vm": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/virtualMachines/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "location": "eastus", "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "powerstate": "running", "properties": { "diagnosticsProfile": { "bootDiagnostics": { "enabled": false, "storageUri": null } }, "hardwareProfile": { "vmSize": "Standard_B2s" }, "instanceView": { "computerName": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "disks": [ { "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd", "statuses": [ { "code": "ProvisioningState/succeeded", "displayStatus": "Provisioning succeeded", "level": "Info", "time": "2019-11-21T09:58:12.538557Z" } ] } ], "osName": "redhat", "osVersion": "7.8", "statuses": [ { "code": "ProvisioningState/succeeded", "displayStatus": "Provisioning succeeded", "level": "Info", "time": "2019-11-21T10:00:33.663569Z" }, { "code": "PowerState/running", "displayStatus": "VM running", "level": "Info" } ], "vmAgent": { "extensionHandlers": [], "statuses": [ { "code": "ProvisioningState/succeeded", "displayStatus": "Ready", "level": "Info", "message": "Guest Agent is running", "time": "2019-11-21T10:01:01.000Z" } ], "vmAgentVersion": "2.2.45" } }, "networkProfile": { "networkInterfaces": [ { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkInterfaces/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "properties": { "dnsSettings": { "appliedDnsServers": [], "dnsServers": [], "internalDomainNameSuffix": "erlta3flxnyepcwscb53gqcc3g.bx.internal.cloudapp.net" }, "enableAcceleratedNetworking": false, "enableIPForwarding": false, "hostedWorkloads": [], "ipConfigurations": [ { "etag": "W/\"2e392b46-7fde-417f-a85d-3fe34a77065b\"", "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkInterfaces/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01/ipConfigurations/default", "name": "default", "properties": { "primary": true, "privateIPAddress": "10.0.1.5", "privateIPAddressVersion": "IPv4", "privateIPAllocationMethod": "Dynamic", "provisioningState": "Succeeded", "publicIPAddress": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/publicIPAddresses/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "properties": { "idleTimeoutInMinutes": 4, "ipAddress": "13.68.138.74", "ipConfiguration": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkInterfaces/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01/ipConfigurations/default" }, "ipTags": [], "provisioningState": "Succeeded", "publicIPAddressVersion": "IPv4", "publicIPAllocationMethod": "Static", "resourceGuid": "1b3ac0d8-9d86-4de1-b6fb-062ab74b794b" } }, "subnet": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/virtualNetworks/composervnet378/subnets/default" } } } ], "macAddress": "00-0D-3A-8D-BF-09", "networkSecurityGroup": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Network/networkSecurityGroups/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01" }, "primary": true, "provisioningState": "Succeeded", "resourceGuid": "a6c0691c-cfbe-4135-95f9-db72e382b06a", "tapConfigurations": [], "virtualMachine": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/virtualMachines/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c" } } } ] }, "osProfile": { "adminUsername": "azure-user", "allowExtensionOperations": true, "computerName": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c", "linuxConfiguration": { "disablePasswordAuthentication": true, "provisionVMAgent": true, "ssh": { "publicKeys": [ { "keyData": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCtixZjHLPLI/JBJ9VRFQnFLip5ZQ/t3huXKCdiv4tbyCplL3L3jVmYOXFNhGkiOTnb3AnqtVhPxuRHhm4HT0cxP6SUBE+8oxZJ7zsdeRuh8m5hsVsQbeVZhdf2YbYHBG0e6Tu94NZksUGaKI+9nySxqxe5ZXHEpdF7Jjfe765EU5WFLAGK22tOOd/ew/zBa+L+sk1BbmGcDXydk0ETNuSG78BZaMV6nPdATrV3K711lSijDFhsSLT9lcwj8PkyVswf9IyImhGCjloxwzzUdAVwxRvkB7EJGpjwpgKm0yGCn7ZRa51oqW1jNMlC+1SUk6i+HUH+iIBHwc11W59YLXTn root@m1.cockpit.lan", "path": "/home/azure-user/.ssh/authorized_keys" } ] } }, "secrets": [] }, "provisioningState": "Succeeded", "storageProfile": { "dataDisks": [], "imageReference": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/images/Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import" }, "osDisk": { "caching": "ReadOnly", "createOption": "FromImage", "diskSizeGB": 4, "managedDisk": { "id": "/subscriptions/8d026bb1-2a65-454d-a88f-c896db94c4f8/resourceGroups/composer/providers/Microsoft.Compute/disks/Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd", "storageAccountType": "Standard_LRS" }, "name": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c.vhd", "osType": "Linux" } }, "vmId": "2f4adadb-f71e-4917-bb40-cd7623e07594" }, "tags": { "_own_nic_": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "_own_nsg_": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "_own_pip_": "Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c01", "first_seen": "2019-11-21T14:57:11" }, "type": "Microsoft.Compute/virtualMachines", "zones": null } }, "changed": false, "powerstate_change": null } :: [ 10:01:02 ] :: [ INFO ] :: Running instance IP_ADDRESS=13.68.138.74 :: [ 10:01:02 ] :: [ INFO ] :: Waiting 60sec for instance to initialize ... :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 291s :: Assertions: 3 good, 0 bad :: RESULT: PASS (Start VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Verify VM instance :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:02:03 ] :: [ INFO ] :: verify_image: SSH_OPTS:'-o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa' SSH_USER:'azure-user' SSH_MACHINE: '13.68.138.74' :: [ 10:02:03 ] :: [ PASS ] :: Can't ssh to '13.68.138.74' as root using password-based auth (Expected 0, got 0) :: [ 10:02:06 ] :: [ PASS ] :: root account is disabled in /etc/shadow (Expected 0, got 0) :: [ 10:02:07 ] :: [ PASS ] :: audit.log contains entry about unsuccessful root login (Expected 0, got 0) :: [ 10:02:08 ] :: [ PASS ] :: Login with empty passwords is disabled in sshd config file (Expected 1, got 1) :: [ 10:02:09 ] :: [ PASS ] :: Command 'ssh -o StrictHostKeyChecking=no -o BatchMode=yes -i /tmp/composer-ssh-keys.6CSAGU/id_rsa azure-user@13.68.138.74 'cat /etc/redhat-release'' (Expected 0, got 0) :: [ 10:02:10 ] :: [ PASS ] :: System booted from the image contains specified parameter on kernel command line (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 7s :: Assertions: 6 good, 0 bad :: RESULT: PASS (Verify VM instance) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Cleanup :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:04:39 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_virtualmachine -a 'resource_group=composer name=Composer-Auto-VM-ad1c870a-e111-4518-bf56-6e598899ba3c location=eastus state=absent'' (Expected 0, got 0) :: [ 10:05:15 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_image -a 'resource_group=composer name=Composer-ad1c870a-e111-4518-bf56-6e598899ba3c-Automated-Import state=absent'' (Expected 0, got 0) :: [ 10:05:20 ] :: [ PASS ] :: Command 'ansible localhost -m azure_rm_storageblob -a 'resource_group=composer storage_account_name=composerredhat container=composerredhat blob=ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd state=absent'' (Expected 0, got 0) :: [ 10:05:20 ] :: [ PASS ] :: Command '/usr/bin/composer-cli compose delete ad1c870a-e111-4518-bf56-6e598899ba3c' (Expected 0, got 0) :: [ 10:05:21 ] :: [ PASS ] :: Command 'rm -rf ad1c870a-e111-4518-bf56-6e598899ba3c-disk.vhd /tmp/composer-ssh-keys.6CSAGU /tmp/composer-azure.GiiSz /tmp/venv.KHk' (Expected 0, got 0) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 191s :: Assertions: 5 good, 0 bad :: RESULT: PASS (Cleanup) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: /tests/cli/test_build_and_deploy_azure.sh :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 10:05:22 ] :: [ LOG ] :: JOURNAL TXT: /tmp/composer-test.3WeB6c/journal.txt :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 19527s :: Phases: 7 good, 0 bad :: OVERALL RESULT: PASS (/tests/cli/test_build_and_deploy_azure.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 100 301 100 301 0 0 30 0 0:00:10 0:00:09 0:00:01 60 100 301 100 301 0 0 30 0 0:00:10 0:00:09 0:00:01 75 "db_supported": true, Starting virtual machine 'rhel-7-8' Machine is up. Connect to it via: ssh -o ControlPath=/build/tmp/ssh-%h-%p-%r-1131 -p 2201 root@127.0.0.2 Waiting for lorax-composer to become ready... ok 1 TestCloud.test_azure