ROOT log: -----------------( last 500 lines )-----\n\n
DEBUG util.py:282: basesystem.noarch 0:10.0-7.el7.centos
DEBUG util.py:282: binutils.i686 0:2.23.52.0.1-55.el7
DEBUG util.py:282: bzip2-libs.i686 0:1.0.6-13.el7
DEBUG util.py:282: ca-certificates.noarch 0:2015.2.4-71.el7
DEBUG util.py:282: chkconfig.i686 0:1.3.61-5.el7
DEBUG util.py:282: cpp.i686 0:4.8.5-4.el7
DEBUG util.py:282: cracklib.i686 0:2.9.0-11.el7
DEBUG util.py:282: cracklib-dicts.i686 0:2.9.0-11.el7
DEBUG util.py:282: curl.i686 0:7.29.0-19.el7
DEBUG util.py:282: cyrus-sasl-lib.i686 0:2.1.26-19.2.el7
DEBUG util.py:282: dwz.i686 0:0.11-3.el7
DEBUG util.py:282: elfutils.i686 0:0.163-3.el7
DEBUG util.py:282: elfutils-libelf.i686 0:0.163-3.el7
DEBUG util.py:282: elfutils-libs.i686 0:0.163-3.el7
DEBUG util.py:282: expat.i686 0:2.1.0-8.el7
DEBUG util.py:282: file.i686 0:5.11-31.el7
DEBUG util.py:282: file-libs.i686 0:5.11-31.el7
DEBUG util.py:282: filesystem.i686 0:3.2-20.el7
DEBUG util.py:282: gdb.i686 0:7.6.1-80.el7
DEBUG util.py:282: gdbm.i686 0:1.10-8.el7
DEBUG util.py:282: glib2.i686 0:2.42.2-5.el7
DEBUG util.py:282: glibc.i686 0:2.17-105.el7
DEBUG util.py:282: glibc-common.i686 0:2.17-105.el7
DEBUG util.py:282: glibc-devel.i686 0:2.17-105.el7
DEBUG util.py:282: glibc-headers.i686 0:2.17-105.el7
DEBUG util.py:282: gmp.i686 1:6.0.0-12.el7_1
DEBUG util.py:282: gnupg2.i686 0:2.0.22-3.el7
DEBUG util.py:282: groff-base.i686 0:1.22.2-8.el7
DEBUG util.py:282: kernel-headers.i686 0:3.10.0-229.20.1.el7
DEBUG util.py:282: keyutils-libs.i686 0:1.5.8-3.el7
DEBUG util.py:282: krb5-libs.i686 0:1.13.2-10.el7
DEBUG util.py:282: libacl.i686 0:2.2.51-12.el7
DEBUG util.py:282: libasan.i686 0:4.8.5-4.el7
DEBUG util.py:282: libassuan.i686 0:2.1.0-3.el7
DEBUG util.py:282: libatomic.i686 0:4.8.5-4.el7
DEBUG util.py:282: libattr.i686 0:2.4.46-12.el7
DEBUG util.py:282: libblkid.i686 0:2.23.2-26.el7
DEBUG util.py:282: libcap.i686 0:2.22-8.el7
DEBUG util.py:282: libcap-ng.i686 0:0.7.5-4.el7
DEBUG util.py:282: libcom_err.i686 0:1.42.9-7.el7
DEBUG util.py:282: libcurl.i686 0:7.29.0-19.el7
DEBUG util.py:282: libdb.i686 0:5.3.21-19.el7
DEBUG util.py:282: libdb-utils.i686 0:5.3.21-19.el7
DEBUG util.py:282: libffi.i686 0:3.0.13-16.el7
DEBUG util.py:282: libgcc.i686 0:4.8.5-4.el7
DEBUG util.py:282: libgcrypt.i686 0:1.5.3-12.el7_1.1
DEBUG util.py:282: libgomp.i686 0:4.8.5-4.el7
DEBUG util.py:282: libgpg-error.i686 0:1.12-3.el7
DEBUG util.py:282: libidn.i686 0:1.28-4.el7
DEBUG util.py:282: libmount.i686 0:2.23.2-26.el7
DEBUG util.py:282: libmpc.i686 0:1.0.1-3.el7
DEBUG util.py:282: libpwquality.i686 0:1.2.3-4.el7
DEBUG util.py:282: libselinux.i686 0:2.2.2-6.el7
DEBUG util.py:282: libsemanage.i686 0:2.1.10-18.el7
DEBUG util.py:282: libsepol.i686 0:2.1.9-3.el7
DEBUG util.py:282: libssh2.i686 0:1.4.3-10.el7
DEBUG util.py:282: libstdc++.i686 0:4.8.5-4.el7
DEBUG util.py:282: libstdc++-devel.i686 0:4.8.5-4.el7
DEBUG util.py:282: libtasn1.i686 0:3.8-2.el7
DEBUG util.py:282: libuser.i686 0:0.60-7.el7_1
DEBUG util.py:282: libutempter.i686 0:1.1.6-4.el7
DEBUG util.py:282: libuuid.i686 0:2.23.2-26.el7
DEBUG util.py:282: libverto.i686 0:0.2.5-4.el7
DEBUG util.py:282: libxml2.i686 0:2.9.1-5.el7_1.2
DEBUG util.py:282: lua.i686 0:5.1.4-14.el7
DEBUG util.py:282: mpfr.i686 0:3.1.1-4.el7
DEBUG util.py:282: ncurses.i686 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.i686 0:5.9-13.20130511.el7
DEBUG util.py:282: nspr.i686 0:4.10.8-2.el7_1
DEBUG util.py:282: nss.i686 0:3.19.1-18.el7
DEBUG util.py:282: nss-softokn.i686 0:3.16.2.3-13.el7_1
DEBUG util.py:282: nss-softokn-freebl.i686 0:3.16.2.3-13.el7_1
DEBUG util.py:282: nss-sysinit.i686 0:3.19.1-18.el7
DEBUG util.py:282: nss-tools.i686 0:3.19.1-18.el7
DEBUG util.py:282: nss-util.i686 0:3.19.1-4.el7_1
DEBUG util.py:282: openldap.i686 0:2.4.40-8.el7
DEBUG util.py:282: openssl-libs.i686 1:1.0.1e-42.el7.9
DEBUG util.py:282: p11-kit.i686 0:0.20.7-3.el7
DEBUG util.py:282: p11-kit-trust.i686 0:0.20.7-3.el7
DEBUG util.py:282: pam.i686 0:1.1.8-12.el7_1.1
DEBUG util.py:282: pcre.i686 0:8.32-15.el7
DEBUG util.py:282: perl.i686 4:5.16.3-286.el7
DEBUG util.py:282: perl-Carp.noarch 0:1.26-244.el7
DEBUG util.py:282: perl-Encode.i686 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.i686 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.i686 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.i686 0:1.27-248.el7
DEBUG util.py:282: perl-Socket.i686 0:2.010-3.el7
DEBUG util.py:282: perl-Storable.i686 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.i686 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.i686 4:5.16.3-286.el7
DEBUG util.py:282: perl-macros.i686 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.i686 0:1.87-4.el7
DEBUG util.py:282: perl-threads-shared.i686 0:1.43-6.el7
DEBUG util.py:282: pinentry.i686 0:0.8.1-14.el7
DEBUG util.py:282: pkgconfig.i686 1:0.27.1-4.el7
DEBUG util.py:282: popt.i686 0:1.13-16.el7
DEBUG util.py:282: pth.i686 0:2.0.7-23.el7
DEBUG util.py:282: python-libs.i686 0:2.7.5-34.el7
DEBUG util.py:282: readline.i686 0:6.2-9.el7
DEBUG util.py:282: rpm.i686 0:4.11.3-17.el7
DEBUG util.py:282: rpm-build-libs.i686 0:4.11.3-17.el7
DEBUG util.py:282: rpm-libs.i686 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.i686 0:1.1-9.el7
DEBUG util.py:282: sqlite.i686 0:3.7.17-8.el7
DEBUG util.py:282: systemd-libs.i686 0:219-19.el7
DEBUG util.py:282: tzdata.noarch 0:2015g-1.el7
DEBUG util.py:282: ustr.i686 0:1.0.4-16.el7
DEBUG util.py:282: xz-libs.i686 0:5.1.2-12alpha.el7
DEBUG util.py:282: zip.i686 0:3.0-10.el7
DEBUG util.py:282: zlib.i686 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-i686/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-i686/root/builddir/build/RPMS
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/RPMS
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/SRPMS
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/SRPMS
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/SOURCES
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/SOURCES
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/SPECS
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/SPECS
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/BUILD
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/BUILD
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/BUILDROOT
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/BUILDROOT
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/originals
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/builddir/build/originals
DEBUG util.py:332: Executing command: ['/bin/umount', '-n', '/srv/build/7/D/mock-root/c7.01.u-i686/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-i686/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-i686/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-i686/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-i686/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-i686/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: centos-release-7-1.1503.el7.centos.2.8.5.i686
DEBUG util.py:282: perl-srpm-macros-1-8.el7.noarch
DEBUG util.py:282: tzdata-2015g-1.el7.noarch
DEBUG util.py:282: filesystem-3.2-20.el7.i686
DEBUG util.py:282: nss-softokn-freebl-3.16.2.3-13.el7_1.i686
DEBUG util.py:282: glibc-2.17-105.el7.i686
DEBUG util.py:282: libstdc++-4.8.5-4.el7.i686
DEBUG util.py:282: bash-4.2.46-19.el7.i686
DEBUG util.py:282: pcre-8.32-15.el7.i686
DEBUG util.py:282: zlib-1.2.7-15.el7.i686
DEBUG util.py:282: bzip2-libs-1.0.6-13.el7.i686
DEBUG util.py:282: elfutils-libelf-0.163-3.el7.i686
DEBUG util.py:282: nss-util-3.19.1-4.el7_1.i686
DEBUG util.py:282: popt-1.13-16.el7.i686
DEBUG util.py:282: libattr-2.4.46-12.el7.i686
DEBUG util.py:282: libcap-2.22-8.el7.i686
DEBUG util.py:282: chkconfig-1.3.61-5.el7.i686
DEBUG util.py:282: sed-4.2.2-5.el7.i686
DEBUG util.py:282: audit-libs-2.4.1-5.el7.i686
DEBUG util.py:282: libmpc-1.0.1-3.el7.i686
DEBUG util.py:282: file-libs-5.11-31.el7.i686
DEBUG util.py:282: libcom_err-1.42.9-7.el7.i686
DEBUG util.py:282: libgcrypt-1.5.3-12.el7_1.1.i686
DEBUG util.py:282: nss-softokn-3.16.2.3-13.el7_1.i686
DEBUG util.py:282: findutils-4.5.11-5.el7.i686
DEBUG util.py:282: expat-2.1.0-8.el7.i686
DEBUG util.py:282: glibc-headers-2.17-105.el7.i686
DEBUG util.py:282: libstdc++-devel-4.8.5-4.el7.i686
DEBUG util.py:282: file-5.11-31.el7.i686
DEBUG util.py:282: libassuan-2.1.0-3.el7.i686
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.i686
DEBUG util.py:282: perl-macros-5.16.3-286.el7.i686
DEBUG util.py:282: perl-Socket-2.010-3.el7.i686
DEBUG util.py:282: perl-Storable-2.45-3.el7.i686
DEBUG util.py:282: perl-Filter-1.49-3.el7.i686
DEBUG util.py:282: perl-PathTools-3.40-5.el7.i686
DEBUG util.py:282: perl-Time-HiRes-1.9725-3.el7.i686
DEBUG util.py:282: perl-File-Temp-0.23.01-3.el7.noarch
DEBUG util.py:282: perl-Time-Local-1.2300-2.el7.noarch
DEBUG util.py:282: perl-Pod-Simple-3.28-4.el7.noarch
DEBUG util.py:282: perl-5.16.3-286.el7.i686
DEBUG util.py:282: pinentry-0.8.1-14.el7.i686
DEBUG util.py:282: patch-2.7.1-8.el7.i686
DEBUG util.py:282: libdb-utils-5.3.21-19.el7.i686
DEBUG util.py:282: zip-3.0-10.el7.i686
DEBUG util.py:282: bzip2-1.0.6-13.el7.i686
DEBUG util.py:282: libgomp-4.8.5-4.el7.i686
DEBUG util.py:282: libasan-4.8.5-4.el7.i686
DEBUG util.py:282: libidn-1.28-4.el7.i686
DEBUG util.py:282: glib2-2.42.2-5.el7.i686
DEBUG util.py:282: shared-mime-info-1.1-9.el7.i686
DEBUG util.py:282: ncurses-5.9-13.20130511.el7.i686
DEBUG util.py:282: keyutils-libs-1.5.8-3.el7.i686
DEBUG util.py:282: libsemanage-2.1.10-18.el7.i686
DEBUG util.py:282: libtasn1-3.8-2.el7.i686
DEBUG util.py:282: krb5-libs-1.13.2-10.el7.i686
DEBUG util.py:282: coreutils-8.22-15.el7.i686
DEBUG util.py:282: libblkid-2.23.2-26.el7.i686
DEBUG util.py:282: gzip-1.5-8.el7.i686
DEBUG util.py:282: cracklib-dicts-2.9.0-11.el7.i686
DEBUG util.py:282: pam-1.1.8-12.el7_1.1.i686
DEBUG util.py:282: gcc-4.8.5-4.el7.i686
DEBUG util.py:282: python-libs-2.7.5-34.el7.i686
DEBUG util.py:282: nss-3.19.1-18.el7.i686
DEBUG util.py:282: nss-tools-3.19.1-18.el7.i686
DEBUG util.py:282: libutempter-1.1.6-4.el7.i686
DEBUG util.py:282: libcurl-7.29.0-19.el7.i686
DEBUG util.py:282: rpm-libs-4.11.3-17.el7.i686
DEBUG util.py:282: openldap-2.4.40-8.el7.i686
DEBUG util.py:282: libuser-0.60-7.el7_1.i686
DEBUG util.py:282: rpm-build-libs-4.11.3-17.el7.i686
DEBUG util.py:282: util-linux-2.23.2-26.el7.i686
DEBUG util.py:282: gcc-c++-4.8.5-4.el7.i686
DEBUG util.py:282: make-3.82-21.el7.i686
DEBUG util.py:282: ncurses-base-5.9-13.20130511.el7.noarch
DEBUG util.py:282: setup-2.8.71-6.el7.noarch
DEBUG util.py:282: kernel-headers-3.10.0-229.20.1.el7.i686
DEBUG util.py:282: libgcc-4.8.5-4.el7.i686
DEBUG util.py:282: basesystem-10.0-7.el7.centos.noarch
DEBUG util.py:282: glibc-common-2.17-105.el7.i686
DEBUG util.py:282: xz-libs-5.1.2-12alpha.el7.i686
DEBUG util.py:282: ncurses-libs-5.9-13.20130511.el7.i686
DEBUG util.py:282: libsepol-2.1.9-3.el7.i686
DEBUG util.py:282: libselinux-2.2.2-6.el7.i686
DEBUG util.py:282: info-5.1-4.el7.i686
DEBUG util.py:282: libdb-5.3.21-19.el7.i686
DEBUG util.py:282: nspr-4.10.8-2.el7_1.i686
DEBUG util.py:282: gmp-6.0.0-12.el7_1.i686
DEBUG util.py:282: readline-6.2-9.el7.i686
DEBUG util.py:282: libacl-2.2.51-12.el7.i686
DEBUG util.py:282: lua-5.1.4-14.el7.i686
DEBUG util.py:282: mpfr-3.1.1-4.el7.i686
DEBUG util.py:282: libgpg-error-1.12-3.el7.i686
DEBUG util.py:282: libffi-3.0.13-16.el7.i686
DEBUG util.py:282: grep-2.20-2.el7.i686
DEBUG util.py:282: libuuid-2.23.2-26.el7.i686
DEBUG util.py:282: p11-kit-0.20.7-3.el7.i686
DEBUG util.py:282: sqlite-3.7.17-8.el7.i686
DEBUG util.py:282: elfutils-libs-0.163-3.el7.i686
DEBUG util.py:282: gawk-4.0.2-4.el7.i686
DEBUG util.py:282: gdbm-1.10-8.el7.i686
DEBUG util.py:282: glibc-devel-2.17-105.el7.i686
DEBUG util.py:282: elfutils-0.163-3.el7.i686
DEBUG util.py:282: cpp-4.8.5-4.el7.i686
DEBUG util.py:282: groff-base-1.22.2-8.el7.i686
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-libs-5.16.3-286.el7.i686
DEBUG util.py:282: perl-threads-1.87-4.el7.i686
DEBUG util.py:282: perl-Carp-1.26-244.el7.noarch
DEBUG util.py:282: perl-File-Path-2.09-2.el7.noarch
DEBUG util.py:282: perl-Scalar-List-Utils-1.27-248.el7.i686
DEBUG util.py:282: perl-Exporter-5.68-3.el7.noarch
DEBUG util.py:282: perl-constant-1.27-2.el7.noarch
DEBUG util.py:282: perl-threads-shared-1.43-6.el7.i686
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.i686
DEBUG util.py:282: dwz-0.11-3.el7.i686
DEBUG util.py:282: cyrus-sasl-lib-2.1.26-19.2.el7.i686
DEBUG util.py:282: unzip-6.0-15.el7.i686
DEBUG util.py:282: libatomic-4.8.5-4.el7.i686
DEBUG util.py:282: cpio-2.11-24.el7.i686
DEBUG util.py:282: diffutils-3.3-4.el7.i686
DEBUG util.py:282: libxml2-2.9.1-5.el7_1.2.i686
DEBUG util.py:282: pkgconfig-0.27.1-4.el7.i686
DEBUG util.py:282: xz-5.1.2-12alpha.el7.i686
DEBUG util.py:282: pth-2.0.7-23.el7.i686
DEBUG util.py:282: ustr-1.0.4-16.el7.i686
DEBUG util.py:282: libverto-0.2.5-4.el7.i686
DEBUG util.py:282: p11-kit-trust-0.20.7-3.el7.i686
DEBUG util.py:282: openssl-libs-1.0.1e-42.el7.9.i686
DEBUG util.py:282: ca-certificates-2015.2.4-71.el7.noarch
DEBUG util.py:282: binutils-2.23.52.0.1-55.el7.i686
DEBUG util.py:282: cracklib-2.9.0-11.el7.i686
DEBUG util.py:282: libpwquality-1.2.3-4.el7.i686
DEBUG util.py:282: systemd-libs-219-19.el7.i686
DEBUG util.py:282: libmount-2.23.2-26.el7.i686
DEBUG util.py:282: gdb-7.6.1-80.el7.i686
DEBUG util.py:282: nss-sysinit-3.19.1-18.el7.i686
DEBUG util.py:282: shadow-utils-4.1.5.1-18.el7.i686
DEBUG util.py:282: libssh2-1.4.3-10.el7.i686
DEBUG util.py:282: curl-7.29.0-19.el7.i686
DEBUG util.py:282: rpm-4.11.3-17.el7.i686
DEBUG util.py:282: redhat-rpm-config-9.1.0-68.el7.centos.noarch
DEBUG util.py:282: gnupg2-2.0.22-3.el7.i686
DEBUG util.py:282: libcap-ng-0.7.5-4.el7.i686
DEBUG util.py:282: rpm-build-4.11.3-17.el7.i686
DEBUG util.py:282: which-2.20-7.el7.i686
DEBUG util.py:372: Child return code was: 0
DEBUG util.py:92: remove tree: /srv/build/7/D/mock-root/c7.01.u-i686/root/dev
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/dev/pts
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/root/dev/pts
DEBUG util.py:65: ensuring that dir exists: /srv/build/7/D/mock-root/c7.01.u-i686/root/dev/shm
DEBUG util.py:68: creating dir: /srv/build/7/D/mock-root/c7.01.u-i686/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-i686/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-i686/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-i686/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-i686/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.YmowPu', '/srv/build/7/D/mock-root/c7.01.u-i686/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-i686/root/', '/srv/build/7/D/mock-root/c7.01.u-i686/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-i686/root/', '/srv/build/7/D/mock-root/c7.01.u-i686/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.i686
DEBUG util.py:282: --> krb5-devel-1.13.2-10.el7.i686
DEBUG util.py:282: --> libidn-devel-1.28-4.el7.i686
DEBUG util.py:282: --> libssh2-devel-1.4.3-10.el7.i686
DEBUG util.py:282: --> nss-devel-3.19.1-18.el7.i686
DEBUG util.py:282: --> openldap-devel-2.4.40-8.el7.i686
DEBUG util.py:282: --> openssh-clients-6.6.1p1-22.el7.i686
DEBUG util.py:282: --> openssh-server-6.6.1p1-22.el7.i686
DEBUG util.py:282: --> Already installed : 1:pkgconfig-0.27.1-4.el7.i686
DEBUG util.py:282: --> stunnel-4.56-4.el7.i686
DEBUG util.py:282: --> zlib-devel-1.2.7-15.el7.i686
DEBUG util.py:282: --> Already installed : perl-PathTools-3.40-5.el7.i686
DEBUG util.py:282: --> perl-Digest-MD5-2.52-3.el7.i686
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.i686
DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.i686
DEBUG util.py:282: --> Already installed : perl-PathTools-3.40-5.el7.i686
DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.i686
DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.i686
DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.i686
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.i686
DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.i686
DEBUG util.py:282: --> Already installed : 4:perl-5.16.3-286.el7.i686
DEBUG util.py:282: --> 1:valgrind-3.10.0-16.el7.i686
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 i686 1.22.2-8.el7 c7.00.02 1.0 M
DEBUG util.py:282: krb5-devel i686 1.13.2-10.el7 c7.1511.00 647 k
DEBUG util.py:282: libidn-devel i686 1.28-4.el7 c7.1511.00 123 k
DEBUG util.py:282: libssh2-devel i686 1.4.3-10.el7 c7.1511.00 53 k
DEBUG util.py:282: nss-devel i686 3.19.1-18.el7 c7.1511.00 209 k
DEBUG util.py:282: openldap-devel i686 2.4.40-8.el7 c7.1511.00 798 k
DEBUG util.py:282: openssh-clients i686 6.6.1p1-22.el7 c7.1511.00 652 k
DEBUG util.py:282: openssh-server i686 6.6.1p1-22.el7 c7.1511.00 442 k
DEBUG util.py:282: perl-Digest-MD5 i686 2.52-3.el7 c7.00.02 28 k
DEBUG util.py:282: stunnel i686 4.56-4.el7 c7.00.02 145 k
DEBUG util.py:282: valgrind i686 1:3.10.0-16.el7 c7.1511.00 13 M
DEBUG util.py:282: zlib-devel i686 1.2.7-15.el7 c7.1511.00 49 k
DEBUG util.py:282: Installing for dependencies:
DEBUG util.py:282: acl i686 2.2.51-12.el7 c7.00.03 79 k
DEBUG util.py:282: cryptsetup-libs i686 1.6.7-1.el7 c7.1511.00 184 k
DEBUG util.py:282: cyrus-sasl i686 2.1.26-19.2.el7 c7.1511.00 85 k
DEBUG util.py:282: cyrus-sasl-devel i686 2.1.26-19.2.el7 c7.1511.00 308 k
DEBUG util.py:282: dbus i686 1:1.6.12-13.el7 c7.1511.00 311 k
DEBUG util.py:282: dbus-libs i686 1:1.6.12-13.el7 c7.1511.00 150 k
DEBUG util.py:282: device-mapper i686 7:1.02.107-5.el7 c7.1511.00 248 k
DEBUG util.py:282: device-mapper-libs i686 7:1.02.107-5.el7 c7.1511.00 299 k
DEBUG util.py:282: dracut i686 033-359.el7 c7.1511.00 309 k
DEBUG util.py:282: fipscheck i686 1.4.1-5.el7 c7.00.03 20 k
DEBUG util.py:282: fipscheck-lib i686 1.4.1-5.el7 c7.00.03 9.6 k
DEBUG util.py:282: hardlink i686 1:1.0-19.el7 c7.00.02 13 k
DEBUG util.py:282: hostname i686 3.13-3.el7 c7.00.02 16 k
DEBUG util.py:282: initscripts i686 9.49.30-1.el7 c7.1511.00 428 k
DEBUG util.py:282: iproute i686 3.10.0-54.el7 c7.1511.00 524 k
DEBUG util.py:282: iptables i686 1.4.21-16.el7 c7.1511.00 416 k
DEBUG util.py:282: iputils i686 20121221-7.el7 c7.1511.00 149 k
DEBUG util.py:282: keyutils-libs-devel i686 1.5.8-3.el7 c7.00.03 36 k
DEBUG util.py:282: kmod i686 20-5.el7 c7.1511.00 115 k
DEBUG util.py:282: kmod-libs i686 20-5.el7 c7.1511.00 49 k
DEBUG util.py:282: kpartx i686 0.4.9-85.el7 c7.1511.00 59 k
DEBUG util.py:282: libcom_err-devel i686 1.42.9-7.el7 c7.01.00 29 k
DEBUG util.py:282: libedit i686 3.0-12.20121213cvs.el7 c7.00.03 90 k
DEBUG util.py:282: libmnl i686 1.0.3-7.el7 c7.00.04 23 k
DEBUG util.py:282: libnetfilter_conntrack i686 1.0.4-2.el7 c7.00.03 53 k
DEBUG util.py:282: libnfnetlink i686 1.0.1-4.el7 c7.00.03 25 k
DEBUG util.py:282: libselinux-devel i686 2.2.2-6.el7 c7.00.03 173 k
DEBUG util.py:282: libsepol-devel i686 2.1.9-3.el7 c7.00.03 70 k
DEBUG util.py:282: libverto-devel i686 0.2.5-4.el7 c7.00.03 10 k
DEBUG util.py:282: nspr-devel i686 4.10.8-2.el7_1 c7.01.u 112 k
DEBUG util.py:282: nss-softokn-devel i686 3.16.2.3-13.el7_1 c7.01.u 25 k
DEBUG util.py:282: nss-softokn-freebl-devel i686 3.16.2.3-13.el7_1 c7.01.u 44 k
DEBUG util.py:282: nss-util-devel i686 3.19.1-4.el7_1 c7.01.u 69 k
DEBUG util.py:282: openssh i686 6.6.1p1-22.el7 c7.1511.00 445 k
DEBUG util.py:282: openssl-devel i686 1:1.0.1e-42.el7.9 c7.01.u 1.2 M
DEBUG util.py:282: pcre-devel i686 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 i686 3.3.10-3.el7 c7.01.00 280 k
DEBUG util.py:282: qrencode-libs i686 3.4.1-3.el7 c7.00.03 49 k
DEBUG util.py:282: systemd i686 219-19.el7 c7.1511.00 5.0 M
DEBUG util.py:282: sysvinit-tools i686 2.88-14.dsf.el7 c7.00.02 61 k
DEBUG util.py:282: tcp_wrappers-libs i686 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: 30 M
DEBUG util.py:282: Installed size: 92 M
DEBUG util.py:282: Installed:
DEBUG util.py:282: groff.i686 0:1.22.2-8.el7 krb5-devel.i686 0:1.13.2-10.el7
DEBUG util.py:282: libidn-devel.i686 0:1.28-4.el7 libssh2-devel.i686 0:1.4.3-10.el7
DEBUG util.py:282: nss-devel.i686 0:3.19.1-18.el7 openldap-devel.i686 0:2.4.40-8.el7
DEBUG util.py:282: openssh-clients.i686 0:6.6.1p1-22.el7 openssh-server.i686 0:6.6.1p1-22.el7
DEBUG util.py:282: perl-Digest-MD5.i686 0:2.52-3.el7 stunnel.i686 0:4.56-4.el7
DEBUG util.py:282: valgrind.i686 1:3.10.0-16.el7 zlib-devel.i686 0:1.2.7-15.el7
DEBUG util.py:282: Dependency Installed:
DEBUG util.py:282: acl.i686 0:2.2.51-12.el7
DEBUG util.py:282: cryptsetup-libs.i686 0:1.6.7-1.el7
DEBUG util.py:282: cyrus-sasl.i686 0:2.1.26-19.2.el7
DEBUG util.py:282: cyrus-sasl-devel.i686 0:2.1.26-19.2.el7
DEBUG util.py:282: dbus.i686 1:1.6.12-13.el7
DEBUG util.py:282: dbus-libs.i686 1:1.6.12-13.el7
DEBUG util.py:282: device-mapper.i686 7:1.02.107-5.el7
DEBUG util.py:282: device-mapper-libs.i686 7:1.02.107-5.el7
DEBUG util.py:282: dracut.i686 0:033-359.el7
DEBUG util.py:282: fipscheck.i686 0:1.4.1-5.el7
DEBUG util.py:282: fipscheck-lib.i686 0:1.4.1-5.el7
DEBUG util.py:282: hardlink.i686 1:1.0-19.el7
DEBUG util.py:282: hostname.i686 0:3.13-3.el7
DEBUG util.py:282: initscripts.i686 0:9.49.30-1.el7
DEBUG util.py:282: iproute.i686 0:3.10.0-54.el7
DEBUG util.py:282: iptables.i686 0:1.4.21-16.el7
DEBUG util.py:282: iputils.i686 0:20121221-7.el7
DEBUG util.py:282: keyutils-libs-devel.i686 0:1.5.8-3.el7
DEBUG util.py:282: kmod.i686 0:20-5.el7
DEBUG util.py:282: kmod-libs.i686 0:20-5.el7
DEBUG util.py:282: kpartx.i686 0:0.4.9-85.el7
DEBUG util.py:282: libcom_err-devel.i686 0:1.42.9-7.el7
DEBUG util.py:282: libedit.i686 0:3.0-12.20121213cvs.el7
DEBUG util.py:282: libmnl.i686 0:1.0.3-7.el7
DEBUG util.py:282: libnetfilter_conntrack.i686 0:1.0.4-2.el7
DEBUG util.py:282: libnfnetlink.i686 0:1.0.1-4.el7
DEBUG util.py:282: libselinux-devel.i686 0:2.2.2-6.el7
DEBUG util.py:282: libsepol-devel.i686 0:2.1.9-3.el7
DEBUG util.py:282: libverto-devel.i686 0:0.2.5-4.el7
DEBUG util.py:282: nspr-devel.i686 0:4.10.8-2.el7_1
DEBUG util.py:282: nss-softokn-devel.i686 0:3.16.2.3-13.el7_1
DEBUG util.py:282: nss-softokn-freebl-devel.i686 0:3.16.2.3-13.el7_1
DEBUG util.py:282: nss-util-devel.i686 0:3.19.1-4.el7_1
DEBUG util.py:282: openssh.i686 0:6.6.1p1-22.el7
DEBUG util.py:282: openssl-devel.i686 1:1.0.1e-42.el7.9
DEBUG util.py:282: pcre-devel.i686 0:8.32-15.el7
DEBUG util.py:282: perl-Digest.noarch 0:1.17-245.el7
DEBUG util.py:282: procps-ng.i686 0:3.3.10-3.el7
DEBUG util.py:282: qrencode-libs.i686 0:3.4.1-3.el7
DEBUG util.py:282: systemd.i686 0:219-19.el7
DEBUG util.py:282: sysvinit-tools.i686 0:2.88-14.dsf.el7
DEBUG util.py:282: tcp_wrappers-libs.i686 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-i686/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-i686/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-i686/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-i686/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-i686/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-i686.tmp
INFO backend.py:227: chroot (/srv/build/7/D/mock-root/c7.01.u-i686) unlocked and deleted
DEBUG util.py:120: kill orphans
---------------------------------------------
BUILD log: ----------------( last 500 lines )-----\n\n
../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:3290/we/want/1400 --libcurl log/test1400.c >log/stdout1400 2>log/stderr1400
-d-p-oe-v OK (736 out of 793, remaining: 03:31)
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:3290/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: 03:27)
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:3290/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: 03:23)
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:3290/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:19)
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:3290/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:16)
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:3292/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:12)
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:3305/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:08)
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:3301/1407 -l -u user:secret --libcurl log/test1407.c >log/stdout1407 2>log/stderr1407
---p-oe-v OK (743 out of 793, remaining: 03:04)
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]:3294/path/14080001 http://[::1]:3294/path/14080002 >log/stdout1408 2>log/stderr1408
---p--e-v OK (744 out of 793, remaining: 03:00)
* 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:3290/1409 -C wrong >log/stdout1409 2>log/stderr1409
------e-v OK (745 out of 793, remaining: 02: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:3290/1410 --max-time -4 >log/stdout1410 2>log/stderr1410
------e-v OK (746 out of 793, remaining: 02:53)
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:3305/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: 02:49)
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:3290/1412 -u testuser:testpass --anyauth http://127.0.0.1:3290/14120001 >log/stdout1412 2>log/stderr1412
-d-p--e-v OK (748 out of 793, remaining: 02:45)
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:3290/this/1413 -L >log/stdout1413 2>log/stderr1413
-d-p--e-v OK (749 out of 793, remaining: 02:41)
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:3290 >log/stdout1415 2>log/stderr1415
-d-p-oe-v OK (750 out of 793, remaining: 02:38)
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:3290/1500 >log/stdout1500 2>log/stderr1500
-d----e-v OK (751 out of 793, remaining: 02:34)
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:3292/1501/ >log/stdout1501 2>log/stderr1501
---p--e-v OK (752 out of 793, remaining: 02:30)
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:3290/1502 3290 127.0.0.1 >log/stdout1502 2>log/stderr1502
-d-p--e-v OK (753 out of 793, remaining: 02:26)
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:3290/1503 3290 127.0.0.1 >log/stdout1503 2>log/stderr1503
-d-p--e-v OK (754 out of 793, remaining: 02:23)
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:3290/1504 3290 127.0.0.1 >log/stdout1504 2>log/stderr1504
-d-p--e-v OK (755 out of 793, remaining: 02:19)
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:3290/1505 3290 127.0.0.1 >log/stdout1505 2>log/stderr1505
-d-p--e-v OK (756 out of 793, remaining: 02:15)
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:3290/path/1506 127.0.0.1 3290 >log/stdout1506 2>log/stderr1506
---p-oe-v OK (757 out of 793, remaining: 02:11)
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:3305/user <log/stdin-for-1507 >log/stdout1507 2>log/stderr1507
---p--e-v OK (758 out of 793, remaining: 02:08)
* 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:3290/path/1508 >log/stdout1508 2>log/stderr1508
-----oe-v OK (759 out of 793, remaining: 02:04)
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:3290/1529" 127.0.0.1:3313 >log/stdout1529 2>log/stderr1529
------e-v OK (760 out of 793, remaining: 02:00)
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:3292/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:56)
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:3290/20010001 ftp://127.0.0.1:3292/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:53)
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:3290/20020001 ftp://127.0.0.1:3292/20020002 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2002.txt tftp://127.0.0.1:3297//20020003 >log/stdout2002 2>log/stderr2002
s--p--e-v OK (763 out of 793, remaining: 01:49)
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:3290/20030001 ftp://127.0.0.1:3292/20030002 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2003.txt tftp://127.0.0.1:3297//20030003 tftp://127.0.0.1:3297//20030003 file://localhost//builddir/build/BUILD/curl-7.29.0/tests/log/test2003.txt ftp://127.0.0.1:3292/20030002 http://127.0.0.1:3290/20030001 >log/stdout2003 2>log/stderr2003
s--p--e-v OK (764 out of 793, remaining: 01:45)
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:3297//2004 sftp://127.0.0.1:3299/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:3299/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:3299/builddir/build/BUILD/curl-7.29.0/tests/log/test2004.txt tftp://127.0.0.1:3297//2004 --insecure >log/stdout2004 2>log/stderr2004
valgrind SKIPPED
s--p--e-- OK (765 out of 793, remaining: 01:42)
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:3290/2023 basic basic >log/stdout2023 2>log/stderr2023
-d-p--e-v OK (784 out of 793, remaining: 00:32)
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:3290/2024 basic digest >log/stdout2024 2>log/stderr2024
-d-p--e-v OK (785 out of 793, remaining: 00:28)
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:3290/2025 basic ntlm >log/stdout2025 2>log/stderr2025
-d-p--e-v OK (786 out of 793, remaining: 00:24)
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:3290/2026 digest basic >log/stdout2026 2>log/stderr2026
-d-p--e-v OK (787 out of 793, remaining: 00:21)
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:3290/2027 digest digest >log/stdout2027 2>log/stderr2027
-d-p--e-v OK (788 out of 793, remaining: 00:17)
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:3290/2028 digest ntlm >log/stdout2028 2>log/stderr2028
-d-p--e-v OK (789 out of 793, remaining: 00:14)
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:3290/2029 ntlm basic >log/stdout2029 2>log/stderr2029
-d-p--e-v OK (790 out of 793, remaining: 00:10)
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:3290/2030 ntlm digest >log/stdout2030 2>log/stderr2030
-d-p--e-v OK (791 out of 793, remaining: 00:07)
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:3290/2031 ntlm ntlm >log/stdout2031 2>log/stderr2031
-d-p--e-v OK (792 out of 793, remaining: 00:03)
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:3290/2032 >log/stdout2032 2>log/stderr2032
2032: data FAILED:
--- log/check-expected 2015-11-21 22:48:47.774620323 +0000
+++ log/check-generated 2015-11-21 22:48:47.774620323 +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
22:48:42.899760 ====> Client connect
22:48:42.899914 TCP_NODELAY set
22:48:42.899958 accept_connection 3 returned 4
22:48:42.900008 accept_connection 3 returned 0
22:48:42.976110 Read 108 bytes
22:48:42.976208 Process 108 bytes request
22:48:42.976257 Got request: GET /20320100 HTTP/1.1
22:48:42.976294 Requested test number 2032 part 100
22:48:42.976396 - request found to be complete
22:48:42.976555 Wrote request (108 bytes) input to log/server.input
22:48:42.976613 Send response test2032 section <data100>
22:48:42.976808 Sent off 200 bytes
22:48:42.976870 Sent off 21 bytes
22:48:42.976926 Response sent (221 bytes) and written to log/server.response
22:48:42.976961 => persistant connection request ended, awaits new request
22:48:43.128332 ====> Client connect
22:48:43.128425 TCP_NODELAY set
22:48:43.128465 accept_connection 3 returned 5
22:48:43.128516 accept_connection 3 returned 0
22:48:43.129418 Read 108 bytes
22:48:43.129469 Process 108 bytes request
22:48:43.129521 Got request: GET /20320100 HTTP/1.1
22:48:43.129563 Requested test number 2032 part 100
22:48:43.129666 - request found to be complete
22:48:43.129732 Wrote request (108 bytes) input to log/server.input
22:48:43.129787 Send response test2032 section <data100>
22:48:43.129940 Sent off 200 bytes
22:48:43.130004 Sent off 21 bytes
22:48:43.130061 Response sent (221 bytes) and written to log/server.response
22:48:43.130101 => persistant connection request ended, awaits new request
22:48:43.135473 ====> Client connect
22:48:43.135555 TCP_NODELAY set
22:48:43.135595 accept_connection 3 returned 6
22:48:43.135654 accept_connection 3 returned 0
22:48:48.118734 Read 127 bytes
22:48:48.118824 Process 127 bytes request
22:48:48.118870 Got request: GET /20320200 HTTP/1.1
22:48:48.118903 Requested test number 2032 part 200
22:48:48.118994 - request found to be complete
22:48:48.119034 Received NTLM type-1, sending back data 1201
22:48:48.119078 Wrote request (127 bytes) input to log/server.input
22:48:48.119121 Send response test2032 section <data1201>
22:48:48.119274 Sent off 200 bytes
22:48:48.119347 Sent off 58 bytes
22:48:48.119398 Response sent (258 bytes) and written to log/server.response
22:48:48.119429 => persistant connection request ended, awaits new request
22:48:48.268435 Read 255 bytes
22:48:48.268525 Process 255 bytes request
22:48:48.268575 Got request: GET /20320200 HTTP/1.1
22:48:48.268613 Requested test number 2032 part 200
22:48:48.268709 - request found to be complete
22:48:48.268749 Received NTLM type-3, sending back data 1202
22:48:48.268796 Wrote request (255 bytes) input to log/server.input
22:48:48.268842 Send response test2032 section <data1202>
22:48:48.269003 Sent off 166 bytes
22:48:48.269061 Response sent (166 bytes) and written to log/server.response
22:48:48.269095 => persistant connection request ended, awaits new request
22:48:48.299164 recv() returned error: (104) Connection reset by peer
22:48:48.299259 ====> Client disconnect 0
22:48:48.308490 Connection closed by client
22:48:48.308583 ====> Client disconnect 0
22:48:48.314560 Connection closed by client
22:48:48.314642 ====> 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:3290
Accept: */*
GET /20320100 HTTP/1.1
Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M=
Host: 127.0.0.1:3290
Accept: */*
GET /20320200 HTTP/1.1
Authorization: NTLM TlRMTVNTUAABAAAABoIIAAAAAAAAAAAAAAAAAAAAAAA=
Host: 127.0.0.1:3290
Accept: */*
GET /20320200 HTTP/1.1
Authorization: NTLM TlRMTVNTUAADAAAAGAAYAEAAAAAYABgAWAAAAAAAAABwAAAACAAIAHAAAAAIAAgAeAAAAAAAAAAAAAAABoIBAI+/Fp9IERAQ74OsdNPbBpg7o8CVwLSO4DtFyIcZHUMKVktWIu92s2892OVpd2JzqnRlc3R1c2VyY3VybGhvc3Q=
Host: 127.0.0.1:3290
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:3290/2032
* About to connect() to 127.0.0.1 port 3290 (#0)
* Trying 127.0.0.1...
* Connected to 127.0.0.1 (127.0.0.1) port 3290 (#0)
* Server auth using Basic with user 'testuser'
> GET /20320100 HTTP/1.1
Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M=
Host: 127.0.0.1:3290
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 3290 (#1)
* Trying 127.0.0.1...
* Connected to 127.0.0.1 (127.0.0.1) port 3290 (#1)
* Server auth using Basic with user 'testuser'
> GET /20320100 HTTP/1.1
Authorization: Basic dGVzdHVzZXI6dGVzdHBhc3M=
Host: 127.0.0.1:3290
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 #1 to host 127.0.0.1 left intact
libntlmconnect.c:236 num_handles 2 timeout 0
* About to connect() to 127.0.0.1 port 3290 (#2)
* Trying 127.0.0.1...
* Connected to 127.0.0.1 (127.0.0.1) port 3290 (#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:3290
Accept: */*
< 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:3290/20320200'
* Found bundle for host 127.0.0.1: 0x4a3e120
* Re-using existing connection! (#2) with host 127.0.0.1
* Connected to 127.0.0.1 (127.0.0.1) port 3290 (#2)
* Server auth using NTLM with user 'testuser'
> GET /20320200 HTTP/1.1
Authorization: NTLM TlRMTVNTUAADAAAAGAAYAEAAAAAYABgAWAAAAAAAAABwAAAACAAIAHAAAAAIAAgAeAAAAAAAAAAAAAAABoIBAI+/Fp9IERAQ74OsdNPbBpg7o8CVwLSO4DtFyIcZHUMKVktWIu92s2892OVpd2JzqnRlc3R1c2VyY3VybGhvc3Q=
Host: 127.0.0.1:3290
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
==11516== Memcheck, a memory error detector
==11516== Copyright (C) 2002-2013, and GNU GPL'd, by Julian Seward et al.
==11516== Using Valgrind-3.10.0 and LibVEX; rerun with -h for copyright info
==11516== Command: /builddir/build/BUILD/curl-7.29.0/tests/libtest/.libs/lt-libntlmconnect http://127.0.0.1:3290/2032
==11516== Parent PID: 11515
==11516==
==11516==
==11516== HEAP SUMMARY:
==11516== in use at exit: 11,883 bytes in 86 blocks
==11516== total heap usage: 6,500 allocs, 6,414 frees, 1,345,429 bytes allocated
==11516==
==11516== LEAK SUMMARY:
==11516== definitely lost: 0 bytes in 0 blocks
==11516== indirectly lost: 0 bytes in 0 blocks
==11516== possibly lost: 0 bytes in 0 blocks
==11516== still reachable: 11,883 bytes in 86 blocks
==11516== suppressed: 0 bytes in 0 blocks
==11516== Reachable blocks (those to which a pointer was found) are not shown.
==11516== To see them, rerun with: --leak-check=full --show-leak-kinds=all
==11516==
==11516== For counts of detected and suppressed errors, rerun with: -v
==11516== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 0 from 0)
=== End of file valgrind2032
* kill pid for ftp-ctrl => 16955
* kill pid for ftp-ipv6-ctrl => 8255
* kill pid for imap-filt => 16173
* kill pid for pop3-filt => 14246
* kill pid for smtp-filt => 16273
* kill pid for smtp => 16272
* kill pid for https => 6782
* kill pid for https => 6785
* kill pid for ssh => 6992
* kill pid for ssh => 6934
* kill pid for imap => 16170
* kill pid for ftp => 16923
* kill pid for http => 6761
* kill pid for pop3 => 14219
* kill pid for ftp-ipv6 => 8254
* kill pid for tftp => 9646
* kill pid for socks => 7020
* kill pid for http-proxy => 3034
* kill pid for http-ipv6 => 7878
* kill pid for rtsp => 7173
* kill pid for gopher => 11166
* kill pid for gopher-ipv6 => 11694
RUN: Process with pid 3034 signalled to die
RUN: Process with pid 6761 signalled to die
RUN: Process with pid 6782 signalled to die
RUN: Process with pid 6785 signalled to die
RUN: Process with pid 6934 signalled to die
RUN: Process with pid 6992 signalled to die
RUN: Process with pid 7020 signalled to die
RUN: Process with pid 7173 signalled to die
RUN: Process with pid 7878 signalled to die
RUN: Process with pid 8254 signalled to die
RUN: Process with pid 9646 signalled to die
RUN: Process with pid 11166 signalled to die
RUN: Process with pid 11694 signalled to die
RUN: Process with pid 14219 signalled to die
RUN: Process with pid 16170 signalled to die
RUN: Process with pid 16272 signalled to die
RUN: Process with pid 16923 signalled to die
RUN: Process with pid 6785 gracefully died
RUN: Process with pid 6992 gracefully died
RUN: Process with pid 3034 forced to die with SIGKILL
RUN: Process with pid 6761 forced to die with SIGKILL
RUN: Process with pid 6782 forced to die with SIGKILL
RUN: Process with pid 6934 forced to die with SIGKILL
RUN: Process with pid 7020 forced to die with SIGKILL
RUN: Process with pid 7173 forced to die with SIGKILL
RUN: Process with pid 7878 forced to die with SIGKILL
RUN: Process with pid 8254 forced to die with SIGKILL
RUN: Process with pid 9646 forced to die with SIGKILL
RUN: Process with pid 11166 forced to die with SIGKILL
RUN: Process with pid 11694 forced to die with SIGKILL
RUN: Process with pid 14219 forced to die with SIGKILL
RUN: Process with pid 16170 forced to die with SIGKILL
RUN: Process with pid 16272 forced to die with SIGKILL
RUN: Process with pid 16923 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 2842 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.ZIef3u (%check)
Bad exit status from /var/tmp/rpm-tmp.ZIef3u (%check)
Child return code was: 1
EXCEPTION: Command failed. See logs for output.
# ['bash', '--login', '-c', 'rpmbuild -bb --target i686 --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 i686 --nodeps builddir/build/SPECS/curl.spec']
LEAVE do --> EXCEPTION RAISED