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-4.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/D/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/D/mock-root/c7.01.u-x86_64/root/builddir/build/RPMS DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/RPMS DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/SRPMS DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/SRPMS DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/SOURCES DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/SOURCES DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/SPECS DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/SPECS DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/BUILD DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/BUILD DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/BUILDROOT DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/BUILDROOT DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/originals DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/builddir/build/originals DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '/srv/build/7/D/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/D/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/D/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/D/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/D/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/D/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-4.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/D/mock-root/c7.01.u-x86_64/root/dev DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/dev/pts DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/dev/pts DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-x86_64/root/dev/shm DEBUG util.py:68: creating dir: /srv/build/7/D/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/D/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/D/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/D/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/D/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.t10Kaj', '/srv/build/7/D/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/D/mock-root/c7.01.u-x86_64/root/', '/srv/build/7/D/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/D/mock-root/c7.01.u-x86_64/root/', '/srv/build/7/D/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-4.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-4.el7 c7.1511.00 123 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.107-5.el7 c7.1511.00 250 k DEBUG util.py:282: device-mapper-libs x86_64 7:1.02.107-5.el7 c7.1511.00 303 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-4.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.107-5.el7 DEBUG util.py:282: device-mapper-libs.x86_64 7:1.02.107-5.el7 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/D/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/D/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/D/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/D/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/D/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/D/mock-root/c7.01.u-x86_64.tmp INFO backend.py:227: chroot (/srv/build/7/D/mock-root/c7.01.u-x86_64) unlocked and deleted DEBUG util.py:120: kill orphans --------------------------------------------- BUILD log: ----------------( last 500 lines )-----\n\n -d-p-oe-v OK (736 out of 793, remaining: 04:13) 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: 04:08) 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: 04:03) 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: 03:59) 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: 03:56) 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: 03:51) 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: 03:46) 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: 03:42) 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: 03:37) * 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: 03:32) * 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: 03:28) 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: 03:24) 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: 03:19) 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: 03:14) 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: 03:10) 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: 03:06) 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: 03:01) 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: 02:57) 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: 02:52) 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: 02:48) 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: 02:44) 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: 02:39) 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: 02:34) * 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: 02:30) 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: 02: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: 02:21) 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: 02:16) 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: 02:12) 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: 02:07) 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: 02:03) 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:38) 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:34) 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:30) 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:25) 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:21) 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:17) 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:12) 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:08) 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:04) 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-21 23:52:30.444432598 +0000 +++ log/check-generated 2015-11-21 23:52:30.444432598 +0000 @@ -20,9 +20,3 @@ 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 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= === End of file check-generated === Start of file http_server.log 23:52:23.434928 ====> Client connect 23:52:23.435024 TCP_NODELAY set 23:52:23.435046 accept_connection 3 returned 4 23:52:23.435070 accept_connection 3 returned 0 23:52:23.476015 Read 108 bytes 23:52:23.476059 Process 108 bytes request 23:52:23.476094 Got request: GET /20320100 HTTP/1.1 23:52:23.476113 Requested test number 2032 part 100 23:52:23.476164 - request found to be complete 23:52:23.476227 Wrote request (108 bytes) input to log/server.input 23:52:23.476260 Send response test2032 section <data100> 23:52:23.476375 Sent off 200 bytes 23:52:23.476419 Sent off 21 bytes 23:52:23.476452 Response sent (221 bytes) and written to log/server.response 23:52:23.476471 => persistant connection request ended, awaits new request 23:52:23.557532 ====> Client connect 23:52:23.557576 TCP_NODELAY set 23:52:23.557603 accept_connection 3 returned 5 23:52:23.557624 accept_connection 3 returned 0 23:52:23.557879 Read 108 bytes 23:52:23.557914 Process 108 bytes request 23:52:23.557948 Got request: GET /20320100 HTTP/1.1 23:52:23.557969 Requested test number 2032 part 100 23:52:23.558020 - request found to be complete 23:52:23.558048 Wrote request (108 bytes) input to log/server.input 23:52:23.558071 Send response test2032 section <data100> 23:52:23.558148 Sent off 200 bytes 23:52:23.558189 Sent off 21 bytes 23:52:23.558219 Response sent (221 bytes) and written to log/server.response 23:52:23.558237 => persistant connection request ended, awaits new request 23:52:23.564176 ====> Client connect 23:52:23.564218 TCP_NODELAY set 23:52:23.564245 accept_connection 3 returned 6 23:52:23.564266 accept_connection 3 returned 0 23:52:28.132100 Read 127 bytes 23:52:28.132180 Process 127 bytes request 23:52:28.132230 Got request: GET /20320200 HTTP/1.1 23:52:28.132267 Requested test number 2032 part 200 23:52:28.132368 - request found to be complete 23:52:28.132410 Received NTLM type-1, sending back data 1201 23:52:28.132459 Wrote request (127 bytes) input to log/server.input 23:52:28.132508 Send response test2032 section <data1201> 23:52:28.132667 Sent off 200 bytes 23:52:28.132737 Sent off 58 bytes 23:52:28.132786 Response sent (258 bytes) and written to log/server.response 23:52:28.132823 => persistant connection request ended, awaits new request 23:52:29.541911 Read 255 bytes 23:52:29.541987 Process 255 bytes request 23:52:29.542027 Got request: GET /20320200 HTTP/1.1 23:52:29.542061 Requested test number 2032 part 200 23:52:29.542135 - request found to be complete 23:52:29.542167 Received NTLM type-3, sending back data 1202 23:52:29.542207 Wrote request (255 bytes) input to log/server.input 23:52:29.542261 Send response test2032 section <data1202> 23:52:29.542404 Sent off 166 bytes 23:52:29.542459 Response sent (166 bytes) and written to log/server.response 23:52:29.542487 => persistant connection request ended, awaits new request 23:52:29.581225 recv() returned error: (104) Connection reset by peer 23:52:29.581304 ====> Client disconnect 0 23:52:29.590321 Connection closed by client 23:52:29.590402 ====> Client disconnect 0 23:52:29.595312 Connection closed by client 23:52:29.595386 ====> 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: */* GET /20320200 HTTP/1.1 Authorization: NTLM TlRMTVNTUAADAAAAGAAYAEAAAAAYABgAWAAAAAAAAABwAAAACAAIAHAAAAAIAAgAeAAAAAAAAAAAAAAABoIBAI+/Fp9IERAQ74OsdNPbBpg7o8CVwLSO4DtFyIcZHUMKVktWIu92s2892OVpd2JzqnRlc3R1c2VyY3VybGhvc3Q= 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 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 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:236 num_handles 3 timeout 0 < 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= < * Ignoring the response-body * Connection #2 to host 127.0.0.1 left intact * Issue another request to this URL: 'http://127.0.0.1:6490/20320200' * Found bundle for host 127.0.0.1: 0x9a79340 * Re-using existing connection! (#2) with host 127.0.0.1 * Connected to 127.0.0.1 (127.0.0.1) port 6490 (#2) * Server auth using NTLM with user 'testuser' > GET /20320200 HTTP/1.1 Authorization: NTLM TlRMTVNTUAADAAAAGAAYAEAAAAAYABgAWAAAAAAAAABwAAAACAAIAHAAAAAIAAgAeAAAAAAAAAAAAAAABoIBAI+/Fp9IERAQ74OsdNPbBpg7o8CVwLSO4DtFyIcZHUMKVktWIu92s2892OVpd2JzqnRlc3R1c2VyY3VybGhvc3Q= 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 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= === End of file stdout2032 === Start of file valgrind2032 ==16072== Memcheck, a memory error detector ==16072== Copyright (C) 2002-2013, and GNU GPL'd, by Julian Seward et al. ==16072== Using Valgrind-3.10.0 and LibVEX; rerun with -h for copyright info ==16072== Command: /builddir/build/BUILD/curl-7.29.0/tests/libtest/.libs/lt-libntlmconnect http://127.0.0.1:6490/2032 ==16072== Parent PID: 16067 ==16072== ==16072== ==16072== HEAP SUMMARY: ==16072== in use at exit: 15,875 bytes in 86 blocks ==16072== total heap usage: 6,493 allocs, 6,407 frees, 1,861,685 bytes allocated ==16072== ==16072== LEAK SUMMARY: ==16072== definitely lost: 0 bytes in 0 blocks ==16072== indirectly lost: 0 bytes in 0 blocks ==16072== possibly lost: 0 bytes in 0 blocks ==16072== still reachable: 15,875 bytes in 86 blocks ==16072== suppressed: 0 bytes in 0 blocks ==16072== Reachable blocks (those to which a pointer was found) are not shown. ==16072== To see them, rerun with: --leak-check=full --show-leak-kinds=all ==16072== ==16072== For counts of detected and suppressed errors, rerun with: -v ==16072== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0) === End of file valgrind2032 * kill pid for ftp-ctrl => 10595 * kill pid for ftp-ipv6-ctrl => 11510 * kill pid for imap-filt => 8624 * kill pid for pop3-filt => 8038 * kill pid for smtp-filt => 9961 * kill pid for smtp => 9889 * kill pid for https => 1312 * kill pid for https => 1319 * kill pid for ssh => 3457 * kill pid for ssh => 3105 * kill pid for imap => 8615 * kill pid for ftp => 10592 * kill pid for http => 1132 * kill pid for pop3 => 8012 * kill pid for ftp-ipv6 => 11490 * kill pid for tftp => 26808 * kill pid for socks => 3518 * kill pid for http-proxy => 26367 * kill pid for http-ipv6 => 2661 * kill pid for rtsp => 8246 * kill pid for gopher => 4749 * kill pid for gopher-ipv6 => 6780 RUN: Process with pid 1132 signalled to die RUN: Process with pid 1312 signalled to die RUN: Process with pid 1319 signalled to die RUN: Process with pid 2661 signalled to die RUN: Process with pid 3105 signalled to die RUN: Process with pid 3457 signalled to die RUN: Process with pid 3518 signalled to die RUN: Process with pid 4749 signalled to die RUN: Process with pid 6780 signalled to die RUN: Process with pid 8012 signalled to die RUN: Process with pid 8246 signalled to die RUN: Process with pid 8615 signalled to die RUN: Process with pid 9889 signalled to die RUN: Process with pid 10592 signalled to die RUN: Process with pid 11490 signalled to die RUN: Process with pid 26367 signalled to die RUN: Process with pid 26808 signalled to die RUN: Process with pid 3457 gracefully died RUN: Process with pid 1319 gracefully died RUN: Process with pid 1132 forced to die with SIGKILL RUN: Process with pid 1312 forced to die with SIGKILL RUN: Process with pid 2661 forced to die with SIGKILL RUN: Process with pid 3105 forced to die with SIGKILL RUN: Process with pid 3518 forced to die with SIGKILL RUN: Process with pid 4749 forced to die with SIGKILL RUN: Process with pid 6780 forced to die with SIGKILL RUN: Process with pid 8012 forced to die with SIGKILL RUN: Process with pid 8246 forced to die with SIGKILL RUN: Process with pid 8615 forced to die with SIGKILL RUN: Process with pid 9889 forced to die with SIGKILL RUN: Process with pid 10592 forced to die with SIGKILL RUN: Process with pid 11490 forced to die with SIGKILL RUN: Process with pid 26367 forced to die with SIGKILL RUN: Process with pid 26808 forced to die with SIGKILL TESTDONE: 718 tests out of 722 reported OK: 99% TESTFAIL: These test cases failed: 46 1206 1319 2032 TESTDONE: 797 tests were considered during 3467 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.nQNiZ9 (%check) Bad exit status from /var/tmp/rpm-tmp.nQNiZ9 (%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