ROOT log: -----------------( last 500 lines )-----\n\n DEBUG util.py:282: bzip2-libs.x86_64 0:1.0.6-13.el7 DEBUG util.py:282: ca-certificates.noarch 0:2015.2.4-71.el7 DEBUG util.py:282: chkconfig.x86_64 0:1.3.61-5.el7 DEBUG util.py:282: cpp.x86_64 0:4.8.5-4.el7 DEBUG util.py:282: cracklib.x86_64 0:2.9.0-11.el7 DEBUG util.py:282: cracklib-dicts.x86_64 0:2.9.0-11.el7 DEBUG util.py:282: curl.x86_64 0:7.29.0-19.el7 DEBUG util.py:282: cyrus-sasl-lib.x86_64 0:2.1.26-19.2.el7 DEBUG util.py:282: dwz.x86_64 0:0.11-3.el7 DEBUG util.py:282: elfutils.x86_64 0:0.163-3.el7 DEBUG util.py:282: elfutils-libelf.x86_64 0:0.163-3.el7 DEBUG util.py:282: elfutils-libs.x86_64 0:0.163-3.el7 DEBUG util.py:282: expat.x86_64 0:2.1.0-8.el7 DEBUG util.py:282: file.x86_64 0:5.11-31.el7 DEBUG util.py:282: file-libs.x86_64 0:5.11-31.el7 DEBUG util.py:282: filesystem.x86_64 0:3.2-20.el7 DEBUG util.py:282: gdb.x86_64 0:7.6.1-80.el7 DEBUG util.py:282: gdbm.x86_64 0:1.10-8.el7 DEBUG util.py:282: glib2.x86_64 0:2.42.2-5.el7 DEBUG util.py:282: glibc.x86_64 0:2.17-105.el7 DEBUG util.py:282: glibc-common.x86_64 0:2.17-105.el7 DEBUG util.py:282: glibc-devel.x86_64 0:2.17-105.el7 DEBUG util.py:282: glibc-headers.x86_64 0:2.17-105.el7 DEBUG util.py:282: gmp.x86_64 1:6.0.0-12.el7_1 DEBUG util.py:282: gnupg2.x86_64 0:2.0.22-3.el7 DEBUG util.py:282: groff-base.x86_64 0:1.22.2-8.el7 DEBUG util.py:282: kernel-headers.x86_64 0:3.10.0-327.el7 DEBUG util.py:282: keyutils-libs.x86_64 0:1.5.8-3.el7 DEBUG util.py:282: krb5-libs.x86_64 0:1.13.2-10.el7 DEBUG util.py:282: libacl.x86_64 0:2.2.51-12.el7 DEBUG util.py:282: libassuan.x86_64 0:2.1.0-3.el7 DEBUG util.py:282: libattr.x86_64 0:2.4.46-12.el7 DEBUG util.py:282: libblkid.x86_64 0:2.23.2-26.el7 DEBUG util.py:282: libcap.x86_64 0:2.22-8.el7 DEBUG util.py:282: libcap-ng.x86_64 0:0.7.5-4.el7 DEBUG util.py:282: libcom_err.x86_64 0:1.42.9-7.el7 DEBUG util.py:282: libcurl.x86_64 0:7.29.0-19.el7 DEBUG util.py:282: libdb.x86_64 0:5.3.21-19.el7 DEBUG util.py:282: libdb-utils.x86_64 0:5.3.21-19.el7 DEBUG util.py:282: libffi.x86_64 0:3.0.13-16.el7 DEBUG util.py:282: libgcc.x86_64 0:4.8.5-4.el7 DEBUG util.py:282: libgcrypt.x86_64 0:1.5.3-12.el7_1.1 DEBUG util.py:282: libgomp.x86_64 0:4.8.5-4.el7 DEBUG util.py:282: libgpg-error.x86_64 0:1.12-3.el7 DEBUG util.py:282: libidn.x86_64 0:1.28-3.el7 DEBUG util.py:282: libmount.x86_64 0:2.23.2-26.el7 DEBUG util.py:282: libmpc.x86_64 0:1.0.1-3.el7 DEBUG util.py:282: libpwquality.x86_64 0:1.2.3-4.el7 DEBUG util.py:282: libselinux.x86_64 0:2.2.2-6.el7 DEBUG util.py:282: libsemanage.x86_64 0:2.1.10-18.el7 DEBUG util.py:282: libsepol.x86_64 0:2.1.9-3.el7 DEBUG util.py:282: libssh2.x86_64 0:1.4.3-10.el7 DEBUG util.py:282: libstdc++.x86_64 0:4.8.5-4.el7 DEBUG util.py:282: libstdc++-devel.x86_64 0:4.8.5-4.el7 DEBUG util.py:282: libtasn1.x86_64 0:3.8-2.el7 DEBUG util.py:282: libuser.x86_64 0:0.60-7.el7_1 DEBUG util.py:282: libutempter.x86_64 0:1.1.6-4.el7 DEBUG util.py:282: libuuid.x86_64 0:2.23.2-26.el7 DEBUG util.py:282: libverto.x86_64 0:0.2.5-4.el7 DEBUG util.py:282: libxml2.x86_64 0:2.9.1-5.el7_1.2 DEBUG util.py:282: lua.x86_64 0:5.1.4-14.el7 DEBUG util.py:282: mpfr.x86_64 0:3.1.1-4.el7 DEBUG util.py:282: ncurses.x86_64 0:5.9-13.20130511.el7 DEBUG util.py:282: ncurses-base.noarch 0:5.9-13.20130511.el7 DEBUG util.py:282: ncurses-libs.x86_64 0:5.9-13.20130511.el7 DEBUG util.py:282: nspr.x86_64 0:4.10.8-2.el7_1 DEBUG util.py:282: nss.x86_64 0:3.19.1-18.el7 DEBUG util.py:282: nss-softokn.x86_64 0:3.16.2.3-13.el7_1 DEBUG util.py:282: nss-softokn-freebl.x86_64 0:3.16.2.3-13.el7_1 DEBUG util.py:282: nss-sysinit.x86_64 0:3.19.1-18.el7 DEBUG util.py:282: nss-tools.x86_64 0:3.19.1-18.el7 DEBUG util.py:282: nss-util.x86_64 0:3.19.1-4.el7_1 DEBUG util.py:282: openldap.x86_64 0:2.4.40-8.el7 DEBUG util.py:282: openssl-libs.x86_64 1:1.0.1e-42.el7.9 DEBUG util.py:282: p11-kit.x86_64 0:0.20.7-3.el7 DEBUG util.py:282: p11-kit-trust.x86_64 0:0.20.7-3.el7 DEBUG util.py:282: pam.x86_64 0:1.1.8-12.el7_1.1 DEBUG util.py:282: pcre.x86_64 0:8.32-15.el7 DEBUG util.py:282: perl.x86_64 4:5.16.3-286.el7 DEBUG util.py:282: perl-Carp.noarch 0:1.26-244.el7 DEBUG util.py:282: perl-Encode.x86_64 0:2.51-7.el7 DEBUG util.py:282: perl-Exporter.noarch 0:5.68-3.el7 DEBUG util.py:282: perl-File-Path.noarch 0:2.09-2.el7 DEBUG util.py:282: perl-File-Temp.noarch 0:0.23.01-3.el7 DEBUG util.py:282: perl-Filter.x86_64 0:1.49-3.el7 DEBUG util.py:282: perl-Getopt-Long.noarch 0:2.40-2.el7 DEBUG util.py:282: perl-HTTP-Tiny.noarch 0:0.033-3.el7 DEBUG util.py:282: perl-PathTools.x86_64 0:3.40-5.el7 DEBUG util.py:282: perl-Pod-Escapes.noarch 1:1.04-286.el7 DEBUG util.py:282: perl-Pod-Perldoc.noarch 0:3.20-4.el7 DEBUG util.py:282: perl-Pod-Simple.noarch 1:3.28-4.el7 DEBUG util.py:282: perl-Pod-Usage.noarch 0:1.63-3.el7 DEBUG util.py:282: perl-Scalar-List-Utils.x86_64 0:1.27-248.el7 DEBUG util.py:282: perl-Socket.x86_64 0:2.010-3.el7 DEBUG util.py:282: perl-Storable.x86_64 0:2.45-3.el7 DEBUG util.py:282: perl-Text-ParseWords.noarch 0:3.29-4.el7 DEBUG util.py:282: perl-Thread-Queue.noarch 0:3.02-2.el7 DEBUG util.py:282: perl-Time-HiRes.x86_64 4:1.9725-3.el7 DEBUG util.py:282: perl-Time-Local.noarch 0:1.2300-2.el7 DEBUG util.py:282: perl-constant.noarch 0:1.27-2.el7 DEBUG util.py:282: perl-libs.x86_64 4:5.16.3-286.el7 DEBUG util.py:282: perl-macros.x86_64 4:5.16.3-286.el7 DEBUG util.py:282: perl-parent.noarch 1:0.225-244.el7 DEBUG util.py:282: perl-podlators.noarch 0:2.5.1-3.el7 DEBUG util.py:282: perl-srpm-macros.noarch 0:1-8.el7 DEBUG util.py:282: perl-threads.x86_64 0:1.87-4.el7 DEBUG util.py:282: perl-threads-shared.x86_64 0:1.43-6.el7 DEBUG util.py:282: pinentry.x86_64 0:0.8.1-14.el7 DEBUG util.py:282: pkgconfig.x86_64 1:0.27.1-4.el7 DEBUG util.py:282: popt.x86_64 0:1.13-16.el7 DEBUG util.py:282: pth.x86_64 0:2.0.7-23.el7 DEBUG util.py:282: python-libs.x86_64 0:2.7.5-34.el7 DEBUG util.py:282: readline.x86_64 0:6.2-9.el7 DEBUG util.py:282: rpm.x86_64 0:4.11.3-17.el7 DEBUG util.py:282: rpm-build-libs.x86_64 0:4.11.3-17.el7 DEBUG util.py:282: rpm-libs.x86_64 0:4.11.3-17.el7 DEBUG util.py:282: setup.noarch 0:2.8.71-6.el7 DEBUG util.py:282: shared-mime-info.x86_64 0:1.1-9.el7 DEBUG util.py:282: sqlite.x86_64 0:3.7.17-8.el7 DEBUG util.py:282: systemd-libs.x86_64 0:219-19.el7 DEBUG util.py:282: tzdata.noarch 0:2015g-1.el7 DEBUG util.py:282: ustr.x86_64 0:1.0.4-16.el7 DEBUG util.py:282: xz-libs.x86_64 0:5.1.2-12alpha.el7 DEBUG util.py:282: zip.x86_64 0:3.0-10.el7 DEBUG util.py:282: zlib.x86_64 0:1.2.7-15.el7 DEBUG util.py:372: Child return code was: 0 DEBUG util.py:92: remove tree: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir DEBUG util.py:332: Executing command: ['/usr/sbin/userdel', '-r', '-f', 'mockbuild'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:282: userdel: user 'mockbuild' does not exist DEBUG util.py:372: Child return code was: 6 DEBUG util.py:332: Executing command: ['/usr/sbin/groupdel', 'mockbuild'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:282: groupdel: group 'mockbuild' does not exist DEBUG util.py:372: Child return code was: 6 DEBUG util.py:332: Executing command: ['/usr/sbin/groupadd', '-g', '135', 'mockbuild'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: /usr/sbin/useradd -o -m -u 501 -g 135 -d /builddir -n mockbuild with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/RPMS DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/RPMS DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/SRPMS DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/SRPMS DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/SOURCES DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/SOURCES DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/SPECS DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/SPECS DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/BUILD DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/BUILD DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/BUILDROOT DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/BUILDROOT DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/originals DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/builddir/build/originals DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/proc/filesystems'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/pts'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/shm'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/sys'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/proc'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 INFO backend.py:652: Installed packages: DEBUG util.py:332: Executing command: rpm --root /srv/build/7/C/mock-root/c7.01.u-x86_64/root/ -qa with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:282: ncurses-base-5.9-13.20130511.el7.noarch DEBUG util.py:282: perl-srpm-macros-1-8.el7.noarch DEBUG util.py:282: setup-2.8.71-6.el7.noarch DEBUG util.py:282: filesystem-3.2-20.el7.x86_64 DEBUG util.py:282: nss-softokn-freebl-3.16.2.3-13.el7_1.x86_64 DEBUG util.py:282: glibc-common-2.17-105.el7.x86_64 DEBUG util.py:282: xz-libs-5.1.2-12alpha.el7.x86_64 DEBUG util.py:282: ncurses-libs-5.9-13.20130511.el7.x86_64 DEBUG util.py:282: pcre-8.32-15.el7.x86_64 DEBUG util.py:282: zlib-1.2.7-15.el7.x86_64 DEBUG util.py:282: bzip2-libs-1.0.6-13.el7.x86_64 DEBUG util.py:282: elfutils-libelf-0.163-3.el7.x86_64 DEBUG util.py:282: nss-util-3.19.1-4.el7_1.x86_64 DEBUG util.py:282: popt-1.13-16.el7.x86_64 DEBUG util.py:282: libattr-2.4.46-12.el7.x86_64 DEBUG util.py:282: libcap-2.22-8.el7.x86_64 DEBUG util.py:282: chkconfig-1.3.61-5.el7.x86_64 DEBUG util.py:282: sed-4.2.2-5.el7.x86_64 DEBUG util.py:282: libffi-3.0.13-16.el7.x86_64 DEBUG util.py:282: libmpc-1.0.1-3.el7.x86_64 DEBUG util.py:282: file-libs-5.11-31.el7.x86_64 DEBUG util.py:282: libcom_err-1.42.9-7.el7.x86_64 DEBUG util.py:282: p11-kit-0.20.7-3.el7.x86_64 DEBUG util.py:282: nss-softokn-3.16.2.3-13.el7_1.x86_64 DEBUG util.py:282: gawk-4.0.2-4.el7.x86_64 DEBUG util.py:282: expat-2.1.0-8.el7.x86_64 DEBUG util.py:282: elfutils-0.163-3.el7.x86_64 DEBUG util.py:282: cpp-4.8.5-4.el7.x86_64 DEBUG util.py:282: groff-base-1.22.2-8.el7.x86_64 DEBUG util.py:282: perl-HTTP-Tiny-0.033-3.el7.noarch DEBUG util.py:282: perl-Pod-Perldoc-3.20-4.el7.noarch DEBUG util.py:282: perl-Text-ParseWords-3.29-4.el7.noarch DEBUG util.py:282: perl-Pod-Usage-1.63-3.el7.noarch DEBUG util.py:282: perl-Storable-2.45-3.el7.x86_64 DEBUG util.py:282: perl-threads-1.87-4.el7.x86_64 DEBUG util.py:282: perl-Carp-1.26-244.el7.noarch DEBUG util.py:282: perl-Exporter-5.68-3.el7.noarch DEBUG util.py:282: perl-Time-Local-1.2300-2.el7.noarch DEBUG util.py:282: perl-threads-shared-1.43-6.el7.x86_64 DEBUG util.py:282: perl-File-Path-2.09-2.el7.noarch DEBUG util.py:282: perl-Scalar-List-Utils-1.27-248.el7.x86_64 DEBUG util.py:282: perl-Getopt-Long-2.40-2.el7.noarch DEBUG util.py:282: perl-Thread-Queue-3.02-2.el7.noarch DEBUG util.py:282: tar-1.26-29.el7.x86_64 DEBUG util.py:282: dwz-0.11-3.el7.x86_64 DEBUG util.py:282: libdb-utils-5.3.21-19.el7.x86_64 DEBUG util.py:282: zip-3.0-10.el7.x86_64 DEBUG util.py:282: libgomp-4.8.5-4.el7.x86_64 DEBUG util.py:282: cpio-2.11-24.el7.x86_64 DEBUG util.py:282: libxml2-2.9.1-5.el7_1.2.x86_64 DEBUG util.py:282: pkgconfig-0.27.1-4.el7.x86_64 DEBUG util.py:282: ncurses-5.9-13.20130511.el7.x86_64 DEBUG util.py:282: xz-5.1.2-12alpha.el7.x86_64 DEBUG util.py:282: libverto-0.2.5-4.el7.x86_64 DEBUG util.py:282: ustr-1.0.4-16.el7.x86_64 DEBUG util.py:282: glibc-headers-2.17-105.el7.x86_64 DEBUG util.py:282: libcap-ng-0.7.5-4.el7.x86_64 DEBUG util.py:282: p11-kit-trust-0.20.7-3.el7.x86_64 DEBUG util.py:282: openssl-libs-1.0.1e-42.el7.9.x86_64 DEBUG util.py:282: ca-certificates-2015.2.4-71.el7.noarch DEBUG util.py:282: binutils-2.23.52.0.1-55.el7.x86_64 DEBUG util.py:282: cracklib-2.9.0-11.el7.x86_64 DEBUG util.py:282: libpwquality-1.2.3-4.el7.x86_64 DEBUG util.py:282: systemd-libs-219-19.el7.x86_64 DEBUG util.py:282: libmount-2.23.2-26.el7.x86_64 DEBUG util.py:282: libutempter-1.1.6-4.el7.x86_64 DEBUG util.py:282: nss-3.19.1-18.el7.x86_64 DEBUG util.py:282: python-libs-2.7.5-34.el7.x86_64 DEBUG util.py:282: libssh2-1.4.3-10.el7.x86_64 DEBUG util.py:282: curl-7.29.0-19.el7.x86_64 DEBUG util.py:282: rpm-4.11.3-17.el7.x86_64 DEBUG util.py:282: libuser-0.60-7.el7_1.x86_64 DEBUG util.py:282: rpm-build-libs-4.11.3-17.el7.x86_64 DEBUG util.py:282: rpm-build-4.11.3-17.el7.x86_64 DEBUG util.py:282: gcc-c++-4.8.5-4.el7.x86_64 DEBUG util.py:282: make-3.82-21.el7.x86_64 DEBUG util.py:282: libgcc-4.8.5-4.el7.x86_64 DEBUG util.py:282: kernel-headers-3.10.0-327.el7.x86_64 DEBUG util.py:282: centos-release-7-1.1503.el7.centos.2.8.x86_64 DEBUG util.py:282: tzdata-2015g-1.el7.noarch DEBUG util.py:282: basesystem-10.0-7.el7.centos.noarch DEBUG util.py:282: bash-4.2.46-19.el7.x86_64 DEBUG util.py:282: glibc-2.17-105.el7.x86_64 DEBUG util.py:282: libstdc++-4.8.5-4.el7.x86_64 DEBUG util.py:282: libsepol-2.1.9-3.el7.x86_64 DEBUG util.py:282: libselinux-2.2.2-6.el7.x86_64 DEBUG util.py:282: info-5.1-4.el7.x86_64 DEBUG util.py:282: libdb-5.3.21-19.el7.x86_64 DEBUG util.py:282: nspr-4.10.8-2.el7_1.x86_64 DEBUG util.py:282: gmp-6.0.0-12.el7_1.x86_64 DEBUG util.py:282: readline-6.2-9.el7.x86_64 DEBUG util.py:282: libacl-2.2.51-12.el7.x86_64 DEBUG util.py:282: lua-5.1.4-14.el7.x86_64 DEBUG util.py:282: mpfr-3.1.1-4.el7.x86_64 DEBUG util.py:282: audit-libs-2.4.1-5.el7.x86_64 DEBUG util.py:282: libgpg-error-1.12-3.el7.x86_64 DEBUG util.py:282: grep-2.20-2.el7.x86_64 DEBUG util.py:282: libuuid-2.23.2-26.el7.x86_64 DEBUG util.py:282: libgcrypt-1.5.3-12.el7_1.1.x86_64 DEBUG util.py:282: sqlite-3.7.17-8.el7.x86_64 DEBUG util.py:282: elfutils-libs-0.163-3.el7.x86_64 DEBUG util.py:282: findutils-4.5.11-5.el7.x86_64 DEBUG util.py:282: gdbm-1.10-8.el7.x86_64 DEBUG util.py:282: file-5.11-31.el7.x86_64 DEBUG util.py:282: libassuan-2.1.0-3.el7.x86_64 DEBUG util.py:282: perl-parent-0.225-244.el7.noarch DEBUG util.py:282: perl-podlators-2.5.1-3.el7.noarch DEBUG util.py:282: perl-Pod-Escapes-1.04-286.el7.noarch DEBUG util.py:282: perl-Encode-2.51-7.el7.x86_64 DEBUG util.py:282: perl-libs-5.16.3-286.el7.x86_64 DEBUG util.py:282: perl-Time-HiRes-1.9725-3.el7.x86_64 DEBUG util.py:282: perl-constant-1.27-2.el7.noarch DEBUG util.py:282: perl-Filter-1.49-3.el7.x86_64 DEBUG util.py:282: perl-macros-5.16.3-286.el7.x86_64 DEBUG util.py:282: perl-Socket-2.010-3.el7.x86_64 DEBUG util.py:282: perl-File-Temp-0.23.01-3.el7.noarch DEBUG util.py:282: perl-PathTools-3.40-5.el7.x86_64 DEBUG util.py:282: perl-Pod-Simple-3.28-4.el7.noarch DEBUG util.py:282: perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: pinentry-0.8.1-14.el7.x86_64 DEBUG util.py:282: patch-2.7.1-8.el7.x86_64 DEBUG util.py:282: cyrus-sasl-lib-2.1.26-19.2.el7.x86_64 DEBUG util.py:282: bzip2-1.0.6-13.el7.x86_64 DEBUG util.py:282: unzip-6.0-15.el7.x86_64 DEBUG util.py:282: libidn-1.28-3.el7.x86_64 DEBUG util.py:282: diffutils-3.3-4.el7.x86_64 DEBUG util.py:282: glib2-2.42.2-5.el7.x86_64 DEBUG util.py:282: shared-mime-info-1.1-9.el7.x86_64 DEBUG util.py:282: libstdc++-devel-4.8.5-4.el7.x86_64 DEBUG util.py:282: pth-2.0.7-23.el7.x86_64 DEBUG util.py:282: keyutils-libs-1.5.8-3.el7.x86_64 DEBUG util.py:282: libsemanage-2.1.10-18.el7.x86_64 DEBUG util.py:282: glibc-devel-2.17-105.el7.x86_64 DEBUG util.py:282: libtasn1-3.8-2.el7.x86_64 DEBUG util.py:282: krb5-libs-1.13.2-10.el7.x86_64 DEBUG util.py:282: coreutils-8.22-15.el7.x86_64 DEBUG util.py:282: libblkid-2.23.2-26.el7.x86_64 DEBUG util.py:282: gzip-1.5-8.el7.x86_64 DEBUG util.py:282: cracklib-dicts-2.9.0-11.el7.x86_64 DEBUG util.py:282: pam-1.1.8-12.el7_1.1.x86_64 DEBUG util.py:282: gcc-4.8.5-4.el7.x86_64 DEBUG util.py:282: shadow-utils-4.1.5.1-18.el7.x86_64 DEBUG util.py:282: nss-sysinit-3.19.1-18.el7.x86_64 DEBUG util.py:282: nss-tools-3.19.1-18.el7.x86_64 DEBUG util.py:282: gdb-7.6.1-80.el7.x86_64 DEBUG util.py:282: libcurl-7.29.0-19.el7.x86_64 DEBUG util.py:282: rpm-libs-4.11.3-17.el7.x86_64 DEBUG util.py:282: openldap-2.4.40-8.el7.x86_64 DEBUG util.py:282: gnupg2-2.0.22-3.el7.x86_64 DEBUG util.py:282: redhat-rpm-config-9.1.0-68.el7.centos.noarch DEBUG util.py:282: util-linux-2.23.2-26.el7.x86_64 DEBUG util.py:282: which-2.20-7.el7.x86_64 DEBUG util.py:372: Child return code was: 0 DEBUG util.py:92: remove tree: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/pts DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/pts DEBUG util.py:65: ensuring that dir exists: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/shm DEBUG util.py:68: creating dir: /srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/shm DEBUG backend.py:482: kver == 2.6.32-220.17.1.el6.x86_64 DEBUG util.py:332: Executing command: ['/bin/mount', '-n', '-t', 'proc', 'proc', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/proc'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/mount', '-n', '-t', 'sysfs', 'mock_chroot_sys', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/sys'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/mount', '-n', '-t', 'tmpfs', 'mock_chroot_shmfs', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/shm'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/mount', '-n', '-t', 'devpts', '-o', 'gid=5,mode=0620,ptmxmode=0666,newinstance', 'mock_chroot_devpts', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/pts'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/mount', '-n', '--bind', '/tmp/mock-selinux-plugin.JsggnP', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/proc/filesystems'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['rpm', '-Uvh', '--nodeps', '/builddir/build/originals/curl-7.29.0-25.el7.src.rpm'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:282: warning: /builddir/build/originals/curl-7.29.0-25.el7.src.rpm: Header V3 RSA/SHA256 Signature, key ID fd431d51: NOKEY DEBUG util.py:282: Updating / installing... DEBUG util.py:282: curl-7.29.0-25.el7 ######################################## DEBUG util.py:372: Child return code was: 0 DEBUG backend.py:979: ['/usr/bin/yum-builddep', '--installroot', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root///builddir/build/SRPMS/curl-7.29.0-25.el7.src.rpm'] DEBUG util.py:332: Executing command: ['/usr/bin/yum-builddep', '--installroot', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root///builddir/build/SRPMS/curl-7.29.0-25.el7.src.rpm', '--setopt=tsflags=nocontexts'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'PROMPT_COMMAND': 'echo -n "<mock-chroot>"', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:282: Getting requirements for curl-7.29.0-25.el7.src DEBUG util.py:282: --> groff-1.22.2-8.el7.x86_64 DEBUG util.py:282: --> krb5-devel-1.13.2-10.el7.x86_64 DEBUG util.py:282: --> libidn-devel-1.28-3.el7.x86_64 DEBUG util.py:282: --> libssh2-devel-1.4.3-10.el7.x86_64 DEBUG util.py:282: --> nss-devel-3.19.1-18.el7.x86_64 DEBUG util.py:282: --> openldap-devel-2.4.40-8.el7.x86_64 DEBUG util.py:282: --> openssh-clients-6.6.1p1-22.el7.x86_64 DEBUG util.py:282: --> openssh-server-6.6.1p1-22.el7.x86_64 DEBUG util.py:282: --> Already installed : 1:pkgconfig-0.27.1-4.el7.x86_64 DEBUG util.py:282: --> stunnel-4.56-4.el7.x86_64 DEBUG util.py:282: --> zlib-devel-1.2.7-15.el7.x86_64 DEBUG util.py:282: --> Already installed : perl-PathTools-3.40-5.el7.x86_64 DEBUG util.py:282: --> perl-Digest-MD5-2.52-3.el7.x86_64 DEBUG util.py:282: --> Already installed : perl-Exporter-5.68-3.el7.noarch DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> Already installed : perl-PathTools-3.40-5.el7.x86_64 DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> Already installed : perl-Time-Local-1.2300-2.el7.noarch DEBUG util.py:282: --> Already installed : 4:perl-Time-HiRes-1.9725-3.el7.x86_64 DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.x86_64 DEBUG util.py:282: --> 1:valgrind-3.10.0-16.el7.x86_64 DEBUG util.py:282: ================================================================================ DEBUG util.py:282: Package Arch Version Repository Size DEBUG util.py:282: ================================================================================ DEBUG util.py:282: Installing: DEBUG util.py:282: groff x86_64 1.22.2-8.el7 c7.00.03 1.1 M DEBUG util.py:282: krb5-devel x86_64 1.13.2-10.el7 c7.1511.00 648 k DEBUG util.py:282: libidn-devel x86_64 1.28-3.el7 c7.00.03 122 k DEBUG util.py:282: libssh2-devel x86_64 1.4.3-10.el7 c7.1511.00 53 k DEBUG util.py:282: nss-devel x86_64 3.19.1-18.el7 c7.1511.00 208 k DEBUG util.py:282: openldap-devel x86_64 2.4.40-8.el7 c7.1511.00 798 k DEBUG util.py:282: openssh-clients x86_64 6.6.1p1-22.el7 c7.1511.00 637 k DEBUG util.py:282: openssh-server x86_64 6.6.1p1-22.el7 c7.1511.00 435 k DEBUG util.py:282: perl-Digest-MD5 x86_64 2.52-3.el7 c7.00.03 29 k DEBUG util.py:282: stunnel x86_64 4.56-4.el7 c7.00.03 145 k DEBUG util.py:282: valgrind x86_64 1:3.10.0-16.el7 c7.1511.00 15 M DEBUG util.py:282: zlib-devel x86_64 1.2.7-15.el7 c7.1511.00 49 k DEBUG util.py:282: Installing for dependencies: DEBUG util.py:282: acl x86_64 2.2.51-12.el7 c7.00.03 80 k DEBUG util.py:282: cryptsetup-libs x86_64 1.6.7-1.el7 c7.1511.00 181 k DEBUG util.py:282: cyrus-sasl x86_64 2.1.26-19.2.el7 c7.1511.00 87 k DEBUG util.py:282: cyrus-sasl-devel x86_64 2.1.26-19.2.el7 c7.1511.00 308 k DEBUG util.py:282: dbus x86_64 1:1.6.12-13.el7 c7.1511.00 305 k DEBUG util.py:282: dbus-libs x86_64 1:1.6.12-13.el7 c7.1511.00 150 k DEBUG util.py:282: device-mapper x86_64 7:1.02.93-3.el7_1.1 c7.01.u 208 k DEBUG util.py:282: device-mapper-libs x86_64 7:1.02.93-3.el7_1.1 c7.01.u 258 k DEBUG util.py:282: dracut x86_64 033-359.el7 c7.1511.00 309 k DEBUG util.py:282: fipscheck x86_64 1.4.1-5.el7 c7.00.03 20 k DEBUG util.py:282: fipscheck-lib x86_64 1.4.1-5.el7 c7.00.03 9.4 k DEBUG util.py:282: hardlink x86_64 1:1.0-19.el7 c7.00.03 13 k DEBUG util.py:282: hostname x86_64 3.13-3.el7 c7.00.03 16 k DEBUG util.py:282: initscripts x86_64 9.49.30-1.el7 c7.1511.00 428 k DEBUG util.py:282: iproute x86_64 3.10.0-54.el7 c7.1511.00 525 k DEBUG util.py:282: iptables x86_64 1.4.21-16.el7 c7.1511.00 423 k DEBUG util.py:282: iputils x86_64 20121221-7.el7 c7.1511.00 151 k DEBUG util.py:282: keyutils-libs-devel x86_64 1.5.8-3.el7 c7.00.03 36 k DEBUG util.py:282: kmod x86_64 20-5.el7 c7.1511.00 113 k DEBUG util.py:282: kmod-libs x86_64 20-5.el7 c7.1511.00 45 k DEBUG util.py:282: kpartx x86_64 0.4.9-85.el7 c7.1511.00 58 k DEBUG util.py:282: libcom_err-devel x86_64 1.42.9-7.el7 c7.01.00 29 k DEBUG util.py:282: libedit x86_64 3.0-12.20121213cvs.el7 c7.00.03 91 k DEBUG util.py:282: libmnl x86_64 1.0.3-7.el7 c7.00.04 22 k DEBUG util.py:282: libnetfilter_conntrack x86_64 1.0.4-2.el7 c7.00.03 52 k DEBUG util.py:282: libnfnetlink x86_64 1.0.1-4.el7 c7.00.03 25 k DEBUG util.py:282: libselinux-devel x86_64 2.2.2-6.el7 c7.00.03 173 k DEBUG util.py:282: libsepol-devel x86_64 2.1.9-3.el7 c7.00.03 70 k DEBUG util.py:282: libverto-devel x86_64 0.2.5-4.el7 c7.00.03 10 k DEBUG util.py:282: nspr-devel x86_64 4.10.8-2.el7_1 c7.01.u 112 k DEBUG util.py:282: nss-softokn-devel x86_64 3.16.2.3-13.el7_1 c7.01.u 25 k DEBUG util.py:282: nss-softokn-freebl-devel x86_64 3.16.2.3-13.el7_1 c7.01.u 45 k DEBUG util.py:282: nss-util-devel x86_64 3.19.1-4.el7_1 c7.01.u 68 k DEBUG util.py:282: openssh x86_64 6.6.1p1-22.el7 c7.1511.00 433 k DEBUG util.py:282: openssl-devel x86_64 1:1.0.1e-42.el7.9 c7.01.u 1.2 M DEBUG util.py:282: pcre-devel x86_64 8.32-15.el7 c7.1511.00 477 k DEBUG util.py:282: perl-Digest noarch 1.17-245.el7 c7.00.03 22 k DEBUG util.py:282: procps-ng x86_64 3.3.10-3.el7 c7.01.00 285 k DEBUG util.py:282: qrencode-libs x86_64 3.4.1-3.el7 c7.00.03 49 k DEBUG util.py:282: systemd x86_64 219-19.el7 c7.1511.00 5.1 M DEBUG util.py:282: sysvinit-tools x86_64 2.88-14.dsf.el7 c7.00.03 62 k DEBUG util.py:282: tcp_wrappers-libs x86_64 7.6-77.el7 c7.00.03 65 k DEBUG util.py:282: Transaction Summary DEBUG util.py:282: ================================================================================ DEBUG util.py:282: Install 54 Package(s) DEBUG util.py:282: Total download size: 32 M DEBUG util.py:282: Installed size: 96 M DEBUG util.py:282: Installed: DEBUG util.py:282: groff.x86_64 0:1.22.2-8.el7 DEBUG util.py:282: krb5-devel.x86_64 0:1.13.2-10.el7 DEBUG util.py:282: libidn-devel.x86_64 0:1.28-3.el7 DEBUG util.py:282: libssh2-devel.x86_64 0:1.4.3-10.el7 DEBUG util.py:282: nss-devel.x86_64 0:3.19.1-18.el7 DEBUG util.py:282: openldap-devel.x86_64 0:2.4.40-8.el7 DEBUG util.py:282: openssh-clients.x86_64 0:6.6.1p1-22.el7 DEBUG util.py:282: openssh-server.x86_64 0:6.6.1p1-22.el7 DEBUG util.py:282: perl-Digest-MD5.x86_64 0:2.52-3.el7 DEBUG util.py:282: stunnel.x86_64 0:4.56-4.el7 DEBUG util.py:282: valgrind.x86_64 1:3.10.0-16.el7 DEBUG util.py:282: zlib-devel.x86_64 0:1.2.7-15.el7 DEBUG util.py:282: Dependency Installed: DEBUG util.py:282: acl.x86_64 0:2.2.51-12.el7 DEBUG util.py:282: cryptsetup-libs.x86_64 0:1.6.7-1.el7 DEBUG util.py:282: cyrus-sasl.x86_64 0:2.1.26-19.2.el7 DEBUG util.py:282: cyrus-sasl-devel.x86_64 0:2.1.26-19.2.el7 DEBUG util.py:282: dbus.x86_64 1:1.6.12-13.el7 DEBUG util.py:282: dbus-libs.x86_64 1:1.6.12-13.el7 DEBUG util.py:282: device-mapper.x86_64 7:1.02.93-3.el7_1.1 DEBUG util.py:282: device-mapper-libs.x86_64 7:1.02.93-3.el7_1.1 DEBUG util.py:282: dracut.x86_64 0:033-359.el7 DEBUG util.py:282: fipscheck.x86_64 0:1.4.1-5.el7 DEBUG util.py:282: fipscheck-lib.x86_64 0:1.4.1-5.el7 DEBUG util.py:282: hardlink.x86_64 1:1.0-19.el7 DEBUG util.py:282: hostname.x86_64 0:3.13-3.el7 DEBUG util.py:282: initscripts.x86_64 0:9.49.30-1.el7 DEBUG util.py:282: iproute.x86_64 0:3.10.0-54.el7 DEBUG util.py:282: iptables.x86_64 0:1.4.21-16.el7 DEBUG util.py:282: iputils.x86_64 0:20121221-7.el7 DEBUG util.py:282: keyutils-libs-devel.x86_64 0:1.5.8-3.el7 DEBUG util.py:282: kmod.x86_64 0:20-5.el7 DEBUG util.py:282: kmod-libs.x86_64 0:20-5.el7 DEBUG util.py:282: kpartx.x86_64 0:0.4.9-85.el7 DEBUG util.py:282: libcom_err-devel.x86_64 0:1.42.9-7.el7 DEBUG util.py:282: libedit.x86_64 0:3.0-12.20121213cvs.el7 DEBUG util.py:282: libmnl.x86_64 0:1.0.3-7.el7 DEBUG util.py:282: libnetfilter_conntrack.x86_64 0:1.0.4-2.el7 DEBUG util.py:282: libnfnetlink.x86_64 0:1.0.1-4.el7 DEBUG util.py:282: libselinux-devel.x86_64 0:2.2.2-6.el7 DEBUG util.py:282: libsepol-devel.x86_64 0:2.1.9-3.el7 DEBUG util.py:282: libverto-devel.x86_64 0:0.2.5-4.el7 DEBUG util.py:282: nspr-devel.x86_64 0:4.10.8-2.el7_1 DEBUG util.py:282: nss-softokn-devel.x86_64 0:3.16.2.3-13.el7_1 DEBUG util.py:282: nss-softokn-freebl-devel.x86_64 0:3.16.2.3-13.el7_1 DEBUG util.py:282: nss-util-devel.x86_64 0:3.19.1-4.el7_1 DEBUG util.py:282: openssh.x86_64 0:6.6.1p1-22.el7 DEBUG util.py:282: openssl-devel.x86_64 1:1.0.1e-42.el7.9 DEBUG util.py:282: pcre-devel.x86_64 0:8.32-15.el7 DEBUG util.py:282: perl-Digest.noarch 0:1.17-245.el7 DEBUG util.py:282: procps-ng.x86_64 0:3.3.10-3.el7 DEBUG util.py:282: qrencode-libs.x86_64 0:3.4.1-3.el7 DEBUG util.py:282: systemd.x86_64 0:219-19.el7 DEBUG util.py:282: sysvinit-tools.x86_64 0:2.88-14.dsf.el7 DEBUG util.py:282: tcp_wrappers-libs.x86_64 0:7.6-77.el7 DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/proc/filesystems'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/pts'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/dev/shm'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/sys'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '-l', '/srv/build/7/C/mock-root/c7.01.u-x86_64/root/proc'] with env {'LANG': 'en_GB', 'TERM': 'vt100', 'SHELL': '/bin/bash', 'HOSTNAME': 'mock', 'HOME': '/builddir', 'PATH': '/usr/bin:/bin:/usr/sbin:/sbin'} DEBUG util.py:372: Child return code was: 0 DEBUG util.py:120: kill orphans DEBUG util.py:92: remove tree: /srv/build/7/C/mock-root/c7.01.u-x86_64.tmp INFO backend.py:227: chroot (/srv/build/7/C/mock-root/c7.01.u-x86_64) unlocked and deleted DEBUG util.py:120: kill orphans --------------------------------------------- BUILD log: ----------------( last 500 lines )-----\n\n test 1384...[FTP DL, file without C-D inside, using -o fname -i -D stdout] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1384 ../src/curl --trace-ascii log/trace1384 --trace-time ftp://127.0.0.1:6492/path/file1384 -o log/download1384 -i -D - >log/stdout1384 2>log/stderr1384 postcheck perl ./libtest/notexists.pl log/file1384 ---p-oe-v OK (726 out of 793, remaining: 02:44) test 1385...[FTP DL, file without C-D inside, using -o fname -i, without -D] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1385 ../src/curl --trace-ascii log/trace1385 --trace-time ftp://127.0.0.1:6492/path/file1385 -o log/download1385 -i >log/stdout1385 2>log/stderr1385 postcheck perl ./libtest/notexists.pl log/file1385 ---p-oe-v OK (727 out of 793, remaining: 02:42) test 1386...[FTP DL, file with Content-Disposition inside, using -o fname] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1386 ../src/curl --trace-ascii log/trace1386 --trace-time ftp://127.0.0.1:6492/path/file1386 -o log/download1386 >log/stdout1386 2>log/stderr1386 postcheck perl ./libtest/notexists.pl log/file1386 log/name1386 ---p-oe-v OK (728 out of 793, remaining: 02:39) test 1387...[FTP DL, file with C-D inside, using -o fname -D file] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1387 ../src/curl --trace-ascii log/trace1387 --trace-time ftp://127.0.0.1:6492/path/file1387 -o log/download1387 -D log/heads1387 >log/stdout1387 2>log/stderr1387 postcheck perl ./libtest/notexists.pl log/file1387 log/name1387 ---p-oe-v OK (729 out of 793, remaining: 02:37) test 1388...[FTP DL, file with C-D inside, using -o fname -D stdout] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1388 ../src/curl --trace-ascii log/trace1388 --trace-time ftp://127.0.0.1:6492/path/file1388 -o log/download1388 -D - >log/stdout1388 2>log/stderr1388 postcheck perl ./libtest/notexists.pl log/file1388 log/name1388 ---p-oe-v OK (730 out of 793, remaining: 02:34) test 1389...[FTP DL, file with C-D inside, using -o fname -J -D file] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1389 ../src/curl --trace-ascii log/trace1389 --trace-time ftp://127.0.0.1:6492/path/file1389 -o log/download1389 -J -D log/heads1389 >log/stdout1389 2>log/stderr1389 postcheck perl ./libtest/notexists.pl log/file1389 log/name1389 ---p-oe-v OK (731 out of 793, remaining: 02:32) test 1390...[FTP DL, file with C-D inside, using -o fname -J -D stdout] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1390 ../src/curl --trace-ascii log/trace1390 --trace-time ftp://127.0.0.1:6492/path/file1390 -o log/download1390 -J -D - >log/stdout1390 2>log/stderr1390 postcheck perl ./libtest/notexists.pl log/file1390 log/name1390 ---p-oe-v OK (732 out of 793, remaining: 02:29) test 1391...[FTP DL, file with C-D inside, using -o fname -i -D file] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1391 ../src/curl --trace-ascii log/trace1391 --trace-time ftp://127.0.0.1:6492/path/file1391 -o log/download1391 -i -D log/heads1391 >log/stdout1391 2>log/stderr1391 postcheck perl ./libtest/notexists.pl log/file1391 log/name1391 ---p-oe-v OK (733 out of 793, remaining: 02:26) test 1392...[FTP DL, file with C-D inside, using -o fname -i -D stdout] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1392 ../src/curl --trace-ascii log/trace1392 --trace-time ftp://127.0.0.1:6492/path/file1392 -o log/download1392 -i -D - >log/stdout1392 2>log/stderr1392 postcheck perl ./libtest/notexists.pl log/file1392 log/name1392 ---p-oe-v OK (734 out of 793, remaining: 02:24) test 1393...[FTP DL, file with C-D inside, using -o fname -i, without -D] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1393 ../src/curl --trace-ascii log/trace1393 --trace-time ftp://127.0.0.1:6492/path/file1393 -o log/download1393 -i >log/stdout1393 2>log/stderr1393 postcheck perl ./libtest/notexists.pl log/file1393 log/name1393 ---p-oe-v OK (735 out of 793, remaining: 02:21) test 1400...[--libcurl for simple HTTP GET] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1400 ../src/curl --output log/curl1400.out --include --trace-ascii log/trace1400 --trace-time http://127.0.0.1:6490/we/want/1400 --libcurl log/test1400.c >log/stdout1400 2>log/stderr1400 -d-p-oe-v OK (736 out of 793, remaining: 02:19) test 1401...[--libcurl for GET with various options] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1401 ../src/curl --output log/curl1401.out --include --trace-ascii log/trace1401 --trace-time http://127.0.0.1:6490/we/want/1401 --libcurl log/test1401.c --basic -u fake:user -H "X-Files: Mulder" -H "X-Men: cyclops, iceman" -A MyUA -b chocolate=chip --proto "=http,ftp,file" >log/stdout1401 2>log/stderr1401 -d-p-oe-v OK (737 out of 793, remaining: 02:16) test 1402...[--libcurl for simple POST] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1402 ../src/curl --output log/curl1402.out --include --trace-ascii log/trace1402 --trace-time http://127.0.0.1:6490/we/want/1402 --libcurl log/test1402.c -d "foo=bar" -d "baz=quux" >log/stdout1402 2>log/stderr1402 -d-p-oe-v OK (738 out of 793, remaining: 02:14) test 1403...[--libcurl for GET with query] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1403 ../src/curl --output log/curl1403.out --include --trace-ascii log/trace1403 --trace-time http://127.0.0.1:6490/we/want/1403 --libcurl log/test1403.c -G -d "foo=bar" -d "baz=quux" >log/stdout1403 2>log/stderr1403 -d-p-oe-v OK (739 out of 793, remaining: 02:11) test 1404...[HTTP RFC1867-type formposting - -F with three files, one with explicit type] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1404 ../src/curl --output log/curl1404.out --include --trace-ascii log/trace1404 --trace-time http://127.0.0.1:6490/we/want/1404 -F name=value -F 'file=@log/test1404.txt,log/test1404.txt;type=magic/content,log/test1404.txt' --libcurl log/test1404.c >log/stdout1404 2>log/stderr1404 -d-p-oe-v OK (740 out of 793, remaining: 02:09) test 1405...[FTP with quote ops] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1405 ../src/curl --output log/curl1405.out --include --trace-ascii log/trace1405 --trace-time ftp://127.0.0.1:6492/1405 -Q "NOOP 1" -Q "+NOOP 2" -Q "-NOOP 3" -Q "*FAIL" -Q "+*FAIL HARD" --libcurl log/test1405.c >log/stdout1405 2>log/stderr1405 -d-p-oe-v OK (741 out of 793, remaining: 02:06) test 1406...[SMTP] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1406 ../src/curl --output log/curl1406.out --include --trace-ascii log/trace1406 --trace-time smtp://127.0.0.1:6505/user --mail-rcpt 1406 at foo --mail-rcpt 1406 at foobar.example --mail-from 1406 at from -T log/test1406.eml --libcurl log/test1406.c >log/stdout1406 2>log/stderr1406 --up-oe-v OK (742 out of 793, remaining: 02:04) test 1407...[POP3 LIST one message] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1407 ../src/curl --output log/curl1407.out --include --trace-ascii log/trace1407 --trace-time pop3://127.0.0.1:6501/1407 -l -u user:secret --libcurl log/test1407.c >log/stdout1407 2>log/stderr1407 ---p-oe-v OK (743 out of 793, remaining: 02:01) test 1408...[HTTP receive cookies over IPV6] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1408 ../src/curl --output log/curl1408.out --include --trace-ascii log/trace1408 --trace-time -c log/jar1408 -g http://[::1]:6494/path/14080001 http://[::1]:6494/path/14080002 >log/stdout1408 2>log/stderr1408 ---p--e-v OK (744 out of 793, remaining: 01:59) * starts no server test 1409...[Pass in string to -C] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1409 ../src/curl --output log/curl1409.out --include --trace-ascii log/trace1409 --trace-time http://127.0.0.1:6490/1409 -C wrong >log/stdout1409 2>log/stderr1409 ------e-v OK (745 out of 793, remaining: 01:56) * starts no server test 1410...[Pass in negative number to --max-time] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1410 ../src/curl --output log/curl1410.out --include --trace-ascii log/trace1410 --trace-time http://127.0.0.1:6490/1410 --max-time -4 >log/stdout1410 2>log/stderr1410 ------e-v OK (746 out of 793, remaining: 01:54) test 1411...[SMTP without SIZE support] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1411 ../src/curl --output log/curl1411.out --include --trace-ascii log/trace1411 --trace-time smtp://127.0.0.1:6505/user --mail-rcpt 1411 at foo --mail-rcpt 1411 at foobar.example --mail-from 1411 at from -T log/test1411.eml >log/stdout1411 2>log/stderr1411 --up--e-v OK (747 out of 793, remaining: 01:51) test 1412...[HTTP GET with --anyauth with two URLs (picking Digest) ] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1412 ../src/curl --output log/curl1412.out --include --trace-ascii log/trace1412 --trace-time http://127.0.0.1:6490/1412 -u testuser:testpass --anyauth http://127.0.0.1:6490/14120001 >log/stdout1412 2>log/stderr1412 -d-p--e-v OK (748 out of 793, remaining: 01:49) test 1413...[HTTP redirect with fragment in new URL] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1413 ../src/curl --output log/curl1413.out --include --trace-ascii log/trace1413 --trace-time http://127.0.0.1:6490/this/1413 -L >log/stdout1413 2>log/stderr1413 -d-p--e-v OK (749 out of 793, remaining: 01:46) test 1415...[Delete expired cookies] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1415 ../src/curl --output log/curl1415.out --include --trace-ascii log/trace1415 --trace-time http://example.com/we/want/1415 -b none -c log/jar1415.txt -x 127.0.0.1:6490 >log/stdout1415 2>log/stderr1415 -d-p-oe-v OK (750 out of 793, remaining: 01:51) test 1500...[curl_multi_wait] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1500 ./libtest/lib1500 http://127.0.0.1:6490/1500 >log/stdout1500 2>log/stderr1500 -d----e-v OK (751 out of 793, remaining: 01:48) test 1501...[FTP with multi interface and slow LIST response ] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1501 ./libtest/lib1501 ftp://127.0.0.1:6492/1501/ >log/stdout1501 2>log/stderr1501 ---p--e-v OK (752 out of 793, remaining: 01:46) test 1502...[HTTP multi with CURLOPT_RESOLVE, cleanup sequence UA] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1502 ./libtest/lib1502 http://google.com:6490/1502 6490 127.0.0.1 >log/stdout1502 2>log/stderr1502 -d-p--e-v OK (753 out of 793, remaining: 01:43) test 1503...[HTTP multi with CURLOPT_RESOLVE, cleanup sequence PA] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1503 ./libtest/lib1503 http://google.com:6490/1503 6490 127.0.0.1 >log/stdout1503 2>log/stderr1503 -d-p--e-v OK (754 out of 793, remaining: 01:40) test 1504...[HTTP multi with CURLOPT_RESOLVE, cleanup sequence UB] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1504 ./libtest/lib1504 http://google.com:6490/1504 6490 127.0.0.1 >log/stdout1504 2>log/stderr1504 -d-p--e-v OK (755 out of 793, remaining: 01:38) test 1505...[HTTP multi with CURLOPT_RESOLVE, cleanup sequence PB] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1505 ./libtest/lib1505 http://google.com:6490/1505 6490 127.0.0.1 >log/stdout1505 2>log/stderr1505 -d-p--e-v OK (756 out of 793, remaining: 01:35) test 1506...[HTTP GET connection cache limit (CURLMOPT_MAXCONNECTS)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1506 ./libtest/lib1506 http://127.0.0.1:6490/path/1506 127.0.0.1 6490 >log/stdout1506 2>log/stderr1506 ---p-oe-v OK (757 out of 793, remaining: 01:33) test 1507...[SMTP with multi interface and CURLE_ABORTED_BY_CALLBACK] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1507 ./libtest/lib1507 smtp://127.0.0.1:6505/user <log/stdin-for-1507 >log/stdout1507 2>log/stderr1507 ---p--e-v OK (758 out of 793, remaining: 01:30) * starts no server test 1508...[Close a multi handle without using it] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1508 ./libtest/lib1508 http://127.0.0.1:6490/path/1508 >log/stdout1508 2>log/stderr1508 -----oe-v OK (759 out of 793, remaining: 01:27) test 1529...[HTTP request-injection in URL sent over proxy] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind1529 ./libtest/lib1529 "http://the.old.moo:6490/1529" 127.0.0.1:6513 >log/stdout1529 2>log/stderr1529 ------e-v OK (760 out of 793, remaining: 01:25) test 2000...[FTP RETR followed by FILE] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2000 ../src/curl --include --trace-ascii log/trace2000 --trace-time ftp://127.0.0.1:6492/2000 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2000.txt >log/stdout2000 2>log/stderr2000 s--p--e-v OK (761 out of 793, remaining: 01:22) test 2001...[HTTP GET followed by FTP RETR followed by FILE] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2001 ../src/curl --include --trace-ascii log/trace2001 --trace-time http://127.0.0.1:6490/20010001 ftp://127.0.0.1:6492/20010002 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2001.txt >log/stdout2001 2>log/stderr2001 s--p--e-v OK (762 out of 793, remaining: 01:19) test 2002...[HTTP GET followed by FTP RETR followed by FILE followed by TFTP RRQ] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2002 ../src/curl --include --trace-ascii log/trace2002 --trace-time http://127.0.0.1:6490/20020001 ftp://127.0.0.1:6492/20020002 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2002.txt tftp://127.0.0.1:6497//20020003 >log/stdout2002 2>log/stderr2002 s--p--e-v OK (763 out of 793, remaining: 01:17) test 2003...[HTTP GET followed by FTP RETR followed by FILE followed by TFTP RRQ then again in reverse order] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2003 ../src/curl --include --trace-ascii log/trace2003 --trace-time http://127.0.0.1:6490/20030001 ftp://127.0.0.1:6492/20030002 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2003.txt tftp://127.0.0.1:6497//20030003 tftp://127.0.0.1:6497//20030003 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2003.txt ftp://127.0.0.1:6492/20030002 http://127.0.0.1:6490/20030001 >log/stdout2003 2>log/stderr2003 s--p--e-v OK (764 out of 793, remaining: 01:14) test 2004...[TFTP RRQ followed by SFTP retrieval followed by FILE followed by SCP retrieval then again in reverse order] ../src/curl --include --trace-ascii log/trace2004 --trace-time --key curl_client_key --pubkey curl_client_key.pub -u mockbuild: tftp://127.0.0.1:6497//2004 sftp://127.0.0.1:6499/builddir/build/BUILD/curl-7.29.0/tests/log/test2004.txt file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2004.txt scp://127.0.0.1:6499/builddir/build/BUILD/curl-7.29.0/tests/log/test2004.txt file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2004.txt sftp://127.0.0.1:6499/builddir/build/BUILD/curl-7.29.0/tests/log/test2004.txt tftp://127.0.0.1:6497//2004 --insecure >log/stdout2004 2>log/stderr2004 valgrind SKIPPED s--p--e-- OK (765 out of 793, remaining: 01:12) test 2005 SKIPPED: curl lacks Metalink support test 2008 SKIPPED: curl lacks Metalink support test 2009 SKIPPED: curl lacks Metalink support prechecked ./libtest/chkhostname curlhost test 2023...[HTTP authorization retry (Basic)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2023 ./libtest/libauthretry http://127.0.0.1:6490/2023 basic basic >log/stdout2023 2>log/stderr2023 -d-p--e-v OK (784 out of 793, remaining: 00:22) prechecked ./libtest/chkhostname curlhost test 2024...[HTTP authorization retry (Basic switching to Digest)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2024 ./libtest/libauthretry http://127.0.0.1:6490/2024 basic digest >log/stdout2024 2>log/stderr2024 -d-p--e-v OK (785 out of 793, remaining: 00:20) prechecked ./libtest/chkhostname curlhost test 2025...[HTTP authorization retry (Basic switching to NTLM)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2025 ./libtest/libauthretry http://127.0.0.1:6490/2025 basic ntlm >log/stdout2025 2>log/stderr2025 -d-p--e-v OK (786 out of 793, remaining: 00:17) prechecked ./libtest/chkhostname curlhost test 2026...[HTTP authorization retry (Digest switching to Basic)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2026 ./libtest/libauthretry http://127.0.0.1:6490/2026 digest basic >log/stdout2026 2>log/stderr2026 -d-p--e-v OK (787 out of 793, remaining: 00:15) prechecked ./libtest/chkhostname curlhost test 2027...[HTTP authorization retry (Digest)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2027 ./libtest/libauthretry http://127.0.0.1:6490/2027 digest digest >log/stdout2027 2>log/stderr2027 -d-p--e-v OK (788 out of 793, remaining: 00:12) prechecked ./libtest/chkhostname curlhost test 2028...[HTTP authorization retry (Digest switching to NTLM)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2028 ./libtest/libauthretry http://127.0.0.1:6490/2028 digest ntlm >log/stdout2028 2>log/stderr2028 -d-p--e-v OK (789 out of 793, remaining: 00:10) prechecked ./libtest/chkhostname curlhost test 2029...[HTTP authorization retry (NTLM switching to Basic)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2029 ./libtest/libauthretry http://127.0.0.1:6490/2029 ntlm basic >log/stdout2029 2>log/stderr2029 -d-p--e-v OK (790 out of 793, remaining: 00:07) prechecked ./libtest/chkhostname curlhost test 2030...[HTTP authorization retry (NTLM switching to Digest)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2030 ./libtest/libauthretry http://127.0.0.1:6490/2030 ntlm digest >log/stdout2030 2>log/stderr2030 -d-p--e-v OK (791 out of 793, remaining: 00:05) prechecked ./libtest/chkhostname curlhost test 2031...[HTTP authorization retry (NTLM)] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2031 ./libtest/libauthretry http://127.0.0.1:6490/2031 ntlm ntlm >log/stdout2031 2>log/stderr2031 -d-p--e-v OK (792 out of 793, remaining: 00:02) prechecked ./libtest/chkhostname curlhost test 2032...[NTLM connection mapping] ../libtool --mode=execute /usr/bin/valgrind --tool=memcheck --leak-check=yes --suppressions=./valgrind.supp --num-callers=16 --log-file=log/valgrind2032 ./libtest/libntlmconnect http://127.0.0.1:6490/2032 >log/stdout2032 2>log/stderr2032 2032: data FAILED: --- log/check-expected 2015-11-20 21:06:03.922291826 +0000 +++ log/check-generated 2015-11-20 21:06:03.922291826 +0000 @@ -14,15 +14,3 @@ WWW-Authenticate: Basic realm="testrealm" This is a bad password page! -HTTP/1.1 401 NTLM intermediate (2) -Server: Microsoft-IIS/5.0 -Content-Type: text/html; charset=iso-8859-1 -Content-Length: 33 -WWW-Authenticate: NTLM TlRMTVNTUAACAAAACAAIADAAAAAGggEAq6U1NAWaJCIAAAAAAAAAAAAAAAA4AAAATlRMTUF1dGg= - -HTTP/1.1 200 Things are fine in server land -Server: Microsoft-IIS/5.0 -Content-Type: text/html; charset=iso-8859-1 -Content-Length: 32 - -Finally, this is the real page! == Contents of files in the log/ dir after test 2032 === Start of file check-expected HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page HTTP/1.1 401 NTLM intermediate (2) Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 33 WWW-Authenticate: NTLM TlRMTVNTUAACAAAACAAIADAAAAAGggEAq6U1NAWaJCIAAAAAAAAAAAAAAAA4AAAATlRMTUF1dGg= HTTP/1.1 200 Things are fine in server land Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 32 Finally, this is the real page === End of file check-expected === Start of file check-generated HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page === End of file check-generated === Start of file http_server.log 21:04:42.628964 ====> Client connect 21:04:42.629134 TCP_NODELAY set 21:04:42.629157 accept_connection 3 returned 4 21:04:42.629183 accept_connection 3 returned 0 21:04:42.955030 Read 108 bytes 21:04:42.955110 Process 108 bytes request 21:04:42.955155 Got request: GET /20320100 HTTP/1.1 21:04:42.955187 Requested test number 2032 part 100 21:04:42.955273 - request found to be complete 21:04:42.955379 Wrote request (108 bytes) input to log/server.input 21:04:42.955426 Send response test2032 section <data100> 21:04:42.955586 Sent off 200 bytes 21:04:42.955648 Sent off 21 bytes 21:04:42.955699 Response sent (221 bytes) and written to log/server.response 21:04:42.955730 => persistant connection request ended, awaits new request 21:04:43.040862 ====> Client connect 21:04:43.040940 TCP_NODELAY set 21:04:43.040973 accept_connection 3 returned 5 21:04:43.041014 accept_connection 3 returned 0 21:04:43.041352 Read 108 bytes 21:04:43.041393 Process 108 bytes request 21:04:43.041436 Got request: GET /20320100 HTTP/1.1 21:04:43.041470 Requested test number 2032 part 100 21:04:43.041547 - request found to be complete 21:04:43.041595 Wrote request (108 bytes) input to log/server.input 21:04:43.041637 Send response test2032 section <data100> 21:04:43.041763 Sent off 200 bytes 21:04:43.041827 Sent off 21 bytes 21:04:43.041872 Response sent (221 bytes) and written to log/server.response 21:04:43.041905 => persistant connection request ended, awaits new request 21:04:43.048377 ====> Client connect 21:04:43.048437 TCP_NODELAY set 21:04:43.048475 accept_connection 3 returned 6 21:04:43.048513 accept_connection 3 returned 0 21:06:03.461990 Read 127 bytes 21:06:03.462094 Process 127 bytes request 21:06:03.462143 Got request: GET /20320200 HTTP/1.1 21:06:03.462178 Requested test number 2032 part 200 21:06:03.462260 - request found to be complete 21:06:03.462296 Received NTLM type-1, sending back data 1201 21:06:03.462334 Wrote request (127 bytes) input to log/server.input 21:06:03.462376 Send response test2032 section <data1201> 21:06:03.462519 Sent off 200 bytes 21:06:03.462583 Sent off 58 bytes 21:06:03.462681 Response sent (258 bytes) and written to log/server.response 21:06:03.463221 => persistant connection request ended, awaits new request 21:06:03.500455 recv() returned error: (104) Connection reset by peer 21:06:03.500527 ====> Client disconnect 0 21:06:03.507460 Connection closed by client 21:06:03.507505 ====> Client disconnect 0 21:06:03.511078 Connection closed by client 21:06:03.511124 ====> Client disconnect 0 === End of file http_server.log === Start of file server.input GET /20320100 HTTP/1.1 Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M= Host: 127.0.0.1:6490 Accept: */* GET /20320100 HTTP/1.1 Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M= Host: 127.0.0.1:6490 Accept: */* GET /20320200 HTTP/1.1 Authorization: NTLM TlRMTVNTUAABAAAABoIIAAAAAAAAAAAAAAAAAAAAAAA= Host: 127.0.0.1:6490 Accept: */* === End of file server.input === Start of file server.response HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page HTTP/1.1 401 NTLM intermediate (2) Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 33 WWW-Authenticate: NTLM TlRMTVNTUAACAAAACAAIADAAAAAGggEAq6U1NAWaJCIAAAAAAAAAAAAAAAA4AAAATlRMTUF1dGg= This is still not the real page === End of file server.response === Start of file stderr2032 URL: http://127.0.0.1:6490/2032 * About to connect() to 127.0.0.1 port 6490 (#0) * Trying 127.0.0.1... * Connected to 127.0.0.1 (127.0.0.1) port 6490 (#0) * Server auth using Basic with user 'testuser' > GET /20320100 HTTP/1.1 Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M= Host: 127.0.0.1:6490 Accept: */* < HTTP/1.1 401 Need Basic or NTLM auth < Server: Microsoft-IIS/5.0 < Content-Type: text/html; charset=iso-8859-1 < Content-Length: 29 < WWW-Authenticate: NTLM * Authentication problem. Ignoring this. < WWW-Authenticate: Basic realm="testrealm" < * Connection #0 to host 127.0.0.1 left intact libntlmconnect.c:236 num_handles 1 timeout 0 * About to connect() to 127.0.0.1 port 6490 (#1) * Trying 127.0.0.1... * Connected to 127.0.0.1 (127.0.0.1) port 6490 (#1) * Server auth using Basic with user 'testuser' > GET /20320100 HTTP/1.1 Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M= Host: 127.0.0.1:6490 Accept: */* libntlmconnect.c:236 num_handles 2 timeout 0 < HTTP/1.1 401 Need Basic or NTLM auth < Server: Microsoft-IIS/5.0 < Content-Type: text/html; charset=iso-8859-1 < Content-Length: 29 < WWW-Authenticate: NTLM * Authentication problem. Ignoring this. < WWW-Authenticate: Basic realm="testrealm" < * Connection #1 to host 127.0.0.1 left intact * About to connect() to 127.0.0.1 port 6490 (#2) * Trying 127.0.0.1... * Connected to 127.0.0.1 (127.0.0.1) port 6490 (#2) * Initializing NSS with certpath: sql:/etc/pki/nssdb * Server auth using NTLM with user 'testuser' > GET /20320200 HTTP/1.1 Authorization: NTLM TlRMTVNTUAABAAAABoIIAAAAAAAAAAAAAAAAAAAAAAA= Host: 127.0.0.1:6490 Accept: */* libntlmconnect.c:164 ABORTING TEST, since it seems that it would have run forever. * Closing connection 2 === End of file stderr2032 === Start of file stdout2032 HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page HTTP/1.1 401 Need Basic or NTLM auth Server: Microsoft-IIS/5.0 Content-Type: text/html; charset=iso-8859-1 Content-Length: 29 WWW-Authenticate: NTLM WWW-Authenticate: Basic realm="testrealm" This is a bad password page === End of file stdout2032 === Start of file valgrind2032 ==20293== Memcheck, a memory error detector ==20293== Copyright (C) 2002-2013, and GNU GPL'd, by Julian Seward et al. ==20293== Using Valgrind-3.10.0 and LibVEX; rerun with -h for copyright info ==20293== Command: /builddir/build/BUILD/curl-7.29.0/tests/libtest/.libs/lt-libntlmconnect http://127.0.0.1:6490/2032 ==20293== Parent PID: 20292 ==20293== ==20293== ==20293== HEAP SUMMARY: ==20293== in use at exit: 15,875 bytes in 86 blocks ==20293== total heap usage: 6,385 allocs, 6,299 frees, 1,841,308 bytes allocated ==20293== ==20293== LEAK SUMMARY: ==20293== definitely lost: 0 bytes in 0 blocks ==20293== indirectly lost: 0 bytes in 0 blocks ==20293== possibly lost: 0 bytes in 0 blocks ==20293== still reachable: 15,875 bytes in 86 blocks ==20293== suppressed: 0 bytes in 0 blocks ==20293== Reachable blocks (those to which a pointer was found) are not shown. ==20293== To see them, rerun with: --leak-check=full --show-leak-kinds=all ==20293== ==20293== For counts of detected and suppressed errors, rerun with: -v ==20293== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) === End of file valgrind2032 * kill pid for ftp-ctrl => 12050 * kill pid for ftp-ipv6-ctrl => 31925 * kill pid for imap-filt => 31826 * kill pid for pop3-filt => 31638 * kill pid for smtp-filt => 32185 * kill pid for smtp => 32173 * kill pid for https => 10443 * kill pid for https => 10446 * kill pid for ssh => 11491 * kill pid for ssh => 11354 * kill pid for imap => 31825 * kill pid for ftp => 12019 * kill pid for http => 10355 * kill pid for pop3 => 31637 * kill pid for ftp-ipv6 => 31924 * kill pid for tftp => 1034 * kill pid for socks => 11729 * kill pid for http-proxy => 2025 * kill pid for http-ipv6 => 30797 * kill pid for rtsp => 13928 * kill pid for gopher => 15207 * kill pid for gopher-ipv6 => 15293 RUN: Process with pid 1034 signalled to die RUN: Process with pid 2025 signalled to die RUN: Process with pid 10355 signalled to die RUN: Process with pid 10443 signalled to die RUN: Process with pid 10446 signalled to die RUN: Process with pid 11354 signalled to die RUN: Process with pid 11491 signalled to die RUN: Process with pid 11729 signalled to die RUN: Process with pid 12019 signalled to die RUN: Process with pid 13928 signalled to die RUN: Process with pid 15207 signalled to die RUN: Process with pid 15293 signalled to die RUN: Process with pid 30797 signalled to die RUN: Process with pid 31637 signalled to die RUN: Process with pid 31825 signalled to die RUN: Process with pid 31924 signalled to die RUN: Process with pid 32173 signalled to die RUN: Process with pid 11491 gracefully died RUN: Process with pid 10446 gracefully died RUN: Process with pid 1034 forced to die with SIGKILL RUN: Process with pid 2025 forced to die with SIGKILL RUN: Process with pid 10355 forced to die with SIGKILL RUN: Process with pid 10443 forced to die with SIGKILL RUN: Process with pid 11354 forced to die with SIGKILL RUN: Process with pid 11729 forced to die with SIGKILL RUN: Process with pid 12019 forced to die with SIGKILL RUN: Process with pid 13928 forced to die with SIGKILL RUN: Process with pid 15207 forced to die with SIGKILL RUN: Process with pid 15293 forced to die with SIGKILL RUN: Process with pid 30797 forced to die with SIGKILL RUN: Process with pid 31637 forced to die with SIGKILL RUN: Process with pid 31825 forced to die with SIGKILL RUN: Process with pid 31924 forced to die with SIGKILL RUN: Process with pid 32173 forced to die with SIGKILL TESTDONE: 720 tests out of 722 reported OK: 99% TESTFAIL: These test cases failed: 46 2032 TESTDONE: 797 tests were considered during 2082 seconds. TESTINFO: 75 tests were skipped due to these restraints: TESTINFO: "curl lacks debug support" 37 times (1210, 1310, 1311, 1312, 1313, 1334, 1335, 1336, 1337 and 28 more) TESTINFO: "rlimit problem: fds needed 1050 > system limit 1024" 1 times (518) TESTINFO: "curl lacks TLS-SRP support" 5 times (320, 321, 322, 323, 324) TESTINFO: "configured as DISABLED" 4 times (594, 1112, 1209, 1211) TESTINFO: "curl lacks Metalink support" 16 times (2005, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015 and 7 more) TESTINFO: "curl lacks unittest support" 10 times (1300, 1301, 1302, 1303, 1304, 1305, 1306, 1307, 1308 and 1 more) TESTINFO: "curl lacks OpenSSL support" 2 times (307, 308) RPM build errors: error: Bad exit status from /var/tmp/rpm-tmp.zxGvNJ (%check) Bad exit status from /var/tmp/rpm-tmp.zxGvNJ (%check) Child return code was: 1 EXCEPTION: Command failed. See logs for output. # ['bash', '--login', '-c', 'rpmbuild -bb --target x86_64 --nodeps builddir/build/SPECS/curl.spec'] Traceback (most recent call last): File "/usr/lib/python2.6/site-packages/mockbuild/trace_decorator.py", line 70, in trace result = func(*args, **kw) File "/usr/lib/python2.6/site-packages/mockbuild/util.py", line 377, in do raise mockbuild.exception.Error, ("Command failed. See logs for output.\n # %s" % (command,), child.returncode) Error: Command failed. See logs for output. # ['bash', '--login', '-c', 'rpmbuild -bb --target x86_64 --nodeps builddir/build/SPECS/curl.spec'] LEAVE do --> EXCEPTION RAISED