Attachment 268651 Details for Bug 399231 – mock setup log

mock setup log

root.log (text/plain), 123.64 KB, created by Jan Safranek on 2007-11-26 09:35:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jan Safranek

Created: 2007-11-26 09:35:51 UTC

Size: 123.64 KB

patch

obsolete

>2007-11-26 09:25:02,995 - trace_decorator.py:30:DEBUG: LEAVE _resetLogging --> None
>
>2007-11-26 09:25:02,995 - backend.py:168:DEBUG: rootdir = /var/lib/mock/ldap-i386/root
>2007-11-26 09:25:02,995 - backend.py:169:DEBUG: resultdir = /var/lib/mock/ldap-i386/result
>2007-11-26 09:25:02,995 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'preinit'), {})
>2007-11-26 09:25:02,996 - trace_decorator.py:20:DEBUG: ENTER: _ccachePreInitHook((<ccache.CCache object at 0x886c90>,), {})
>2007-11-26 09:25:02,996 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/tmp/ccache',), {})
>2007-11-26 09:25:02,996 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:02,996 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:02,996 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:02,997 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/cache/ldap-i386/ccache/',), {})
>2007-11-26 09:25:02,997 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/cache/ldap-i386/ccache/
>2007-11-26 09:25:02,997 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:02,997 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/cc'), {})
>2007-11-26 09:25:02,997 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,997 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-cc'), {})
>2007-11-26 09:25:02,998 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,998 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-cc'), {})
>2007-11-26 09:25:02,998 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,998 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/gcc'), {})
>2007-11-26 09:25:02,998 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,998 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-gcc'), {})
>2007-11-26 09:25:02,999 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,999 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-gcc'), {})
>2007-11-26 09:25:02,999 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,999 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/gcc296'), {})
>2007-11-26 09:25:02,999 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:02,999 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-gcc296'), {})
>2007-11-26 09:25:02,999 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,000 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-gcc296'), {})
>2007-11-26 09:25:03,000 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,000 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/gcc32'), {})
>2007-11-26 09:25:03,000 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,000 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-gcc32'), {})
>2007-11-26 09:25:03,000 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,000 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-gcc32'), {})
>2007-11-26 09:25:03,001 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,001 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/gcc33'), {})
>2007-11-26 09:25:03,001 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,001 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-gcc33'), {})
>2007-11-26 09:25:03,001 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,001 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-gcc33'), {})
>2007-11-26 09:25:03,002 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,002 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/gcc34'), {})
>2007-11-26 09:25:03,002 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,002 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-gcc34'), {})
>2007-11-26 09:25:03,002 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,002 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-gcc34'), {})
>2007-11-26 09:25:03,002 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,003 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/g++'), {})
>2007-11-26 09:25:03,003 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,003 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-g++'), {})
>2007-11-26 09:25:03,003 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,003 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-g++'), {})
>2007-11-26 09:25:03,003 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,003 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/c++'), {})
>2007-11-26 09:25:03,004 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,004 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-c++'), {})
>2007-11-26 09:25:03,004 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,004 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-c++'), {})
>2007-11-26 09:25:03,004 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,005 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/c++32'), {})
>2007-11-26 09:25:03,005 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,005 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-c++32'), {})
>2007-11-26 09:25:03,005 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,005 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-c++32'), {})
>2007-11-26 09:25:03,005 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,006 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/c++33'), {})
>2007-11-26 09:25:03,006 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,006 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-c++33'), {})
>2007-11-26 09:25:03,006 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,006 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-c++33'), {})
>2007-11-26 09:25:03,006 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,007 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/c++34'), {})
>2007-11-26 09:25:03,007 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,007 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-c++34'), {})
>2007-11-26 09:25:03,007 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,007 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-c++34'), {})
>2007-11-26 09:25:03,007 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,007 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/g++296'), {})
>2007-11-26 09:25:03,008 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,008 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-g++296'), {})
>2007-11-26 09:25:03,008 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,008 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-g++296'), {})
>2007-11-26 09:25:03,008 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,008 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/g++32'), {})
>2007-11-26 09:25:03,009 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,009 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-g++32'), {})
>2007-11-26 09:25:03,009 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,009 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-g++32'), {})
>2007-11-26 09:25:03,009 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,009 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/g++33'), {})
>2007-11-26 09:25:03,010 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,010 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-g++33'), {})
>2007-11-26 09:25:03,010 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,010 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-g++33'), {})
>2007-11-26 09:25:03,010 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,010 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/g++34'), {})
>2007-11-26 09:25:03,010 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,011 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-g++34'), {})
>2007-11-26 09:25:03,011 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,011 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-g++34'), {})
>2007-11-26 09:25:03,011 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,011 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/g++-libstdc++-so_7'), {})
>2007-11-26 09:25:03,011 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,011 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/x86_64-redhat-linux-g++-libstdc++-so_7'), {})
>2007-11-26 09:25:03,012 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,012 - trace_decorator.py:20:DEBUG: ENTER: forceLink(('/usr/bin/ccache', '/var/lib/mock/cache/ldap-i386/ccache/i386-redhat-linux-g++-libstdc++-so_7'), {})
>2007-11-26 09:25:03,012 - trace_decorator.py:30:DEBUG: LEAVE forceLink --> None
>
>2007-11-26 09:25:03,012 - trace_decorator.py:30:DEBUG: LEAVE _ccachePreInitHook --> None
>
>2007-11-26 09:25:03,012 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePreInitHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:03,012 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/var/cache/yum',), {})
>2007-11-26 09:25:03,013 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:03,013 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:03,013 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:03,013 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/cache/ldap-i386/yum_cache/',), {})
>2007-11-26 09:25:03,013 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/cache/ldap-i386/yum_cache/
>2007-11-26 09:25:03,013 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:03,014 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePreYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:03,014 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePreYumHook --> None
>
>2007-11-26 09:25:03,014 - trace_decorator.py:20:DEBUG: ENTER: state((<mock.backend.Root object at 0x882890>, 'enabled yum cache, cleaning yum metadata'), {})
>2007-11-26 09:25:03,014 - trace_decorator.py:30:DEBUG: LEAVE state --> enabled yum cache, cleaning yum metadata
>
>2007-11-26 09:25:03,041 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePostYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:03,041 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePostYumHook --> None
>
>2007-11-26 09:25:03,041 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePreInitHook --> None
>
>2007-11-26 09:25:03,042 - trace_decorator.py:20:DEBUG: ENTER: _rootCachePreInitHook((<root_cache.RootCache object at 0x892490>,), {})
>2007-11-26 09:25:03,042 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/cache/ldap-i386/root_cache/',), {})
>2007-11-26 09:25:03,042 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/cache/ldap-i386/root_cache/
>2007-11-26 09:25:03,042 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:03,042 - trace_decorator.py:20:DEBUG: ENTER: state((<mock.backend.Root object at 0x882890>, 'enabling root cache'), {})
>2007-11-26 09:25:03,042 - trace_decorator.py:30:DEBUG: LEAVE state --> enabling root cache
>
>2007-11-26 09:25:03,043 - trace_decorator.py:20:DEBUG: ENTER: state((<mock.backend.Root object at 0x882890>, 'unpacking cache'), {})
>2007-11-26 09:25:03,043 - trace_decorator.py:30:DEBUG: LEAVE state --> unpacking cache
>
>2007-11-26 09:25:03,043 - trace_decorator.py:20:DEBUG: ENTER: _rootCacheLock((<root_cache.RootCache object at 0x892490>, 1), {})
>2007-11-26 09:25:03,043 - trace_decorator.py:30:DEBUG: LEAVE _rootCacheLock --> None
>
>2007-11-26 09:25:03,043 - trace_decorator.py:20:DEBUG: ENTER: do(('tar xzf /var/lib/mock/cache/ldap-i386/root_cache/cache.tar.gz -C /var/lib/mock/ldap-i386/root', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:03,043 - util.py:196:DEBUG: Run cmd: tar xzf /var/lib/mock/cache/ldap-i386/root_cache/cache.tar.gz -C /var/lib/mock/ldap-i386/root
>2007-11-26 09:25:03,043 - util.py:202:DEBUG: Executing timeout(0): tar xzf /var/lib/mock/cache/ldap-i386/root_cache/cache.tar.gz -C /var/lib/mock/ldap-i386/root
>2007-11-26 09:25:03,045 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:03,045 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:03,046 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:03,046 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:12,432 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:12,433 - trace_decorator.py:20:DEBUG: ENTER: _rootCacheUnlock((<root_cache.RootCache object at 0x892490>,), {})
>2007-11-26 09:25:12,433 - trace_decorator.py:30:DEBUG: LEAVE _rootCacheUnlock --> None
>
>2007-11-26 09:25:12,433 - trace_decorator.py:30:DEBUG: LEAVE _rootCachePreInitHook --> None
>
>2007-11-26 09:25:12,433 - trace_decorator.py:20:DEBUG: ENTER: _bindMountPreInitHook((<bind_mount.BindMount object at 0x892ad0>,), {})
>2007-11-26 09:25:12,433 - trace_decorator.py:30:DEBUG: LEAVE _bindMountPreInitHook --> None
>
>2007-11-26 09:25:12,433 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:12,433 - backend.py:175:INFO: create skeleton dirs
>2007-11-26 09:25:12,434 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/var/lib/rpm',), {})
>2007-11-26 09:25:12,434 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/var/lib/rpm
>2007-11-26 09:25:12,434 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,434 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/var/lib/yum',), {})
>2007-11-26 09:25:12,434 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/var/lib/yum
>2007-11-26 09:25:12,434 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,434 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/var/log',), {})
>2007-11-26 09:25:12,434 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/var/log
>2007-11-26 09:25:12,435 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,435 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/var/lock/rpm',), {})
>2007-11-26 09:25:12,435 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/var/lock/rpm
>2007-11-26 09:25:12,435 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,435 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/etc/rpm',), {})
>2007-11-26 09:25:12,435 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/etc/rpm
>2007-11-26 09:25:12,435 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,436 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/tmp',), {})
>2007-11-26 09:25:12,436 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/tmp
>2007-11-26 09:25:12,436 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,436 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/var/tmp',), {})
>2007-11-26 09:25:12,436 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/var/tmp
>2007-11-26 09:25:12,436 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,436 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/etc/yum.repos.d',), {})
>2007-11-26 09:25:12,436 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/etc/yum.repos.d
>2007-11-26 09:25:12,437 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,437 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/etc/yum',), {})
>2007-11-26 09:25:12,437 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/etc/yum
>2007-11-26 09:25:12,437 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,437 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/proc',), {})
>2007-11-26 09:25:12,437 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:12,437 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,437 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/dev/pts',), {})
>2007-11-26 09:25:12,437 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:12,438 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,438 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/sys',), {})
>2007-11-26 09:25:12,438 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:12,438 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,438 - backend.py:193:INFO: touch required files
>2007-11-26 09:25:12,438 - trace_decorator.py:20:DEBUG: ENTER: touch(('/var/lib/mock/ldap-i386/root/etc/mtab',), {})
>2007-11-26 09:25:12,438 - util.py:49:DEBUG: touching file: /var/lib/mock/ldap-i386/root/etc/mtab
>2007-11-26 09:25:12,439 - trace_decorator.py:30:DEBUG: LEAVE touch --> None
>
>2007-11-26 09:25:12,439 - trace_decorator.py:20:DEBUG: ENTER: touch(('/var/lib/mock/ldap-i386/root/etc/fstab',), {})
>2007-11-26 09:25:12,439 - util.py:49:DEBUG: touching file: /var/lib/mock/ldap-i386/root/etc/fstab
>2007-11-26 09:25:12,439 - trace_decorator.py:30:DEBUG: LEAVE touch --> None
>
>2007-11-26 09:25:12,439 - trace_decorator.py:20:DEBUG: ENTER: touch(('/var/lib/mock/ldap-i386/root/var/log/yum.log',), {})
>2007-11-26 09:25:12,439 - util.py:49:DEBUG: touching file: /var/lib/mock/ldap-i386/root/var/log/yum.log
>2007-11-26 09:25:12,439 - trace_decorator.py:30:DEBUG: LEAVE touch --> None
>
>2007-11-26 09:25:12,439 - backend.py:201:INFO: configure yum
>2007-11-26 09:25:12,440 - trace_decorator.py:20:DEBUG: ENTER: rmtree(('/var/lib/mock/ldap-i386/root/dev',), {})
>2007-11-26 09:25:12,441 - trace_decorator.py:30:DEBUG: LEAVE rmtree --> None
>
>2007-11-26 09:25:12,441 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root/dev/pts',), {})
>2007-11-26 09:25:12,441 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:12,441 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:12,441 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:12,442 - backend.py:247:INFO: run yum
>2007-11-26 09:25:12,442 - trace_decorator.py:20:DEBUG: ENTER: _mountall((<mock.backend.Root object at 0x882890>,), {})
>2007-11-26 09:25:12,442 - backend.py:424:INFO: mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:12,442 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:12,443 - util.py:196:DEBUG: Run cmd: mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:12,443 - util.py:202:DEBUG: Executing timeout(0): mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:12,444 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:12,444 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:12,445 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:12,445 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:12,461 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:12,461 - backend.py:424:INFO: mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:12,462 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:12,462 - util.py:196:DEBUG: Run cmd: mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:12,462 - util.py:202:DEBUG: Executing timeout(0): mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:12,463 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:12,464 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:12,464 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:12,464 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:12,479 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:12,480 - backend.py:424:INFO: mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:12,480 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:12,480 - util.py:196:DEBUG: Run cmd: mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:12,480 - util.py:202:DEBUG: Executing timeout(0): mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:12,481 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:12,482 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:12,482 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:12,482 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:12,497 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:12,498 - backend.py:424:INFO: mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:12,498 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:12,498 - util.py:196:DEBUG: Run cmd: mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:12,498 - util.py:202:DEBUG: Executing timeout(0): mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:12,499 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:12,500 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:12,500 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:12,500 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:12,508 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:12,508 - backend.py:424:INFO: mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:12,509 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:12,509 - util.py:196:DEBUG: Run cmd: mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:12,509 - util.py:202:DEBUG: Executing timeout(0): mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:12,510 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:12,510 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:12,511 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:12,511 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:12,519 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:12,519 - trace_decorator.py:30:DEBUG: LEAVE _mountall --> None
>
>2007-11-26 09:25:12,519 - trace_decorator.py:20:DEBUG: ENTER: _yum((<mock.backend.Root object at 0x882890>, 'update', 0), {})
>2007-11-26 09:25:12,519 - backend.py:439:INFO: /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root update
>2007-11-26 09:25:12,519 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'preyum'), {})
>2007-11-26 09:25:12,519 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePreYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:12,520 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePreYumHook --> None
>
>2007-11-26 09:25:12,520 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:12,520 - trace_decorator.py:20:DEBUG: ENTER: do(('/usr/bin/yum --installroot /var/lib/mock/ldap-i386/root update', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:12,520 - util.py:196:DEBUG: Run cmd: /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root update
>2007-11-26 09:25:12,520 - util.py:202:DEBUG: Executing timeout(0): /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root update
>2007-11-26 09:25:12,521 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:12,522 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:12,522 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:12,522 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,058 - util.py:218:DEBUG: 
>2007-11-26 09:25:19,059 - util.py:218:DEBUG: =============================================================================
>2007-11-26 09:25:19,059 - util.py:218:DEBUG:  Package                 Arch       Version          Repository        Size 
>2007-11-26 09:25:19,059 - util.py:218:DEBUG: =============================================================================
>2007-11-26 09:25:19,059 - util.py:218:DEBUG: Updating:
>2007-11-26 09:25:19,059 - util.py:218:DEBUG:  coreutils               i386       6.9-14.fc9       fedora            3.3 M
>2007-11-26 09:25:19,059 - util.py:218:DEBUG:  kernel-headers          i386       2.6.24-0.42.rc3.git1.fc9  fedora            671 k
>2007-11-26 09:25:19,060 - util.py:218:DEBUG:  less                    i386       416-1.fc9        fedora            106 k
>2007-11-26 09:25:19,060 - util.py:218:DEBUG:  zlib                    i386       1.2.3-16.fc9     fedora             72 k
>2007-11-26 09:25:19,060 - util.py:218:DEBUG: 
>2007-11-26 09:25:19,060 - util.py:218:DEBUG: Transaction Summary
>2007-11-26 09:25:19,060 - util.py:218:DEBUG: =============================================================================
>2007-11-26 09:25:19,060 - util.py:218:DEBUG: Install      0 Package(s)         
>2007-11-26 09:25:19,060 - util.py:218:DEBUG: Update       4 Package(s)         
>2007-11-26 09:25:19,060 - util.py:218:DEBUG: Remove       0 Package(s)         
>2007-11-26 09:25:19,061 - util.py:218:DEBUG: 
>2007-11-26 09:25:19,061 - util.py:218:DEBUG: Total download size: 4.2 M
>2007-11-26 09:25:19,061 - util.py:218:DEBUG: 
>2007-11-26 09:25:19,061 - util.py:218:DEBUG: Updated: coreutils.i386 0:6.9-14.fc9 kernel-headers.i386 0:2.6.24-0.42.rc3.git1.fc9 less.i386 0:416-1.fc9 zlib.i386 0:1.2.3-16.fc9
>2007-11-26 09:25:19,062 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,062 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'postyum'), {})
>2007-11-26 09:25:19,062 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePostYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:19,062 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePostYumHook --> None
>
>2007-11-26 09:25:19,063 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:19,063 - trace_decorator.py:30:DEBUG: LEAVE _yum --> 
>
>2007-11-26 09:25:19,063 - trace_decorator.py:20:DEBUG: ENTER: _umountall((<mock.backend.Root object at 0x882890>,), {})
>2007-11-26 09:25:19,063 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:19,063 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/proc', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:25:19,063 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:19,063 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:19,066 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,067 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,067 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,067 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,079 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,079 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:19,079 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/dev/pts', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:25:19,079 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:19,079 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:19,081 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,081 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,081 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,081 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,090 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,090 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:19,090 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/sys', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:25:19,090 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:19,091 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:19,092 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,092 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,093 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,093 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,101 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,101 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:19,101 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/tmp/ccache', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:25:19,102 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:19,102 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:19,103 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,104 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,104 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,104 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,112 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,113 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:19,113 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/var/cache/yum', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:25:19,113 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:19,113 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:19,115 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,115 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,115 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,115 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,124 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,124 - trace_decorator.py:30:DEBUG: LEAVE _umountall --> None
>
>2007-11-26 09:25:19,124 - trace_decorator.py:20:DEBUG: ENTER: _makeBuildUser((<mock.backend.Root object at 0x882890>,), {})
>2007-11-26 09:25:19,125 - trace_decorator.py:20:DEBUG: ENTER: rmtree(('/builddir',), {})
>2007-11-26 09:25:19,125 - trace_decorator.py:30:DEBUG: LEAVE rmtree --> None
>
>2007-11-26 09:25:19,126 - trace_decorator.py:20:DEBUG: ENTER: doChroot((<mock.backend.Root object at 0x882890>, '/usr/sbin/userdel -r mockbuild', ''), {'raiseExc': False})
>2007-11-26 09:25:19,126 - trace_decorator.py:20:DEBUG: ENTER: do(('/usr/sbin/userdel -r mockbuild', '/var/lib/mock/ldap-i386/root', 0, False, 0, None, None, None), {})
>2007-11-26 09:25:19,126 - util.py:196:DEBUG: Run cmd: /usr/sbin/userdel -r mockbuild
>2007-11-26 09:25:19,126 - util.py:202:DEBUG: Executing timeout(0): /usr/sbin/userdel -r mockbuild
>2007-11-26 09:25:19,127 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', None), {})
>2007-11-26 09:25:19,128 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,128 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 03:25:19,128 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,148 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,149 - trace_decorator.py:30:DEBUG: LEAVE doChroot --> 
>
>2007-11-26 09:25:19,149 - trace_decorator.py:20:DEBUG: ENTER: doChroot((<mock.backend.Root object at 0x882890>, '/usr/sbin/groupdel mockbuild', ''), {'raiseExc': False})
>2007-11-26 09:25:19,149 - trace_decorator.py:20:DEBUG: ENTER: do(('/usr/sbin/groupdel mockbuild', '/var/lib/mock/ldap-i386/root', 0, False, 0, None, None, None), {})
>2007-11-26 09:25:19,149 - util.py:196:DEBUG: Run cmd: /usr/sbin/groupdel mockbuild
>2007-11-26 09:25:19,149 - util.py:202:DEBUG: Executing timeout(0): /usr/sbin/groupdel mockbuild
>2007-11-26 09:25:19,151 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', None), {})
>2007-11-26 09:25:19,151 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,151 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 03:25:19,152 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,160 - util.py:218:DEBUG: groupdel: group mockbuild does not exist
>2007-11-26 09:25:19,161 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,161 - trace_decorator.py:30:DEBUG: LEAVE doChroot --> 
>
>2007-11-26 09:25:19,161 - trace_decorator.py:20:DEBUG: ENTER: doChroot((<mock.backend.Root object at 0x882890>, '/usr/sbin/groupadd -g 494 mockbuild', ''), {})
>2007-11-26 09:25:19,161 - trace_decorator.py:20:DEBUG: ENTER: do(('/usr/sbin/groupadd -g 494 mockbuild', '/var/lib/mock/ldap-i386/root', 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,161 - util.py:196:DEBUG: Run cmd: /usr/sbin/groupadd -g 494 mockbuild
>2007-11-26 09:25:19,161 - util.py:202:DEBUG: Executing timeout(0): /usr/sbin/groupadd -g 494 mockbuild
>2007-11-26 09:25:19,163 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', None), {})
>2007-11-26 09:25:19,163 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,164 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 03:25:19,164 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,178 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,178 - trace_decorator.py:30:DEBUG: LEAVE doChroot --> 
>
>2007-11-26 09:25:19,178 - trace_decorator.py:20:DEBUG: ENTER: doChroot((<mock.backend.Root object at 0x882890>, '/usr/sbin/useradd -m -u 500 -g 494 -d /builddir -n mockbuild', ''), {})
>2007-11-26 09:25:19,178 - trace_decorator.py:20:DEBUG: ENTER: do(('/usr/sbin/useradd -m -u 500 -g 494 -d /builddir -n mockbuild', '/var/lib/mock/ldap-i386/root', 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,178 - util.py:196:DEBUG: Run cmd: /usr/sbin/useradd -m -u 500 -g 494 -d /builddir -n mockbuild
>2007-11-26 09:25:19,179 - util.py:202:DEBUG: Executing timeout(0): /usr/sbin/useradd -m -u 500 -g 494 -d /builddir -n mockbuild
>2007-11-26 09:25:19,180 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', None), {})
>2007-11-26 09:25:19,180 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,181 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 03:25:19,181 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,195 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,196 - trace_decorator.py:30:DEBUG: LEAVE doChroot --> 
>
>2007-11-26 09:25:19,196 - trace_decorator.py:20:DEBUG: ENTER: doChroot((<mock.backend.Root object at 0x882890>, "perl -p -i -e 's/^(mockbuild:)!!/$1/;' /etc/passwd", ''), {'raiseExc': True})
>2007-11-26 09:25:19,196 - trace_decorator.py:20:DEBUG: ENTER: do(("perl -p -i -e 's/^(mockbuild:)!!/$1/;' /etc/passwd", '/var/lib/mock/ldap-i386/root', 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,196 - util.py:196:DEBUG: Run cmd: perl -p -i -e 's/^(mockbuild:)!!/$1/;' /etc/passwd
>2007-11-26 09:25:19,196 - util.py:202:DEBUG: Executing timeout(0): perl -p -i -e 's/^(mockbuild:)!!/$1/;' /etc/passwd
>2007-11-26 09:25:19,198 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', None), {})
>2007-11-26 09:25:19,199 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,199 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 03:25:19,199 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,210 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,210 - trace_decorator.py:30:DEBUG: LEAVE doChroot --> 
>
>2007-11-26 09:25:19,210 - trace_decorator.py:30:DEBUG: LEAVE _makeBuildUser --> None
>
>2007-11-26 09:25:19,211 - trace_decorator.py:20:DEBUG: ENTER: _buildDirSetup((<mock.backend.Root object at 0x882890>,), {})
>2007-11-26 09:25:19,211 - trace_decorator.py:20:DEBUG: ENTER: becomeUser((<mock.uid.uidManager object at 0x882910>, 500, -1), {})
>2007-11-26 09:25:19,211 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,211 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:25:19,211 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 500, -1), {})
>2007-11-26 09:25:19,211 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,211 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,211 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:25:19,212 - trace_decorator.py:30:DEBUG: LEAVE becomeUser --> None
>
>2007-11-26 09:25:19,212 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root//builddir/build/RPMS',), {})
>2007-11-26 09:25:19,212 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root//builddir/build/RPMS
>2007-11-26 09:25:19,212 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root//builddir/build/RPMS
>2007-11-26 09:25:19,212 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:19,212 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root//builddir/build/SRPMS',), {})
>2007-11-26 09:25:19,213 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root//builddir/build/SRPMS
>2007-11-26 09:25:19,213 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root//builddir/build/SRPMS
>2007-11-26 09:25:19,213 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:19,213 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root//builddir/build/SOURCES',), {})
>2007-11-26 09:25:19,213 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root//builddir/build/SOURCES
>2007-11-26 09:25:19,213 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root//builddir/build/SOURCES
>2007-11-26 09:25:19,214 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:19,214 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root//builddir/build/SPECS',), {})
>2007-11-26 09:25:19,214 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root//builddir/build/SPECS
>2007-11-26 09:25:19,214 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root//builddir/build/SPECS
>2007-11-26 09:25:19,214 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:19,214 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root//builddir/build/BUILD',), {})
>2007-11-26 09:25:19,215 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root//builddir/build/BUILD
>2007-11-26 09:25:19,215 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root//builddir/build/BUILD
>2007-11-26 09:25:19,215 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:19,215 - trace_decorator.py:20:DEBUG: ENTER: mkdirIfAbsent(('/var/lib/mock/ldap-i386/root//builddir/build/originals',), {})
>2007-11-26 09:25:19,215 - util.py:38:DEBUG: ensuring that dir exists: /var/lib/mock/ldap-i386/root//builddir/build/originals
>2007-11-26 09:25:19,215 - util.py:41:DEBUG: creating dir: /var/lib/mock/ldap-i386/root//builddir/build/originals
>2007-11-26 09:25:19,215 - trace_decorator.py:30:DEBUG: LEAVE mkdirIfAbsent --> None
>
>2007-11-26 09:25:19,216 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,216 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,216 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,216 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 09:25:19,216 - trace_decorator.py:30:DEBUG: LEAVE _buildDirSetup --> None
>
>2007-11-26 09:25:19,216 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'postinit'), {})
>2007-11-26 09:25:19,217 - trace_decorator.py:20:DEBUG: ENTER: _rootCachePostInitHook((<root_cache.RootCache object at 0x892490>,), {})
>2007-11-26 09:25:19,217 - trace_decorator.py:30:DEBUG: LEAVE _rootCachePostInitHook --> None
>
>2007-11-26 09:25:19,217 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:19,217 - trace_decorator.py:30:DEBUG: LEAVE init --> None
>
>2007-11-26 09:25:19,217 - trace_decorator.py:20:DEBUG: ENTER: build((<mock.backend.Root object at 0x882890>, 'callweaver-1.2-0.3.rc4.20070822.src.rpm', 0), {})
>2007-11-26 09:25:19,217 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'earlyprebuild'), {})
>2007-11-26 09:25:19,217 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:19,217 - trace_decorator.py:20:DEBUG: ENTER: _mountall((<mock.backend.Root object at 0x882890>,), {})
>2007-11-26 09:25:19,218 - backend.py:424:INFO: mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:19,218 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,218 - util.py:196:DEBUG: Run cmd: mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:19,218 - util.py:202:DEBUG: Executing timeout(0): mount -n -t proc   mock_chroot_proc   /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:25:19,219 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,220 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,220 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,220 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,236 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,237 - backend.py:424:INFO: mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:19,237 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,237 - util.py:196:DEBUG: Run cmd: mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:19,237 - util.py:202:DEBUG: Executing timeout(0): mount -n -t devpts mock_chroot_devpts /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:25:19,238 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,239 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,239 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,239 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,255 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,255 - backend.py:424:INFO: mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:19,255 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,256 - util.py:196:DEBUG: Run cmd: mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:19,256 - util.py:202:DEBUG: Executing timeout(0): mount -n -t sysfs  mock_chroot_sysfs  /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:25:19,257 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,257 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,258 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,258 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,274 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,275 - backend.py:424:INFO: mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:19,275 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,275 - util.py:196:DEBUG: Run cmd: mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:19,275 - util.py:202:DEBUG: Executing timeout(0): mount -n --bind /var/lib/mock/cache/ldap-i386/ccache/  /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:25:19,276 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,277 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,277 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,277 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,286 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,286 - backend.py:424:INFO: mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:19,286 - trace_decorator.py:20:DEBUG: ENTER: do(('mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum', None, 0, True, 0, None, None, None), {})
>2007-11-26 09:25:19,286 - util.py:196:DEBUG: Run cmd: mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:19,286 - util.py:202:DEBUG: Executing timeout(0): mount -n --bind /var/lib/mock/cache/ldap-i386/yum_cache/  /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:25:19,288 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:19,288 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:19,288 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:19,288 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,298 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,298 - trace_decorator.py:30:DEBUG: LEAVE _mountall --> None
>
>2007-11-26 09:25:19,298 - trace_decorator.py:20:DEBUG: ENTER: becomeUser((<mock.uid.uidManager object at 0x882910>, 500, -1), {})
>2007-11-26 09:25:19,298 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,299 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:25:19,299 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 500, -1), {})
>2007-11-26 09:25:19,299 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,299 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,299 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:25:19,299 - trace_decorator.py:30:DEBUG: LEAVE becomeUser --> None
>
>2007-11-26 09:25:19,299 - trace_decorator.py:20:DEBUG: ENTER: state((<mock.backend.Root object at 0x882890>, 'setup'), {})
>2007-11-26 09:25:19,300 - trace_decorator.py:30:DEBUG: LEAVE state --> setup
>
>2007-11-26 09:25:19,300 - trace_decorator.py:20:DEBUG: ENTER: _copySrpmIntoChroot((<mock.backend.Root object at 0x882890>, 'callweaver-1.2-0.3.rc4.20070822.src.rpm'), {})
>2007-11-26 09:25:19,300 - trace_decorator.py:20:DEBUG: ENTER: becomeUser((<mock.uid.uidManager object at 0x882910>, 500, -1), {})
>2007-11-26 09:25:19,300 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,300 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:25:19,300 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 500, -1), {})
>2007-11-26 09:25:19,301 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,301 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,301 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:25:19,301 - trace_decorator.py:30:DEBUG: LEAVE becomeUser --> None
>
>2007-11-26 09:25:19,483 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,483 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,483 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,483 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 09:25:19,483 - trace_decorator.py:30:DEBUG: LEAVE _copySrpmIntoChroot --> /builddir/build/originals/callweaver-1.2-0.3.rc4.20070822.src.rpm
>
>2007-11-26 09:25:19,483 - trace_decorator.py:20:DEBUG: ENTER: do(('rpm -Uvh --nodeps /builddir/build/originals/callweaver-1.2-0.3.rc4.20070822.src.rpm', '/var/lib/mock/ldap-i386/root', 0, True, 0, <mock.uid.uidManager object at 0x882910>, 500, 494), {})
>2007-11-26 09:25:19,483 - util.py:196:DEBUG: Run cmd: rpm -Uvh --nodeps /builddir/build/originals/callweaver-1.2-0.3.rc4.20070822.src.rpm
>2007-11-26 09:25:19,484 - util.py:202:DEBUG: Executing timeout(0): rpm -Uvh --nodeps /builddir/build/originals/callweaver-1.2-0.3.rc4.20070822.src.rpm
>2007-11-26 09:25:19,485 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', <mock.uid.uidManager object at 0x882910>), {})
>2007-11-26 09:25:19,486 - util.py:162:DEBUG: elevate privs to run chroot
>2007-11-26 09:25:19,486 - trace_decorator.py:20:DEBUG: ENTER: becomeUser((<mock.uid.uidManager object at 0x882910>, 0, -1), {})
>2007-11-26 09:25:19,486 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,486 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:25:19,486 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 0, -1), {})
>2007-11-26 09:25:19,487 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,487 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,487 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:25:19,487 - trace_decorator.py:30:DEBUG: LEAVE becomeUser --> None
>
>2007-11-26 09:25:19,487 - util.py:167:DEBUG: back to other privs
>2007-11-26 03:25:19,487 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,487 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,488 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 03:25:19,488 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 03:25:19,488 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,488 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((<mock.uid.uidManager object at 0x882910>, 500, 494), {})
>2007-11-26 03:25:19,488 - util.py:173:DEBUG: about to drop privs
>2007-11-26 03:25:19,488 - trace_decorator.py:20:DEBUG: ENTER: dropPrivsForever((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,488 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,488 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 03:25:19,489 - trace_decorator.py:30:DEBUG: LEAVE dropPrivsForever --> None
>
>2007-11-26 03:25:19,489 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:19,671 - util.py:218:DEBUG: warning: /builddir/build/originals/callweaver-1.2-0.3.rc4.20070822.src.rpm: Header V3 DSA signature: NOKEY, key ID 4f2a6fd2
>2007-11-26 09:25:19,671 - util.py:218:DEBUG: callweaver                  warning: user kojibuilder does not exist - using root
>2007-11-26 09:25:19,671 - util.py:218:DEBUG: warning: group kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: ##################################################
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: warning: user kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: warning: group kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: warning: user kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: warning: group kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: warning: user kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - util.py:218:DEBUG: warning: group kojibuilder does not exist - using root
>2007-11-26 09:25:19,672 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:25:19,673 - backend.py:345:INFO: about to drop to unpriv mode.
>2007-11-26 09:25:19,675 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', <mock.uid.uidManager object at 0x882910>), {})
>2007-11-26 09:25:19,676 - util.py:162:DEBUG: elevate privs to run chroot
>2007-11-26 09:25:19,676 - trace_decorator.py:20:DEBUG: ENTER: becomeUser((<mock.uid.uidManager object at 0x882910>, 0, -1), {})
>2007-11-26 09:25:19,676 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,676 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:25:19,677 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 0, -1), {})
>2007-11-26 09:25:19,677 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:19,677 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:19,677 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:25:19,677 - trace_decorator.py:30:DEBUG: LEAVE becomeUser --> None
>
>2007-11-26 09:25:19,677 - util.py:167:DEBUG: back to other privs
>2007-11-26 03:25:19,678 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,678 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,678 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 03:25:19,678 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 03:25:19,678 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:25:19,678 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((<mock.uid.uidManager object at 0x882910>, 500, 494), {})
>2007-11-26 03:25:19,678 - util.py:173:DEBUG: about to drop privs
>2007-11-26 03:25:19,678 - trace_decorator.py:20:DEBUG: ENTER: dropPrivsForever((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,679 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:25:19,679 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 03:25:19,679 - trace_decorator.py:30:DEBUG: LEAVE dropPrivsForever --> None
>
>2007-11-26 03:25:19,679 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:20,462 - trace_decorator.py:20:DEBUG: ENTER: installSrpmDeps((<mock.backend.Root object at 0x882890>, '/var/lib/mock/ldap-i386/root//builddir/build/SRPMS/callweaver-1.2-0.3.rc4.20070822.src.rpm'), {})
>2007-11-26 09:25:20,462 - trace_decorator.py:20:DEBUG: ENTER: dropPrivsTemp((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:20,462 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:20,462 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:25:20,462 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 500, 500), {})
>2007-11-26 09:25:20,462 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:20,463 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:20,463 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:25:20,463 - trace_decorator.py:30:DEBUG: LEAVE dropPrivsTemp --> None
>
>2007-11-26 09:25:20,463 - trace_decorator.py:20:DEBUG: ENTER: yieldSrpmHeaders((('/var/lib/mock/ldap-i386/root//builddir/build/SRPMS/callweaver-1.2-0.3.rc4.20070822.src.rpm',), 0), {})
>2007-11-26 09:25:20,463 - trace_decorator.py:30:DEBUG: LEAVE yieldSrpmHeaders --> <generator object at 0x88dc20>
>
>2007-11-26 09:25:20,463 - trace_decorator.py:20:DEBUG: ENTER: requiresTextFromHdr((<rpm.hdr object at 0x88b870>,), {})
>2007-11-26 09:25:20,464 - trace_decorator.py:30:DEBUG: LEAVE requiresTextFromHdr --> ['libpri-devel', 'bluez-libs-devel', 'mISDN-devel', 'popt', 'openldap-devel', 'mysql-devel', 'openssl-devel', 'automake', 'fedora-usermgmt-devel', 'unixODBC-devel', 'spandsp-devel >= 0.0.4-0.1.pre4', 'alsa-lib-devel', 'js-devel', 'ncurses-devel', 'speex-devel', 'zaptel-devel', 'libtool', 'libcap-devel', 'postgresql-devel', 'readline-devel', 'zlib-devel', 'isdn4k-utils-devel', 'sqlite-devel', 'libjpeg-devel', 'nspr-devel', 'loudmouth-devel', 'autoconf']
>
>2007-11-26 09:25:20,464 - trace_decorator.py:20:DEBUG: ENTER: getAddtlReqs((<rpm.hdr object at 0x88b870>, {}), {})
>2007-11-26 09:25:20,464 - trace_decorator.py:20:DEBUG: ENTER: getNEVRA((<rpm.hdr object at 0x88b870>,), {})
>2007-11-26 09:25:20,464 - trace_decorator.py:30:DEBUG: LEAVE getNEVRA --> ('callweaver', 0, '1.2', '0.3.rc4.20070822', 'i386')
>
>2007-11-26 09:25:20,464 - trace_decorator.py:30:DEBUG: LEAVE getAddtlReqs --> []
>
>2007-11-26 09:25:20,464 - trace_decorator.py:20:DEBUG: ENTER: uniqReqs((['libpri-devel', 'bluez-libs-devel', 'mISDN-devel', 'popt', 'openldap-devel', 'mysql-devel', 'openssl-devel', 'automake', 'fedora-usermgmt-devel', 'unixODBC-devel', 'spandsp-devel >= 0.0.4-0.1.pre4', 'alsa-lib-devel', 'js-devel', 'ncurses-devel', 'speex-devel', 'zaptel-devel', 'libtool', 'libcap-devel', 'postgresql-devel', 'readline-devel', 'zlib-devel', 'isdn4k-utils-devel', 'sqlite-devel', 'libjpeg-devel', 'nspr-devel', 'loudmouth-devel', 'autoconf'], []), {})
>2007-11-26 09:25:20,465 - trace_decorator.py:30:DEBUG: LEAVE uniqReqs --> ['libpri-devel', 'bluez-libs-devel', 'mISDN-devel', 'popt', 'openldap-devel', 'mysql-devel', 'openssl-devel', 'automake', 'fedora-usermgmt-devel', 'unixODBC-devel', 'spandsp-devel >= 0.0.4-0.1.pre4', 'alsa-lib-devel', 'js-devel', 'ncurses-devel', 'speex-devel', 'zaptel-devel', 'libtool', 'libcap-devel', 'postgresql-devel', 'sqlite-devel', 'zlib-devel', 'isdn4k-utils-devel', 'readline-devel', 'libjpeg-devel', 'nspr-devel', 'loudmouth-devel', 'autoconf']
>
>2007-11-26 09:25:20,465 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:20,465 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:25:20,465 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:25:20,465 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 09:25:20,465 - trace_decorator.py:20:DEBUG: ENTER: _yum((<mock.backend.Root object at 0x882890>, "resolvedep ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'", 1), {})
>2007-11-26 09:25:20,465 - backend.py:439:INFO: /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root resolvedep ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'
>2007-11-26 09:25:20,466 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'preyum'), {})
>2007-11-26 09:25:20,466 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePreYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:20,466 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePreYumHook --> None
>
>2007-11-26 09:25:20,466 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:20,466 - trace_decorator.py:20:DEBUG: ENTER: do(("/usr/bin/yum --installroot /var/lib/mock/ldap-i386/root resolvedep ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'", None, 0, True, 1, None, None, None), {})
>2007-11-26 09:25:20,466 - util.py:196:DEBUG: Run cmd: /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root resolvedep ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'
>2007-11-26 09:25:20,466 - util.py:202:DEBUG: Executing timeout(0): /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root resolvedep ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'
>2007-11-26 09:25:20,467 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:20,468 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:20,468 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:20,468 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:25:21,485 - util.py:218:DEBUG: 0:ccache-2.4-11.fc8.i386
>2007-11-26 09:25:21,485 - util.py:218:DEBUG: 0:libpri-devel-1.4.2-1.fc9.i386
>2007-11-26 09:25:21,485 - util.py:218:DEBUG: 0:bluez-libs-devel-3.22-1.fc9.i386
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:mISDN-devel-1.1.5-1.fc8.i386
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:popt-1.12-3.fc8.i386
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:openldap-devel-2.4.6-0.fc9.preview.i386
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:mysql-devel-5.0.45-4.fc8.i386
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:openssl-devel-0.9.8b-17.fc8.i386
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:automake-1.10-7.noarch
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:fedora-usermgmt-devel-0.10-1.fc8.noarch
>2007-11-26 09:25:21,486 - util.py:218:DEBUG: 0:unixODBC-devel-2.2.12-4.fc8.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:spandsp-devel-0.0.4-0.7.pre15.fc9.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:alsa-lib-devel-1.0.15-1.fc9.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:js-devel-1.60-4.fc8.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:ncurses-devel-5.6-12.20070812.fc8.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:speex-devel-1.2-0.3.beta2.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:zaptel-devel-1.4.6-1.fc9.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:libtool-1.5.24-3.fc8.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:libcap-devel-1.10-30.i386
>2007-11-26 09:25:21,487 - util.py:218:DEBUG: 0:postgresql-devel-8.2.5-1.fc8.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:sqlite-devel-3.4.2-3.fc8.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:zlib-devel-1.2.3-16.fc9.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:isdn4k-utils-devel-3.2-55.fc8.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:readline-devel-5.2-8.fc9.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:libjpeg-devel-6b-39.fc8.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:nspr-devel-4.6.99.2-1.fc9.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:loudmouth-devel-1.2.3-6.fc9.i386
>2007-11-26 09:25:21,488 - util.py:218:DEBUG: 0:autoconf-2.61-10.fc9.noarch
>2007-11-26 09:25:21,489 - trace_decorator.py:30:DEBUG: LEAVE do --> 0:ccache-2.4-11.fc8.i386
>0:libpri-devel-1.4.2-1.fc9.i386
>0:bluez-libs-devel-3.22-1.fc9.i386
>0:mISDN-devel-1.1.5-1.fc8.i386
>0:popt-1.12-3.fc8.i386
>0:openldap-devel-2.4.6-0.fc9.preview.i386
>0:mysql-devel-5.0.45-4.fc8.i386
>0:openssl-devel-0.9.8b-17.fc8.i386
>0:automake-1.10-7.noarch
>0:fedora-usermgmt-devel-0.10-1.fc8.noarch
>0:unixODBC-devel-2.2.12-4.fc8.i386
>0:spandsp-devel-0.0.4-0.7.pre15.fc9.i386
>0:alsa-lib-devel-1.0.15-1.fc9.i386
>0:js-devel-1.60-4.fc8.i386
>0:ncurses-devel-5.6-12.20070812.fc8.i386
>0:speex-devel-1.2-0.3.beta2.i386
>0:zaptel-devel-1.4.6-1.fc9.i386
>0:libtool-1.5.24-3.fc8.i386
>0:libcap-devel-1.10-30.i386
>0:postgresql-devel-8.2.5-1.fc8.i386
>0:sqlite-devel-3.4.2-3.fc8.i386
>0:zlib-devel-1.2.3-16.fc9.i386
>0:isdn4k-utils-devel-3.2-55.fc8.i386
>0:readline-devel-5.2-8.fc9.i386
>0:libjpeg-devel-6b-39.fc8.i386
>0:nspr-devel-4.6.99.2-1.fc9.i386
>0:loudmouth-devel-1.2.3-6.fc9.i386
>0:autoconf-2.61-10.fc9.noarch
>
>
>2007-11-26 09:25:21,489 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'postyum'), {})
>2007-11-26 09:25:21,489 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePostYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:21,489 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePostYumHook --> None
>
>2007-11-26 09:25:21,489 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:21,489 - trace_decorator.py:30:DEBUG: LEAVE _yum --> 0:ccache-2.4-11.fc8.i386
>0:libpri-devel-1.4.2-1.fc9.i386
>0:bluez-libs-devel-3.22-1.fc9.i386
>0:mISDN-devel-1.1.5-1.fc8.i386
>0:popt-1.12-3.fc8.i386
>0:openldap-devel-2.4.6-0.fc9.preview.i386
>0:mysql-devel-5.0.45-4.fc8.i386
>0:openssl-devel-0.9.8b-17.fc8.i386
>0:automake-1.10-7.noarch
>0:fedora-usermgmt-devel-0.10-1.fc8.noarch
>0:unixODBC-devel-2.2.12-4.fc8.i386
>0:spandsp-devel-0.0.4-0.7.pre15.fc9.i386
>0:alsa-lib-devel-1.0.15-1.fc9.i386
>0:js-devel-1.60-4.fc8.i386
>0:ncurses-devel-5.6-12.20070812.fc8.i386
>0:speex-devel-1.2-0.3.beta2.i386
>0:zaptel-devel-1.4.6-1.fc9.i386
>0:libtool-1.5.24-3.fc8.i386
>0:libcap-devel-1.10-30.i386
>0:postgresql-devel-8.2.5-1.fc8.i386
>0:sqlite-devel-3.4.2-3.fc8.i386
>0:zlib-devel-1.2.3-16.fc9.i386
>0:isdn4k-utils-devel-3.2-55.fc8.i386
>0:readline-devel-5.2-8.fc9.i386
>0:libjpeg-devel-6b-39.fc8.i386
>0:nspr-devel-4.6.99.2-1.fc9.i386
>0:loudmouth-devel-1.2.3-6.fc9.i386
>0:autoconf-2.61-10.fc9.noarch
>
>
>2007-11-26 09:25:21,489 - trace_decorator.py:20:DEBUG: ENTER: _yum((<mock.backend.Root object at 0x882890>, "install ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'", 1), {})
>2007-11-26 09:25:21,490 - backend.py:439:INFO: /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root install ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'
>2007-11-26 09:25:21,490 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'preyum'), {})
>2007-11-26 09:25:21,490 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePreYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:25:21,490 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePreYumHook --> None
>
>2007-11-26 09:25:21,490 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:25:21,490 - trace_decorator.py:20:DEBUG: ENTER: do(("/usr/bin/yum --installroot /var/lib/mock/ldap-i386/root install ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'", None, 0, True, 1, None, None, None), {})
>2007-11-26 09:25:21,490 - util.py:196:DEBUG: Run cmd: /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root install ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'
>2007-11-26 09:25:21,490 - util.py:202:DEBUG: Executing timeout(0): /usr/bin/yum --installroot /var/lib/mock/ldap-i386/root install ccache 'libpri-devel' 'bluez-libs-devel' 'mISDN-devel' 'popt' 'openldap-devel' 'mysql-devel' 'openssl-devel' 'automake' 'fedora-usermgmt-devel' 'unixODBC-devel' 'spandsp-devel >= 0.0.4-0.1.pre4' 'alsa-lib-devel' 'js-devel' 'ncurses-devel' 'speex-devel' 'zaptel-devel' 'libtool' 'libcap-devel' 'postgresql-devel' 'sqlite-devel' 'zlib-devel' 'isdn4k-utils-devel' 'readline-devel' 'libjpeg-devel' 'nspr-devel' 'loudmouth-devel' 'autoconf'
>2007-11-26 09:25:21,492 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:25:21,492 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:25:21,492 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:25:21,492 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:26:13,944 - util.py:218:DEBUG: 
>2007-11-26 09:26:13,944 - util.py:218:DEBUG: =============================================================================
>2007-11-26 09:26:13,944 - util.py:218:DEBUG:  Package                 Arch       Version          Repository        Size 
>2007-11-26 09:26:13,944 - util.py:218:DEBUG: =============================================================================
>2007-11-26 09:26:13,945 - util.py:218:DEBUG: Installing:
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  alsa-lib-devel          i386       1.0.15-1.fc9     fedora            989 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  bluez-libs-devel        i386       3.22-1.fc9       fedora             65 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  ccache                  i386       2.4-11.fc8       fedora             52 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  fedora-usermgmt-devel   noarch     0.10-1.fc8       fedora            6.4 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  isdn4k-utils-devel      i386       3.2-55.fc8       fedora             41 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  js-devel                i386       1.60-4.fc8       fedora            543 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  libcap-devel            i386       1.10-30          fedora             20 k
>2007-11-26 09:26:13,945 - util.py:218:DEBUG:  libjpeg-devel           i386       6b-39.fc8        fedora            104 k
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  libpri-devel            i386       1.4.2-1.fc9      fedora             11 k
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  libtool                 i386       1.5.24-3.fc8     fedora            684 k
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  loudmouth-devel         i386       1.2.3-6.fc9      fedora             36 k
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  mISDN-devel             i386       1.1.5-1.fc8      fedora             27 k
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  mysql-devel             i386       5.0.45-4.fc8     fedora            2.4 M
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  nspr-devel              i386       4.6.99.2-1.fc9   fedora            110 k
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  openldap-devel          i386       2.4.6-0.fc9.preview  ldap              1.5 M
>2007-11-26 09:26:13,946 - util.py:218:DEBUG:  postgresql-devel        i386       8.2.5-1.fc8      fedora            1.2 M
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  readline-devel          i386       5.2-8.fc9        fedora            132 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  spandsp-devel           i386       0.0.4-0.7.pre15.fc9  fedora            114 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  speex-devel             i386       1.2-0.3.beta2    fedora            112 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  sqlite-devel            i386       3.4.2-3.fc8      fedora            307 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  unixODBC-devel          i386       2.2.12-4.fc8     fedora            809 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  zaptel-devel            i386       1.4.6-1.fc9      fedora             29 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG: Installing for dependencies:
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  MAKEDEV                 i386       3.23-1.2         fedora            135 k
>2007-11-26 09:26:13,947 - util.py:218:DEBUG:  alsa-lib                i386       1.0.15-1.fc9     fedora            408 k
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  autoconf                noarch     2.61-10.fc9      fedora            799 k
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  automake                noarch     1.10-7           fedora            514 k
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  bluez-libs              i386       3.22-1.fc9       fedora             57 k
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  cyrus-sasl-devel        i386       2.1.22-9.fc9     fedora            351 k
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  cyrus-sasl-lib          i386       2.1.22-9.fc9     fedora            1.1 M
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  device-mapper           i386       1.02.22-1.fc8    fedora            466 k
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  e2fsprogs               i386       1.40.2-10.fc8    fedora            1.0 M
>2007-11-26 09:26:13,948 - util.py:218:DEBUG:  e2fsprogs-devel         i386       1.40.2-10.fc8    fedora            635 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  ethtool                 i386       5-1.fc7          fedora             60 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  fedora-usermgmt         noarch     0.10-1.fc8       fedora            7.0 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  fedora-usermgmt-core    noarch     0.10-1.fc8       fedora            8.2 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  fedora-usermgmt-default-fedora-setup  noarch     0.10-1.fc8       fedora            6.9 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  fedora-usermgmt-shadow-utils  noarch     0.10-1.fc8       fedora            7.8 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  glib2                   i386       2.14.4-1.fc9     fedora            824 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  glib2-devel             i386       2.14.4-1.fc9     fedora            922 k
>2007-11-26 09:26:13,949 - util.py:218:DEBUG:  gnutls                  i386       1.6.3-2.fc8      fedora            396 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  gnutls-devel            i386       1.6.3-2.fc8      fedora            948 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  hwdata                  noarch     0.207-2.fc9      fedora            261 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  imake                   i386       1.0.2-5.fc8      fedora            312 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  initscripts             i386       8.60-1           fedora            1.6 M
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  iproute                 i386       2.6.23-1.fc9     fedora            826 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  iputils                 i386       20070202-5.fc8   fedora            126 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  isdn4k-utils            i386       3.2-55.fc8       fedora            3.8 M
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  js                      i386       1.60-4.fc8       fedora            401 k
>2007-11-26 09:26:13,950 - util.py:218:DEBUG:  keyutils-libs           i386       1.2-2.fc6        fedora             18 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  keyutils-libs-devel     i386       1.2-2.fc6        fedora             28 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  krb5-devel              i386       1.6.3-1.fc9      fedora            1.1 M
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  krb5-libs               i386       1.6.3-1.fc9      fedora            715 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  libgcrypt               i386       1.2.4-6          fedora            176 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  libgcrypt-devel         i386       1.2.4-6          fedora             74 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  libgpg-error            i386       1.5-6            fedora             62 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  libgpg-error-devel      i386       1.5-6            fedora             12 k
>2007-11-26 09:26:13,951 - util.py:218:DEBUG:  libidn                  i386       0.6.14-4         fedora            210 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libidn-devel            i386       0.6.14-4         fedora            195 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libjpeg                 i386       6b-39.fc8        fedora            139 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libogg                  i386       2:1.1.3-8.fc9    fedora             19 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libpcap                 i386       14:0.9.8-1.fc9   fedora            102 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libpri                  i386       1.4.2-1.fc9      fedora             67 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libselinux-devel        i386       2.0.42-1.fc9     fedora            155 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libsepol-devel          i386       2.0.14-1.fc9     fedora            181 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libsysfs                i386       2.1.0-1.fc7      fedora             48 k
>2007-11-26 09:26:13,952 - util.py:218:DEBUG:  libtiff                 i386       3.8.2-9.fc8      fedora            308 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  libtiff-devel           i386       3.8.2-9.fc8      fedora            465 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  libvolume_id            i386       116-3.fc8        fedora             53 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  loudmouth               i386       1.2.3-6.fc9      fedora             60 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  m4                      i386       1.4.10-1.fc8     fedora            209 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  mISDN                   i386       1.1.5-1.fc8      fedora             93 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  mingetty                i386       1.07-6           fedora             19 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  module-init-tools       i386       3.4-2.fc8        fedora            414 k
>2007-11-26 09:26:13,953 - util.py:218:DEBUG:  mysql                   i386       5.0.45-4.fc8     fedora            2.7 M
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  mysql-libs              i386       5.0.45-4.fc8     fedora            1.5 M
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  ncurses-devel           i386       5.6-12.20070812.fc8  fedora            653 k
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  net-tools               i386       1.60-84.fc8      fedora            361 k
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  openldap                i386       2.4.6-0.fc9.preview  ldap              302 k
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  openssl                 i386       0.9.8b-17.fc8    fedora            1.4 M
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  openssl-devel           i386       0.9.8b-17.fc8    fedora            1.8 M
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  perl-DBI                i386       1.601-1.fc9      fedora            729 k
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  pkgconfig               i386       1:0.22-4.fc9     fedora             66 k
>2007-11-26 09:26:13,954 - util.py:218:DEBUG:  postgresql              i386       8.2.5-1.fc8      fedora            3.0 M
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  postgresql-libs         i386       8.2.5-1.fc8      fedora            197 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  procps                  i386       3.2.7-17.fc8     fedora            210 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  psmisc                  i386       22.5-2.fc8       fedora             71 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  rsyslog                 i386       1.19.10-1.fc9    fedora            201 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  spandsp                 i386       0.0.4-0.7.pre15.fc9  fedora            282 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  speex                   i386       1.2-0.3.beta2    fedora            519 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  sysvinit                i386       2.86-18          fedora            110 k
>2007-11-26 09:26:13,955 - util.py:218:DEBUG:  udev                    i386       116-3.fc8        fedora            327 k
>2007-11-26 09:26:13,956 - util.py:218:DEBUG:  unixODBC                i386       2.2.12-4.fc8     fedora            870 k
>2007-11-26 09:26:13,956 - util.py:218:DEBUG:  zaptel-lib              i386       1.4.6-1.fc9      fedora             14 k
>2007-11-26 09:26:13,956 - util.py:218:DEBUG:  zlib-devel              i386       1.2.3-16.fc9     fedora             42 k
>2007-11-26 09:26:13,956 - util.py:218:DEBUG: 
>2007-11-26 09:26:13,956 - util.py:218:DEBUG: Transaction Summary
>2007-11-26 09:26:13,956 - util.py:218:DEBUG: =============================================================================
>2007-11-26 09:26:13,956 - util.py:218:DEBUG: Install     94 Package(s)         
>2007-11-26 09:26:13,956 - util.py:218:DEBUG: Update       0 Package(s)         
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: Remove       0 Package(s)         
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: 
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: Total download size: 45 M
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: http://ftp.iasi.roedu.net/mirrors/fedora.redhat.com/linux/development/i386/os/Packages/glib2-devel-2.14.4-1.fc9.i386.rpm: [Errno 14] HTTP Error 404: Not Found
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: Trying other mirror.
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: 
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: Installed: alsa-lib-devel.i386 0:1.0.15-1.fc9 bluez-libs-devel.i386 0:3.22-1.fc9 ccache.i386 0:2.4-11.fc8 fedora-usermgmt-devel.noarch 0:0.10-1.fc8 isdn4k-utils-devel.i386 0:3.2-55.fc8 js-devel.i386 0:1.60-4.fc8 libcap-devel.i386 0:1.10-30 libjpeg-devel.i386 0:6b-39.fc8 libpri-devel.i386 0:1.4.2-1.fc9 libtool.i386 0:1.5.24-3.fc8 loudmouth-devel.i386 0:1.2.3-6.fc9 mISDN-devel.i386 0:1.1.5-1.fc8 mysql-devel.i386 0:5.0.45-4.fc8 nspr-devel.i386 0:4.6.99.2-1.fc9 openldap-devel.i386 0:2.4.6-0.fc9.preview postgresql-devel.i386 0:8.2.5-1.fc8 readline-devel.i386 0:5.2-8.fc9 spandsp-devel.i386 0:0.0.4-0.7.pre15.fc9 speex-devel.i386 0:1.2-0.3.beta2 sqlite-devel.i386 0:3.4.2-3.fc8 unixODBC-devel.i386 0:2.2.12-4.fc8 zaptel-devel.i386 0:1.4.6-1.fc9
>2007-11-26 09:26:13,957 - util.py:218:DEBUG: Dependency Installed: MAKEDEV.i386 0:3.23-1.2 alsa-lib.i386 0:1.0.15-1.fc9 autoconf.noarch 0:2.61-10.fc9 automake.noarch 0:1.10-7 bluez-libs.i386 0:3.22-1.fc9 cyrus-sasl-devel.i386 0:2.1.22-9.fc9 cyrus-sasl-lib.i386 0:2.1.22-9.fc9 device-mapper.i386 0:1.02.22-1.fc8 e2fsprogs.i386 0:1.40.2-10.fc8 e2fsprogs-devel.i386 0:1.40.2-10.fc8 ethtool.i386 0:5-1.fc7 fedora-usermgmt.noarch 0:0.10-1.fc8 fedora-usermgmt-core.noarch 0:0.10-1.fc8 fedora-usermgmt-default-fedora-setup.noarch 0:0.10-1.fc8 fedora-usermgmt-shadow-utils.noarch 0:0.10-1.fc8 glib2.i386 0:2.14.4-1.fc9 glib2-devel.i386 0:2.14.4-1.fc9 gnutls.i386 0:1.6.3-2.fc8 gnutls-devel.i386 0:1.6.3-2.fc8 hwdata.noarch 0:0.207-2.fc9 imake.i386 0:1.0.2-5.fc8 initscripts.i386 0:8.60-1 iproute.i386 0:2.6.23-1.fc9 iputils.i386 0:20070202-5.fc8 isdn4k-utils.i386 0:3.2-55.fc8 js.i386 0:1.60-4.fc8 keyutils-libs.i386 0:1.2-2.fc6 keyutils-libs-devel.i386 0:1.2-2.fc6 krb5-devel.i386 0:1.6.3-1.fc9 krb5-libs.i386 0:1.6.3-1.fc9 libgcrypt.i386 0:1.2.4-6 libgcrypt-devel.i386 0:1.2.4-6 libgpg-error.i386 0:1.5-6 libgpg-error-devel.i386 0:1.5-6 libidn.i386 0:0.6.14-4 libidn-devel.i386 0:0.6.14-4 libjpeg.i386 0:6b-39.fc8 libogg.i386 2:1.1.3-8.fc9 libpcap.i386 14:0.9.8-1.fc9 libpri.i386 0:1.4.2-1.fc9 libselinux-devel.i386 0:2.0.42-1.fc9 libsepol-devel.i386 0:2.0.14-1.fc9 libsysfs.i386 0:2.1.0-1.fc7 libtiff.i386 0:3.8.2-9.fc8 libtiff-devel.i386 0:3.8.2-9.fc8 libvolume_id.i386 0:116-3.fc8 loudmouth.i386 0:1.2.3-6.fc9 m4.i386 0:1.4.10-1.fc8 mISDN.i386 0:1.1.5-1.fc8 mingetty.i386 0:1.07-6 module-init-tools.i386 0:3.4-2.fc8 mysql.i386 0:5.0.45-4.fc8 mysql-libs.i386 0:5.0.45-4.fc8 ncurses-devel.i386 0:5.6-12.20070812.fc8 net-tools.i386 0:1.60-84.fc8 openldap.i386 0:2.4.6-0.fc9.preview openssl.i386 0:0.9.8b-17.fc8 openssl-devel.i386 0:0.9.8b-17.fc8 perl-DBI.i386 0:1.601-1.fc9 pkgconfig.i386 1:0.22-4.fc9 postgresql.i386 0:8.2.5-1.fc8 postgresql-libs.i386 0:8.2.5-1.fc8 procps.i386 0:3.2.7-17.fc8 psmisc.i386 0:22.5-2.fc8 rsyslog.i386 0:1.19.10-1.fc9 spandsp.i386 0:0.0.4-0.7.pre15.fc9 speex.i386 0:1.2-0.3.beta2 sysvinit.i386 0:2.86-18 udev.i386 0:116-3.fc8 unixODBC.i386 0:2.2.12-4.fc8 zaptel-lib.i386 0:1.4.6-1.fc9 zlib-devel.i386 0:1.2.3-16.fc9
>2007-11-26 09:26:13,958 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>=============================================================================
> Package                 Arch       Version          Repository        Size 
>=============================================================================
>Installing:
> alsa-lib-devel          i386       1.0.15-1.fc9     fedora            989 k
> bluez-libs-devel        i386       3.22-1.fc9       fedora             65 k
> ccache                  i386       2.4-11.fc8       fedora             52 k
> fedora-usermgmt-devel   noarch     0.10-1.fc8       fedora            6.4 k
> isdn4k-utils-devel      i386       3.2-55.fc8       fedora             41 k
> js-devel                i386       1.60-4.fc8       fedora            543 k
> libcap-devel            i386       1.10-30          fedora             20 k
> libjpeg-devel           i386       6b-39.fc8        fedora            104 k
> libpri-devel            i386       1.4.2-1.fc9      fedora             11 k
> libtool                 i386       1.5.24-3.fc8     fedora            684 k
> loudmouth-devel         i386       1.2.3-6.fc9      fedora             36 k
> mISDN-devel             i386       1.1.5-1.fc8      fedora             27 k
> mysql-devel             i386       5.0.45-4.fc8     fedora            2.4 M
> nspr-devel              i386       4.6.99.2-1.fc9   fedora            110 k
> openldap-devel          i386       2.4.6-0.fc9.preview  ldap              1.5 M
> postgresql-devel        i386       8.2.5-1.fc8      fedora            1.2 M
> readline-devel          i386       5.2-8.fc9        fedora            132 k
> spandsp-devel           i386       0.0.4-0.7.pre15.fc9  fedora            114 k
> speex-devel             i386       1.2-0.3.beta2    fedora            112 k
> sqlite-devel            i386       3.4.2-3.fc8      fedora            307 k
> unixODBC-devel          i386       2.2.12-4.fc8     fedora            809 k
> zaptel-devel            i386       1.4.6-1.fc9      fedora             29 k
>Installing for dependencies:
> MAKEDEV                 i386       3.23-1.2         fedora            135 k
> alsa-lib                i386       1.0.15-1.fc9     fedora            408 k
> autoconf                noarch     2.61-10.fc9      fedora            799 k
> automake                noarch     1.10-7           fedora            514 k
> bluez-libs              i386       3.22-1.fc9       fedora             57 k
> cyrus-sasl-devel        i386       2.1.22-9.fc9     fedora            351 k
> cyrus-sasl-lib          i386       2.1.22-9.fc9     fedora            1.1 M
> device-mapper           i386       1.02.22-1.fc8    fedora            466 k
> e2fsprogs               i386       1.40.2-10.fc8    fedora            1.0 M
> e2fsprogs-devel         i386       1.40.2-10.fc8    fedora            635 k
> ethtool                 i386       5-1.fc7          fedora             60 k
> fedora-usermgmt         noarch     0.10-1.fc8       fedora            7.0 k
> fedora-usermgmt-core    noarch     0.10-1.fc8       fedora            8.2 k
> fedora-usermgmt-default-fedora-setup  noarch     0.10-1.fc8       fedora            6.9 k
> fedora-usermgmt-shadow-utils  noarch     0.10-1.fc8       fedora            7.8 k
> glib2                   i386       2.14.4-1.fc9     fedora            824 k
> glib2-devel             i386       2.14.4-1.fc9     fedora            922 k
> gnutls                  i386       1.6.3-2.fc8      fedora            396 k
> gnutls-devel            i386       1.6.3-2.fc8      fedora            948 k
> hwdata                  noarch     0.207-2.fc9      fedora            261 k
> imake                   i386       1.0.2-5.fc8      fedora            312 k
> initscripts             i386       8.60-1           fedora            1.6 M
> iproute                 i386       2.6.23-1.fc9     fedora            826 k
> iputils                 i386       20070202-5.fc8   fedora            126 k
> isdn4k-utils            i386       3.2-55.fc8       fedora            3.8 M
> js                      i386       1.60-4.fc8       fedora            401 k
> keyutils-libs           i386       1.2-2.fc6        fedora             18 k
> keyutils-libs-devel     i386       1.2-2.fc6        fedora             28 k
> krb5-devel              i386       1.6.3-1.fc9      fedora            1.1 M
> krb5-libs               i386       1.6.3-1.fc9      fedora            715 k
> libgcrypt               i386       1.2.4-6          fedora            176 k
> libgcrypt-devel         i386       1.2.4-6          fedora             74 k
> libgpg-error            i386       1.5-6            fedora             62 k
> libgpg-error-devel      i386       1.5-6            fedora             12 k
> libidn                  i386       0.6.14-4         fedora            210 k
> libidn-devel            i386       0.6.14-4         fedora            195 k
> libjpeg                 i386       6b-39.fc8        fedora            139 k
> libogg                  i386       2:1.1.3-8.fc9    fedora             19 k
> libpcap                 i386       14:0.9.8-1.fc9   fedora            102 k
> libpri                  i386       1.4.2-1.fc9      fedora             67 k
> libselinux-devel        i386       2.0.42-1.fc9     fedora            155 k
> libsepol-devel          i386       2.0.14-1.fc9     fedora            181 k
> libsysfs                i386       2.1.0-1.fc7      fedora             48 k
> libtiff                 i386       3.8.2-9.fc8      fedora            308 k
> libtiff-devel           i386       3.8.2-9.fc8      fedora            465 k
> libvolume_id            i386       116-3.fc8        fedora             53 k
> loudmouth               i386       1.2.3-6.fc9      fedora             60 k
> m4                      i386       1.4.10-1.fc8     fedora            209 k
> mISDN                   i386       1.1.5-1.fc8      fedora             93 k
> mingetty                i386       1.07-6           fedora             19 k
> module-init-tools       i386       3.4-2.fc8        fedora            414 k
> mysql                   i386       5.0.45-4.fc8     fedora            2.7 M
> mysql-libs              i386       5.0.45-4.fc8     fedora            1.5 M
> ncurses-devel           i386       5.6-12.20070812.fc8  fedora            653 k
> net-tools               i386       1.60-84.fc8      fedora            361 k
> openldap                i386       2.4.6-0.fc9.preview  ldap              302 k
> openssl                 i386       0.9.8b-17.fc8    fedora            1.4 M
> openssl-devel           i386       0.9.8b-17.fc8    fedora            1.8 M
> perl-DBI                i386       1.601-1.fc9      fedora            729 k
> pkgconfig               i386       1:0.22-4.fc9     fedora             66 k
> postgresql              i386       8.2.5-1.fc8      fedora            3.0 M
> postgresql-libs         i386       8.2.5-1.fc8      fedora            197 k
> procps                  i386       3.2.7-17.fc8     fedora            210 k
> psmisc                  i386       22.5-2.fc8       fedora             71 k
> rsyslog                 i386       1.19.10-1.fc9    fedora            201 k
> spandsp                 i386       0.0.4-0.7.pre15.fc9  fedora            282 k
> speex                   i386       1.2-0.3.beta2    fedora            519 k
> sysvinit                i386       2.86-18          fedora            110 k
> udev                    i386       116-3.fc8        fedora            327 k
> unixODBC                i386       2.2.12-4.fc8     fedora            870 k
> zaptel-lib              i386       1.4.6-1.fc9      fedora             14 k
> zlib-devel              i386       1.2.3-16.fc9     fedora             42 k
>
>Transaction Summary
>=============================================================================
>Install     94 Package(s)         
>Update       0 Package(s)         
>Remove       0 Package(s)         
>
>Total download size: 45 M
>http://ftp.iasi.roedu.net/mirrors/fedora.redhat.com/linux/development/i386/os/Packages/glib2-devel-2.14.4-1.fc9.i386.rpm: [Errno 14] HTTP Error 404: Not Found
>Trying other mirror.
>
>Installed: alsa-lib-devel.i386 0:1.0.15-1.fc9 bluez-libs-devel.i386 0:3.22-1.fc9 ccache.i386 0:2.4-11.fc8 fedora-usermgmt-devel.noarch 0:0.10-1.fc8 isdn4k-utils-devel.i386 0:3.2-55.fc8 js-devel.i386 0:1.60-4.fc8 libcap-devel.i386 0:1.10-30 libjpeg-devel.i386 0:6b-39.fc8 libpri-devel.i386 0:1.4.2-1.fc9 libtool.i386 0:1.5.24-3.fc8 loudmouth-devel.i386 0:1.2.3-6.fc9 mISDN-devel.i386 0:1.1.5-1.fc8 mysql-devel.i386 0:5.0.45-4.fc8 nspr-devel.i386 0:4.6.99.2-1.fc9 openldap-devel.i386 0:2.4.6-0.fc9.preview postgresql-devel.i386 0:8.2.5-1.fc8 readline-devel.i386 0:5.2-8.fc9 spandsp-devel.i386 0:0.0.4-0.7.pre15.fc9 speex-devel.i386 0:1.2-0.3.beta2 sqlite-devel.i386 0:3.4.2-3.fc8 unixODBC-devel.i386 0:2.2.12-4.fc8 zaptel-devel.i386 0:1.4.6-1.fc9
>Dependency Installed: MAKEDEV.i386 0:3.23-1.2 alsa-lib.i386 0:1.0.15-1.fc9 autoconf.noarch 0:2.61-10.fc9 automake.noarch 0:1.10-7 bluez-libs.i386 0:3.22-1.fc9 cyrus-sasl-devel.i386 0:2.1.22-9.fc9 cyrus-sasl-lib.i386 0:2.1.22-9.fc9 device-mapper.i386 0:1.02.22-1.fc8 e2fsprogs.i386 0:1.40.2-10.fc8 e2fsprogs-devel.i386 0:1.40.2-10.fc8 ethtool.i386 0:5-1.fc7 fedora-usermgmt.noarch 0:0.10-1.fc8 fedora-usermgmt-core.noarch 0:0.10-1.fc8 fedora-usermgmt-default-fedora-setup.noarch 0:0.10-1.fc8 fedora-usermgmt-shadow-utils.noarch 0:0.10-1.fc8 glib2.i386 0:2.14.4-1.fc9 glib2-devel.i386 0:2.14.4-1.fc9 gnutls.i386 0:1.6.3-2.fc8 gnutls-devel.i386 0:1.6.3-2.fc8 hwdata.noarch 0:0.207-2.fc9 imake.i386 0:1.0.2-5.fc8 initscripts.i386 0:8.60-1 iproute.i386 0:2.6.23-1.fc9 iputils.i386 0:20070202-5.fc8 isdn4k-utils.i386 0:3.2-55.fc8 js.i386 0:1.60-4.fc8 keyutils-libs.i386 0:1.2-2.fc6 keyutils-libs-devel.i386 0:1.2-2.fc6 krb5-devel.i386 0:1.6.3-1.fc9 krb5-libs.i386 0:1.6.3-1.fc9 libgcrypt.i386 0:1.2.4-6 libgcrypt-devel.i386 0:1.2.4-6 libgpg-error.i386 0:1.5-6 libgpg-error-devel.i386 0:1.5-6 libidn.i386 0:0.6.14-4 libidn-devel.i386 0:0.6.14-4 libjpeg.i386 0:6b-39.fc8 libogg.i386 2:1.1.3-8.fc9 libpcap.i386 14:0.9.8-1.fc9 libpri.i386 0:1.4.2-1.fc9 libselinux-devel.i386 0:2.0.42-1.fc9 libsepol-devel.i386 0:2.0.14-1.fc9 libsysfs.i386 0:2.1.0-1.fc7 libtiff.i386 0:3.8.2-9.fc8 libtiff-devel.i386 0:3.8.2-9.fc8 libvolume_id.i386 0:116-3.fc8 loudmouth.i386 0:1.2.3-6.fc9 m4.i386 0:1.4.10-1.fc8 mISDN.i386 0:1.1.5-1.fc8 mingetty.i386 0:1.07-6 module-init-tools.i386 0:3.4-2.fc8 mysql.i386 0:5.0.45-4.fc8 mysql-libs.i386 0:5.0.45-4.fc8 ncurses-devel.i386 0:5.6-12.20070812.fc8 net-tools.i386 0:1.60-84.fc8 openldap.i386 0:2.4.6-0.fc9.preview openssl.i386 0:0.9.8b-17.fc8 openssl-devel.i386 0:0.9.8b-17.fc8 perl-DBI.i386 0:1.601-1.fc9 pkgconfig.i386 1:0.22-4.fc9 postgresql.i386 0:8.2.5-1.fc8 postgresql-libs.i386 0:8.2.5-1.fc8 procps.i386 0:3.2.7-17.fc8 psmisc.i386 0:22.5-2.fc8 rsyslog.i386 0:1.19.10-1.fc9 spandsp.i386 0:0.0.4-0.7.pre15.fc9 speex.i386 0:1.2-0.3.beta2 sysvinit.i386 0:2.86-18 udev.i386 0:116-3.fc8 unixODBC.i386 0:2.2.12-4.fc8 zaptel-lib.i386 0:1.4.6-1.fc9 zlib-devel.i386 0:1.2.3-16.fc9
>
>
>2007-11-26 09:26:13,958 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'postyum'), {})
>2007-11-26 09:26:13,958 - trace_decorator.py:20:DEBUG: ENTER: _yumCachePostYumHook((<yum_cache.YumCache object at 0x886e90>,), {})
>2007-11-26 09:26:13,958 - trace_decorator.py:30:DEBUG: LEAVE _yumCachePostYumHook --> None
>
>2007-11-26 09:26:13,958 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:26:13,958 - trace_decorator.py:30:DEBUG: LEAVE _yum --> 
>=============================================================================
> Package                 Arch       Version          Repository        Size 
>=============================================================================
>Installing:
> alsa-lib-devel          i386       1.0.15-1.fc9     fedora            989 k
> bluez-libs-devel        i386       3.22-1.fc9       fedora             65 k
> ccache                  i386       2.4-11.fc8       fedora             52 k
> fedora-usermgmt-devel   noarch     0.10-1.fc8       fedora            6.4 k
> isdn4k-utils-devel      i386       3.2-55.fc8       fedora             41 k
> js-devel                i386       1.60-4.fc8       fedora            543 k
> libcap-devel            i386       1.10-30          fedora             20 k
> libjpeg-devel           i386       6b-39.fc8        fedora            104 k
> libpri-devel            i386       1.4.2-1.fc9      fedora             11 k
> libtool                 i386       1.5.24-3.fc8     fedora            684 k
> loudmouth-devel         i386       1.2.3-6.fc9      fedora             36 k
> mISDN-devel             i386       1.1.5-1.fc8      fedora             27 k
> mysql-devel             i386       5.0.45-4.fc8     fedora            2.4 M
> nspr-devel              i386       4.6.99.2-1.fc9   fedora            110 k
> openldap-devel          i386       2.4.6-0.fc9.preview  ldap              1.5 M
> postgresql-devel        i386       8.2.5-1.fc8      fedora            1.2 M
> readline-devel          i386       5.2-8.fc9        fedora            132 k
> spandsp-devel           i386       0.0.4-0.7.pre15.fc9  fedora            114 k
> speex-devel             i386       1.2-0.3.beta2    fedora            112 k
> sqlite-devel            i386       3.4.2-3.fc8      fedora            307 k
> unixODBC-devel          i386       2.2.12-4.fc8     fedora            809 k
> zaptel-devel            i386       1.4.6-1.fc9      fedora             29 k
>Installing for dependencies:
> MAKEDEV                 i386       3.23-1.2         fedora            135 k
> alsa-lib                i386       1.0.15-1.fc9     fedora            408 k
> autoconf                noarch     2.61-10.fc9      fedora            799 k
> automake                noarch     1.10-7           fedora            514 k
> bluez-libs              i386       3.22-1.fc9       fedora             57 k
> cyrus-sasl-devel        i386       2.1.22-9.fc9     fedora            351 k
> cyrus-sasl-lib          i386       2.1.22-9.fc9     fedora            1.1 M
> device-mapper           i386       1.02.22-1.fc8    fedora            466 k
> e2fsprogs               i386       1.40.2-10.fc8    fedora            1.0 M
> e2fsprogs-devel         i386       1.40.2-10.fc8    fedora            635 k
> ethtool                 i386       5-1.fc7          fedora             60 k
> fedora-usermgmt         noarch     0.10-1.fc8       fedora            7.0 k
> fedora-usermgmt-core    noarch     0.10-1.fc8       fedora            8.2 k
> fedora-usermgmt-default-fedora-setup  noarch     0.10-1.fc8       fedora            6.9 k
> fedora-usermgmt-shadow-utils  noarch     0.10-1.fc8       fedora            7.8 k
> glib2                   i386       2.14.4-1.fc9     fedora            824 k
> glib2-devel             i386       2.14.4-1.fc9     fedora            922 k
> gnutls                  i386       1.6.3-2.fc8      fedora            396 k
> gnutls-devel            i386       1.6.3-2.fc8      fedora            948 k
> hwdata                  noarch     0.207-2.fc9      fedora            261 k
> imake                   i386       1.0.2-5.fc8      fedora            312 k
> initscripts             i386       8.60-1           fedora            1.6 M
> iproute                 i386       2.6.23-1.fc9     fedora            826 k
> iputils                 i386       20070202-5.fc8   fedora            126 k
> isdn4k-utils            i386       3.2-55.fc8       fedora            3.8 M
> js                      i386       1.60-4.fc8       fedora            401 k
> keyutils-libs           i386       1.2-2.fc6        fedora             18 k
> keyutils-libs-devel     i386       1.2-2.fc6        fedora             28 k
> krb5-devel              i386       1.6.3-1.fc9      fedora            1.1 M
> krb5-libs               i386       1.6.3-1.fc9      fedora            715 k
> libgcrypt               i386       1.2.4-6          fedora            176 k
> libgcrypt-devel         i386       1.2.4-6          fedora             74 k
> libgpg-error            i386       1.5-6            fedora             62 k
> libgpg-error-devel      i386       1.5-6            fedora             12 k
> libidn                  i386       0.6.14-4         fedora            210 k
> libidn-devel            i386       0.6.14-4         fedora            195 k
> libjpeg                 i386       6b-39.fc8        fedora            139 k
> libogg                  i386       2:1.1.3-8.fc9    fedora             19 k
> libpcap                 i386       14:0.9.8-1.fc9   fedora            102 k
> libpri                  i386       1.4.2-1.fc9      fedora             67 k
> libselinux-devel        i386       2.0.42-1.fc9     fedora            155 k
> libsepol-devel          i386       2.0.14-1.fc9     fedora            181 k
> libsysfs                i386       2.1.0-1.fc7      fedora             48 k
> libtiff                 i386       3.8.2-9.fc8      fedora            308 k
> libtiff-devel           i386       3.8.2-9.fc8      fedora            465 k
> libvolume_id            i386       116-3.fc8        fedora             53 k
> loudmouth               i386       1.2.3-6.fc9      fedora             60 k
> m4                      i386       1.4.10-1.fc8     fedora            209 k
> mISDN                   i386       1.1.5-1.fc8      fedora             93 k
> mingetty                i386       1.07-6           fedora             19 k
> module-init-tools       i386       3.4-2.fc8        fedora            414 k
> mysql                   i386       5.0.45-4.fc8     fedora            2.7 M
> mysql-libs              i386       5.0.45-4.fc8     fedora            1.5 M
> ncurses-devel           i386       5.6-12.20070812.fc8  fedora            653 k
> net-tools               i386       1.60-84.fc8      fedora            361 k
> openldap                i386       2.4.6-0.fc9.preview  ldap              302 k
> openssl                 i386       0.9.8b-17.fc8    fedora            1.4 M
> openssl-devel           i386       0.9.8b-17.fc8    fedora            1.8 M
> perl-DBI                i386       1.601-1.fc9      fedora            729 k
> pkgconfig               i386       1:0.22-4.fc9     fedora             66 k
> postgresql              i386       8.2.5-1.fc8      fedora            3.0 M
> postgresql-libs         i386       8.2.5-1.fc8      fedora            197 k
> procps                  i386       3.2.7-17.fc8     fedora            210 k
> psmisc                  i386       22.5-2.fc8       fedora             71 k
> rsyslog                 i386       1.19.10-1.fc9    fedora            201 k
> spandsp                 i386       0.0.4-0.7.pre15.fc9  fedora            282 k
> speex                   i386       1.2-0.3.beta2    fedora            519 k
> sysvinit                i386       2.86-18          fedora            110 k
> udev                    i386       116-3.fc8        fedora            327 k
> unixODBC                i386       2.2.12-4.fc8     fedora            870 k
> zaptel-lib              i386       1.4.6-1.fc9      fedora             14 k
> zlib-devel              i386       1.2.3-16.fc9     fedora             42 k
>
>Transaction Summary
>=============================================================================
>Install     94 Package(s)         
>Update       0 Package(s)         
>Remove       0 Package(s)         
>
>Total download size: 45 M
>http://ftp.iasi.roedu.net/mirrors/fedora.redhat.com/linux/development/i386/os/Packages/glib2-devel-2.14.4-1.fc9.i386.rpm: [Errno 14] HTTP Error 404: Not Found
>Trying other mirror.
>
>Installed: alsa-lib-devel.i386 0:1.0.15-1.fc9 bluez-libs-devel.i386 0:3.22-1.fc9 ccache.i386 0:2.4-11.fc8 fedora-usermgmt-devel.noarch 0:0.10-1.fc8 isdn4k-utils-devel.i386 0:3.2-55.fc8 js-devel.i386 0:1.60-4.fc8 libcap-devel.i386 0:1.10-30 libjpeg-devel.i386 0:6b-39.fc8 libpri-devel.i386 0:1.4.2-1.fc9 libtool.i386 0:1.5.24-3.fc8 loudmouth-devel.i386 0:1.2.3-6.fc9 mISDN-devel.i386 0:1.1.5-1.fc8 mysql-devel.i386 0:5.0.45-4.fc8 nspr-devel.i386 0:4.6.99.2-1.fc9 openldap-devel.i386 0:2.4.6-0.fc9.preview postgresql-devel.i386 0:8.2.5-1.fc8 readline-devel.i386 0:5.2-8.fc9 spandsp-devel.i386 0:0.0.4-0.7.pre15.fc9 speex-devel.i386 0:1.2-0.3.beta2 sqlite-devel.i386 0:3.4.2-3.fc8 unixODBC-devel.i386 0:2.2.12-4.fc8 zaptel-devel.i386 0:1.4.6-1.fc9
>Dependency Installed: MAKEDEV.i386 0:3.23-1.2 alsa-lib.i386 0:1.0.15-1.fc9 autoconf.noarch 0:2.61-10.fc9 automake.noarch 0:1.10-7 bluez-libs.i386 0:3.22-1.fc9 cyrus-sasl-devel.i386 0:2.1.22-9.fc9 cyrus-sasl-lib.i386 0:2.1.22-9.fc9 device-mapper.i386 0:1.02.22-1.fc8 e2fsprogs.i386 0:1.40.2-10.fc8 e2fsprogs-devel.i386 0:1.40.2-10.fc8 ethtool.i386 0:5-1.fc7 fedora-usermgmt.noarch 0:0.10-1.fc8 fedora-usermgmt-core.noarch 0:0.10-1.fc8 fedora-usermgmt-default-fedora-setup.noarch 0:0.10-1.fc8 fedora-usermgmt-shadow-utils.noarch 0:0.10-1.fc8 glib2.i386 0:2.14.4-1.fc9 glib2-devel.i386 0:2.14.4-1.fc9 gnutls.i386 0:1.6.3-2.fc8 gnutls-devel.i386 0:1.6.3-2.fc8 hwdata.noarch 0:0.207-2.fc9 imake.i386 0:1.0.2-5.fc8 initscripts.i386 0:8.60-1 iproute.i386 0:2.6.23-1.fc9 iputils.i386 0:20070202-5.fc8 isdn4k-utils.i386 0:3.2-55.fc8 js.i386 0:1.60-4.fc8 keyutils-libs.i386 0:1.2-2.fc6 keyutils-libs-devel.i386 0:1.2-2.fc6 krb5-devel.i386 0:1.6.3-1.fc9 krb5-libs.i386 0:1.6.3-1.fc9 libgcrypt.i386 0:1.2.4-6 libgcrypt-devel.i386 0:1.2.4-6 libgpg-error.i386 0:1.5-6 libgpg-error-devel.i386 0:1.5-6 libidn.i386 0:0.6.14-4 libidn-devel.i386 0:0.6.14-4 libjpeg.i386 0:6b-39.fc8 libogg.i386 2:1.1.3-8.fc9 libpcap.i386 14:0.9.8-1.fc9 libpri.i386 0:1.4.2-1.fc9 libselinux-devel.i386 0:2.0.42-1.fc9 libsepol-devel.i386 0:2.0.14-1.fc9 libsysfs.i386 0:2.1.0-1.fc7 libtiff.i386 0:3.8.2-9.fc8 libtiff-devel.i386 0:3.8.2-9.fc8 libvolume_id.i386 0:116-3.fc8 loudmouth.i386 0:1.2.3-6.fc9 m4.i386 0:1.4.10-1.fc8 mISDN.i386 0:1.1.5-1.fc8 mingetty.i386 0:1.07-6 module-init-tools.i386 0:3.4-2.fc8 mysql.i386 0:5.0.45-4.fc8 mysql-libs.i386 0:5.0.45-4.fc8 ncurses-devel.i386 0:5.6-12.20070812.fc8 net-tools.i386 0:1.60-84.fc8 openldap.i386 0:2.4.6-0.fc9.preview openssl.i386 0:0.9.8b-17.fc8 openssl-devel.i386 0:0.9.8b-17.fc8 perl-DBI.i386 0:1.601-1.fc9 pkgconfig.i386 1:0.22-4.fc9 postgresql.i386 0:8.2.5-1.fc8 postgresql-libs.i386 0:8.2.5-1.fc8 procps.i386 0:3.2.7-17.fc8 psmisc.i386 0:22.5-2.fc8 rsyslog.i386 0:1.19.10-1.fc9 spandsp.i386 0:0.0.4-0.7.pre15.fc9 speex.i386 0:1.2-0.3.beta2 sysvinit.i386 0:2.86-18 udev.i386 0:116-3.fc8 unixODBC.i386 0:2.2.12-4.fc8 zaptel-lib.i386 0:1.4.6-1.fc9 zlib-devel.i386 0:1.2.3-16.fc9
>
>
>2007-11-26 09:26:13,959 - trace_decorator.py:30:DEBUG: LEAVE installSrpmDeps --> None
>
>2007-11-26 09:26:13,959 - trace_decorator.py:20:DEBUG: ENTER: state((<mock.backend.Root object at 0x882890>, 'build'), {})
>2007-11-26 09:26:13,959 - trace_decorator.py:30:DEBUG: LEAVE state --> build
>
>2007-11-26 09:26:13,959 - trace_decorator.py:20:DEBUG: ENTER: _callHooks((<mock.backend.Root object at 0x882890>, 'prebuild'), {})
>2007-11-26 09:26:13,959 - trace_decorator.py:20:DEBUG: ENTER: _ccacheBuildHook((<ccache.CCache object at 0x886c90>,), {})
>2007-11-26 09:26:13,960 - trace_decorator.py:20:DEBUG: ENTER: doChroot((<mock.backend.Root object at 0x882890>, 'ccache -M 4G', ''), {})
>2007-11-26 09:26:13,960 - trace_decorator.py:20:DEBUG: ENTER: do(('ccache -M 4G', '/var/lib/mock/ldap-i386/root', 0, True, 0, None, None, None), {})
>2007-11-26 09:26:13,960 - util.py:196:DEBUG: Run cmd: ccache -M 4G
>2007-11-26 09:26:13,960 - util.py:202:DEBUG: Executing timeout(0): ccache -M 4G
>2007-11-26 09:26:13,961 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', None), {})
>2007-11-26 09:26:13,962 - trace_decorator.py:27:DEBUG: EXCEPTION: [Errno 1] Operation not permitted: '/var/lib/mock/ldap-i386/root'
>Traceback (most recent call last):
>  File "/usr/lib/python2.5/site-packages/mock/trace_decorator.py", line 24, in trace
>    result = f(*args, **kw)
>  File "/usr/lib/python2.5/site-packages/mock/util.py", line 165, in condChroot
>    os.chroot(chrootPath)
>OSError: [Errno 1] Operation not permitted: '/var/lib/mock/ldap-i386/root'
>2007-11-26 09:26:13,963 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> EXCEPTION RAISED
>
>2007-11-26 09:26:13,964 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:26:13,964 - trace_decorator.py:30:DEBUG: LEAVE doChroot --> 
>
>2007-11-26 09:26:13,964 - trace_decorator.py:30:DEBUG: LEAVE _ccacheBuildHook --> None
>
>2007-11-26 09:26:13,964 - trace_decorator.py:30:DEBUG: LEAVE _callHooks --> None
>
>2007-11-26 09:26:13,966 - trace_decorator.py:20:DEBUG: ENTER: condChroot(('/var/lib/mock/ldap-i386/root', <mock.uid.uidManager object at 0x882910>), {})
>2007-11-26 09:26:13,966 - util.py:162:DEBUG: elevate privs to run chroot
>2007-11-26 09:26:13,967 - trace_decorator.py:20:DEBUG: ENTER: becomeUser((<mock.uid.uidManager object at 0x882910>, 0, -1), {})
>2007-11-26 09:26:13,967 - trace_decorator.py:20:DEBUG: ENTER: _push((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:26:13,967 - trace_decorator.py:30:DEBUG: LEAVE _push --> None
>
>2007-11-26 09:26:13,967 - trace_decorator.py:20:DEBUG: ENTER: _becomeUser((<mock.uid.uidManager object at 0x882910>, 0, -1), {})
>2007-11-26 09:26:13,967 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:26:13,967 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:26:13,967 - trace_decorator.py:30:DEBUG: LEAVE _becomeUser --> None
>
>2007-11-26 09:26:13,968 - trace_decorator.py:30:DEBUG: LEAVE becomeUser --> None
>
>2007-11-26 09:26:13,968 - util.py:167:DEBUG: back to other privs
>2007-11-26 03:26:13,968 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:26:13,968 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:26:13,968 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 03:26:13,968 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 03:26:13,968 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 03:26:13,969 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((<mock.uid.uidManager object at 0x882910>, 500, 494), {})
>2007-11-26 03:26:13,969 - util.py:173:DEBUG: about to drop privs
>2007-11-26 03:26:13,969 - trace_decorator.py:20:DEBUG: ENTER: dropPrivsForever((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:26:13,969 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 03:26:13,969 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 03:26:13,969 - trace_decorator.py:30:DEBUG: LEAVE dropPrivsForever --> None
>
>2007-11-26 03:26:13,969 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:27:37,629 - trace_decorator.py:20:DEBUG: ENTER: restorePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:27:37,629 - trace_decorator.py:20:DEBUG: ENTER: _elevatePrivs((<mock.uid.uidManager object at 0x882910>,), {})
>2007-11-26 09:27:37,629 - trace_decorator.py:30:DEBUG: LEAVE _elevatePrivs --> None
>
>2007-11-26 09:27:37,629 - trace_decorator.py:30:DEBUG: LEAVE restorePrivs --> None
>
>2007-11-26 09:27:37,629 - trace_decorator.py:20:DEBUG: ENTER: _umountall((<mock.backend.Root object at 0x882890>,), {})
>2007-11-26 09:27:37,630 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:27:37,630 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/proc', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:27:37,630 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:27:37,630 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/proc
>2007-11-26 09:27:37,631 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:27:37,632 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:27:37,632 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:27:37,632 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:27:37,642 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:27:37,642 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:27:37,642 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/dev/pts', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:27:37,642 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:27:37,643 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/dev/pts
>2007-11-26 09:27:37,644 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:27:37,644 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:27:37,645 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:27:37,645 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:27:37,653 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:27:37,654 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:27:37,654 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/sys', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:27:37,654 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:27:37,654 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/sys
>2007-11-26 09:27:37,655 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:27:37,656 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:27:37,656 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:27:37,656 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:27:37,665 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:27:37,666 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:27:37,666 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/tmp/ccache', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:27:37,666 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:27:37,666 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/tmp/ccache
>2007-11-26 09:27:37,667 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:27:37,668 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:27:37,668 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:27:37,668 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:27:37,676 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:27:37,677 - backend.py:431:INFO: umount -n /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:27:37,677 - trace_decorator.py:20:DEBUG: ENTER: do(('umount -n /var/lib/mock/ldap-i386/root/var/cache/yum', None, 0, 0, 0, None, None, None), {})
>2007-11-26 09:27:37,677 - util.py:196:DEBUG: Run cmd: umount -n /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:27:37,677 - util.py:202:DEBUG: Executing timeout(0): umount -n /var/lib/mock/ldap-i386/root/var/cache/yum
>2007-11-26 09:27:37,678 - trace_decorator.py:20:DEBUG: ENTER: condChroot((None, None), {})
>2007-11-26 09:27:37,679 - trace_decorator.py:30:DEBUG: LEAVE condChroot --> None
>
>2007-11-26 09:27:37,679 - trace_decorator.py:20:DEBUG: ENTER: condDropPrivs((None, None, None), {})
>2007-11-26 09:27:37,679 - trace_decorator.py:30:DEBUG: LEAVE condDropPrivs --> None
>
>2007-11-26 09:27:37,687 - trace_decorator.py:30:DEBUG: LEAVE do --> 
>
>2007-11-26 09:27:37,687 - trace_decorator.py:30:DEBUG: LEAVE _umountall --> None
>
>2007-11-26 09:27:37,688 - trace_decorator.py:27:DEBUG: EXCEPTION: Command(rpmbuild -bb --target i386 --nodeps //builddir/build/SPECS/callweaver.spec) failed. See logs for output.
>Traceback (most recent call last):
>  File "/usr/lib/python2.5/site-packages/mock/trace_decorator.py", line 24, in trace
>    result = f(*args, **kw)
>  File "/usr/lib/python2.5/site-packages/mock/backend.py", line 375, in build
>    logger=self.build_log, timeout=timeout,
>  File "<string>", line 1, in <lambda>
>  File "/usr/lib/python2.5/site-packages/mock/trace_decorator.py", line 24, in trace
>    result = f(*args, **kw)
>  File "/usr/lib/python2.5/site-packages/mock/util.py", line 244, in do
>    raise mock.exception.Error, "Command(%s) failed. See logs for output." % command
>Error: Command(rpmbuild -bb --target i386 --nodeps //builddir/build/SPECS/callweaver.spec) failed. See logs for output.
>2007-11-26 09:27:37,688 - trace_decorator.py:30:DEBUG: LEAVE build --> EXCEPTION RAISED
>
>2007-11-26 09:27:37,689 - trace_decorator.py:20:DEBUG: ENTER: orphansKill(('/var/lib/mock/ldap-i386/root',), {})
>2007-11-26 09:27:37,699 - trace_decorator.py:30:DEBUG: LEAVE orphansKill --> None
>

Actions: View

Attachments on bug 399231: 268641 | 268651