|
From: Nicolas H. <ni...@hu...> - 2006-12-04 15:00:56
|
Hi,
I experience a weird and not-that-much reproducible problem with
apt-proxy on Debian etch.
Here is the log on the proxy host ("/etc/apt-proxy/apt-proxy-v2.conf" :
"debug = all:9") :
============================================================================
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [CacheEntry] start
download:dists/etch/updates/main/binary-i386/Packages.gz
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [DownloadQueue] start
next download (security/dists/etch/updates/main/binary-i386/Packages.gz)
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [DownloadQueue] keeping
backend server
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [fetcher] fetcher
start:Packages.gz
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [Fetcher] Downloading:
/var/cache/apt-proxy/security/dists/etch/updates/main/binary-i386/Packages.gz
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [fetcher] Connecting to
backend server (security) http://security.debian.org:80
2006/12/04 15:50 CET [Channel,0,192.168.0.173] Starting factory
<apt_proxy.fetchers.HttpFetcher instance at 0xa73284cc>
2006/12/04 15:50 CET [Channel,0,192.168.0.173] Starting factory
<twisted.protocols.policies.ThrottlingFactory instance at 0xa7328d2c>
2006/12/04 15:50 CET [Uninitialized] [Fetcher] Connected to
http://security.debian.org/debian-security
2006/12/04 15:50 CET [Uninitialized] [Fetcher]
downloading:dists/etch/updates/main/binary-i386/Packages.gz mtime:1161956310
2006/12/04 15:50 CET [Uninitialized] [http_client]
GET:/debian-security/dists/etch/updates/main/binary-i386/Packages.gz
2006/12/04 15:50 CET [Uninitialized] [http_client] host
sendHeader:security.debian.org
2006/12/04 15:50 CET [Uninitialized] [http_client] if-modified-since
sendHeader:Fri, 27 Oct 2006 13:38:30 GMT
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [CacheEntry] transfer
complete: /var/cache/apt-proxy/debian/dists/etch/main/source/Sources.gz
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [Request] finish. Queued: 0
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [CacheEntry] transfer
complete: /var/cache/apt-proxy/debian/dists/etch/main/source/Sources.gz
2006/12/04 15:50 CET [Channel,0,192.168.0.173] [Request] finish. Queued: 0
2006/12/04 15:50 CET [Channel,0,192.168.0.173] Unhandled error in Deferred:
2006/12/04 15:50 CET [Channel,0,192.168.0.173] Traceback (most recent
call last):
File
"/usr/lib/python2.4/site-packages/twisted/internet/abstract.py", line
127, in doWrite
self.producer.resumeProducing()
File
"/usr/lib/python2.4/site-packages/twisted/protocols/basic.py", line 442,
in resumeProducing
self.deferred.callback(self.lastSent)
File
"/usr/lib/python2.4/site-packages/twisted/internet/defer.py", line 229,
in callback
self._startRunCallbacks(result)
File
"/usr/lib/python2.4/site-packages/twisted/internet/defer.py", line 294,
in _startRunCallbacks
self._runCallbacks()
--- <exception caught here> ---
File
"/usr/lib/python2.4/site-packages/twisted/internet/defer.py", line 307,
in _runCallbacks
self.result = callback(self.result, *args, **kw)
File "/usr/lib/python2.4/site-packages/apt_proxy/cache.py",
line 267, in file_transfer_complete
request.finish()
File
"/usr/lib/python2.4/site-packages/apt_proxy/apt_proxy.py", line 324, in
finish
http.Request.finish(self)
File "/usr/lib/python2.4/site-packages/twisted/web/http.py",
line 674, in finish
self._cleanup()
File "/usr/lib/python2.4/site-packages/twisted/web/http.py",
line 483, in _cleanup
self.channel.requestDone(self)
File "/usr/lib/python2.4/site-packages/twisted/web/http.py",
line 1101, in requestDone
self.requests[0].noLongerQueued()
File "/usr/lib/python2.4/site-packages/twisted/web/http.py",
line 518, in noLongerQueued
self._cleanup()
File "/usr/lib/python2.4/site-packages/twisted/web/http.py",
line 483, in _cleanup
self.channel.requestDone(self)
exceptions.AttributeError: Request instance has no attribute
'channel'
============================================================================
Here is the apt-get update output on the client host :
============================================================================
Get:3 http://debian etch/main Sources [1667kB]
Get:4 http://debian etch/main Sources [1667kB]
51% [3 Sources gzip 4612096] [4 Sources 160/1667kB 0%]
gzip: stdin: unexpected end of file
Err http://debian etch/main Sources
Sub-process gzip returned an error code (1)
Err http://debian etch/main Sources
Connection timed out
Hit http://debian unstable/main Packages
Hit http://debian unstable/main Sources
Hit http://debian etch/updates/main Packages
Hit http://debian etch/updates/main Sources
Fetched 1668kB in 2m9s (12.9kB/s)
Failed to fetch
http://debian:9999/debian/dists/etch/main/source/Sources.gz Sub-process
gzip returned an error code (1)
Failed to fetch
http://debian:9999/debian/dists/etch/main/source/Sources.gz Connection
timed out
Reading package lists... Done
E: Some index files failed to download, they have been ignored, or old
ones used instead.
============================================================================
The "Unhandled error" occurs at the "gzip: stdin: unexpected end of
file" step. There is a second error on the client-side, with no obvious
exception on the server-side.
/etc/apt/sources.list :
deb http://debian:9999/debian etch main
deb-src http://debian:9999/debian etch main
deb http://debian:9999/security etch/updates main
deb-src http://debian:9999/security etch/updates main
deb http://debian:9999/doloforge unstable main
deb-src http://debian:9999/doloforge unstable main
/etc/apt-proxy/apt-proxy-v2.conf :
[debian]
backends =
http://ftp.fr.debian.org/debian
http://ftp2.fr.debian.org/debian
http://ftp.de.debian.org/debian
http://ftp2.de.debian.org/debian
[security]
backends =
http://security.debian.org/debian-security
http://ftp2.de.debian.org/debian-security
[doloforge]
backends = http://debian/debarchiver
Every package is up-to-date as of today : in sync with etch.
--
NH
|